TRACE: 1 01-35-49->0s=0:00:00.000281s status=OK step=show plc=plctest1_vplc14 force=False TRACE: 1 01-35-49->1s=0:00:01.997591s status=OK step=plcvm_delete plc=plctest1_vplc14 force=False TRACE: 1 01-35-51->0s=0:00:00.936208s status=OK step=plcvm_timestamp plc=plctest1_vplc14 force=False TRACE: 1 01-35-52->30s=0:00:30.833833s status=OK step=plcvm_create plc=plctest1_vplc14 force=False TRACE: 1 01-36-23->31s=0:00:31.131678s status=OK step=plc_install plc=plctest1_vplc14 force=False TRACE: 1 01-36-54->0s=0:00:00.731121s status=OK step=plc_configure plc=plctest1_vplc14 force=False TRACE: 1 01-36-55->31s=0:00:31.669261s status=OK step=plc_start plc=plctest1_vplc14 force=False TRACE: 1 01-37-26->0s=0:00:00.735925s status=OK step=keys_fetch plc=plctest1_vplc14 force=False TRACE: 1 01-37-27->0s=0:00:00.001732s status=OK step=keys_store plc=plctest1_vplc14 force=False TRACE: 1 01-37-27->0s=0:00:00.009944s status=OK step=keys_clear_known_hosts plc=plctest1_vplc14 force=False TRACE: 1 01-37-27->0s=0:00:00.684170s status=OK step=plcapi_urls plc=plctest1_vplc14 force=False TRACE: 1 01-37-28->0s=0:00:00.444392s status=OK step=speed_up_slices plc=plctest1_vplc14 force=False TRACE: 1 01-37-28->0s=0:00:00.159951s status=OK step=initscripts plc=plctest1_vplc14 force=False TRACE: 1 01-37-29->1s=0:00:01.289075s status=OK step=sites plc=plctest1_vplc14 force=False TRACE: 1 01-37-30->0s=0:00:00.186775s status=OK step=nodes plc=plctest1_vplc14 force=False TRACE: 1 01-37-30->0s=0:00:00.836044s status=OK step=slices plc=plctest1_vplc14 force=False TRACE: 1 01-37-31->0s=0:00:00.163450s status=OK step=nodegroups plc=plctest1_vplc14 force=False TRACE: 1 01-37-31->0s=0:00:00.024076s status=OK step=leases plc=plctest1_vplc14 force=False TRACE: 1 01-37-31->0s=0:00:00.102972s status=OK[I] step=check_vsys_defaults_ignore plc=plctest1_vplc14 force=False TRACE: 1 01-37-31->0s=0:00:00.888944s status=OK step=qemu_kill_mine plc=plctest1_vplc14 force=False TRACE: 1 01-37-32->0s=0:00:00.044768s status=OK step=nodestate_reinstall plc=plctest1_vplc14 force=False TRACE: 1 01-37-32->0s=0:00:00.020475s status=OK step=qemu_local_init plc=plctest1_vplc14 force=False TRACE: 1 01-37-32->3s=0:00:03.542046s status=OK step=bootcd plc=plctest1_vplc14 force=False TRACE: 1 01-37-36->0s=0:00:00.028112s status=OK step=qemu_local_config plc=plctest1_vplc14 force=False TRACE: 1 01-37-36->0s=0:00:00.269485s status=OK step=qemu_clean_mine plc=plctest1_vplc14 force=False TRACE: 1 01-37-36->1s=0:00:01.640600s status=OK step=qemu_export plc=plctest1_vplc14 force=False TRACE: 1 01-37-38->0s=0:00:00.738608s status=OK step=qemu_cleanlog plc=plctest1_vplc14 force=False TRACE: 1 01-37-38->1s=0:00:01.159130s status=OK step=qemu_start plc=plctest1_vplc14 force=False TRACE: 1 01-37-39->0s=0:00:00.958236s status=OK step=qemu_timestamp plc=plctest1_vplc14 force=False TRACE: 1 01-37-40->0s=0:00:00.703992s status=OK step=qemu_nodefamily plc=plctest1_vplc14 force=False TRACE: 1 01-37-41->6s=0:00:06.322696s status=OK step=sfa_install_all plc=plctest1_vplc14 force=False TRACE: 1 01-37-47->1s=0:00:01.231159s status=OK step=sfa_configure plc=plctest1_vplc14 force=False TRACE: 1 01-37-49->0s=0:00:00.000328s status=OK step=cross_sfa_configure plc=plctest1_vplc14 force=False TRACE: 1 01-37-49->5s=0:00:05.099133s status=OK step=sfa_start plc=plctest1_vplc14 force=False TRACE: 1 01-37-54->3s=0:00:03.075407s status=OK step=sfa_import plc=plctest1_vplc14 force=False TRACE: 1 01-37-57->1s=0:00:01.048925s status=OK step=sfi_configure plc=plctest1_vplc14 force=False TRACE: 1 01-37-58->1s=0:00:01.121054s status=OK step=sfa_register_site plc=plctest1_vplc14 force=False TRACE: 1 01-37-59->2s=0:00:02.793071s status=OK step=sfa_register_pi plc=plctest1_vplc14 force=False TRACE: 1 01-38-02->1s=0:00:01.220828s status=KO step=sfa_register_user plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000099s status=UNDEF step=sfa_update_user plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000053s status=UNDEF step=sfa_register_slice plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000046s status=UNDEF step=sfa_renew_slice plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000056s status=UNDEF step=sfa_remove_user_from_slice plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000045s status=UNDEF step=sfi_show_slice_researchers plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000044s status=UNDEF step=sfa_insert_user_in_slice plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000044s status=UNDEF step=sfi_show_slice_researchers plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000047s status=UNDEF step=sfa_discover plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000044s status=UNDEF step=sfa_rspec plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000043s status=UNDEF step=sfa_allocate plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=sfa_provision plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=sfa_describe plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=sfa_check_slice_plc plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=sfa_update_slice plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=sfi_list plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000043s status=UNDEF step=sfi_show_site plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000060s status=UNDEF step=sfa_utest plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000044s status=UNDEF step=probe_kvm_iptables plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000052s status=UNDEF step=ping_node plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000043s status=UNDEF step=ssh_node_debug plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000043s status=UNDEF step=plcsh_stress_test plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=ssh_node_boot plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=node_bmlogs plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000043s status=UNDEF step=ssh_slice plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=ssh_slice_basics plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000044s status=UNDEF step=check_initscripts plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000046s status=UNDEF step=ssh_slice_sfa plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000043s status=UNDEF step=sfa_rspec_empty plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000043s status=UNDEF step=sfa_allocate_empty plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=sfa_provision_empty plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=sfa_check_slice_plc_empty plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=sfa_delete_slice plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=sfa_delete_user plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=cross_check_tcp plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=check_system_slice plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000056s status=UNDEF step=debug_nodemanager plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000045s status=UNDEF step=empty_slices plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=ssh_slice_off plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000046s status=UNDEF step=slice_fs_deleted_ignore plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000053s status=UNDEF step=fill_slices plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->0s=0:00:00.000042s status=UNDEF step=ssh_slice_again plc=plctest1_vplc14 force=False TRACE: 1 01-38-03->3s=0:00:03.044125s status=OK step=gather_logs plc=plctest1_vplc14 force=True