TRACE: 1 11-18-18->0s=0:00:00.000258s status=OK step=show plc=plctest1_vplc01 force=False
TRACE: 1 11-18-18->1s=0:00:01.443581s status=OK step=plcvm_delete plc=plctest1_vplc01 force=False
TRACE: 1 11-18-20->0s=0:00:00.535382s status=OK step=plcvm_timestamp plc=plctest1_vplc01 force=False
TRACE: 1 11-18-20->15s=0:00:15.798331s status=KO step=plcvm_create plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000118s status=UNDEF step=plc_install plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000062s status=UNDEF step=plc_configure plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000070s status=UNDEF step=plc_start plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000055s status=UNDEF step=keys_fetch plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000053s status=UNDEF step=keys_store plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000056s status=UNDEF step=keys_clear_known_hosts plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000053s status=UNDEF step=plcapi_urls plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=speed_up_slices plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000053s status=UNDEF step=initscripts plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000055s status=UNDEF step=sites plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000076s status=UNDEF step=nodes plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000055s status=UNDEF step=slices plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=nodegroups plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=leases plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000053s status=UNDEF step=check_vsys_defaults_ignore plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=qemu_kill_mine plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=nodestate_reinstall plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=qemu_local_init plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000054s status=UNDEF step=bootcd plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=qemu_local_config plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=qemu_clean_mine plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=qemu_export plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=qemu_cleanlog plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000050s status=UNDEF step=qemu_start plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=qemu_timestamp plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000050s status=UNDEF step=qemu_nodefamily plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000057s status=UNDEF step=sfa_install_all plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=sfa_configure plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=cross_sfa_configure plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_start plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_import plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000058s status=UNDEF step=sfi_configure plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000053s status=UNDEF step=sfa_register_site plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=sfa_register_pi plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000064s status=UNDEF step=sfa_register_user plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=sfa_update_user plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_register_slice plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000057s status=UNDEF step=sfa_renew_slice plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000053s status=UNDEF step=sfa_remove_user_from_slice plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=sfi_show_slice_researchers plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=sfa_insert_user_in_slice plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfi_show_slice_researchers plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000056s status=UNDEF step=sfa_discover plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_rspec plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_allocate plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=sfa_provision plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_describe plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_check_slice_plc plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_update_slice plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfi_list plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000065s status=UNDEF step=sfi_show_site plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000054s status=UNDEF step=sfa_utest plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=probe_kvm_iptables plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=ping_node plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000050s status=UNDEF step=ssh_node_debug plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=plcsh_stress_test plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=ssh_node_boot plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000050s status=UNDEF step=node_bmlogs plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000064s status=UNDEF step=ssh_slice plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=ssh_slice_basics plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=check_initscripts plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=ssh_slice_sfa plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_rspec_empty plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_allocate_empty plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000050s status=UNDEF step=sfa_provision_empty plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_check_slice_plc_empty plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=sfa_delete_slice plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000054s status=UNDEF step=sfa_delete_user plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=cross_check_tcp plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000050s status=UNDEF step=check_system_slice plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000058s status=UNDEF step=debug_nodemanager plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=empty_slices plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=ssh_slice_off plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000052s status=UNDEF step=slice_fs_deleted_ignore plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000051s status=UNDEF step=fill_slices plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->0s=0:00:00.000055s status=UNDEF step=ssh_slice_again plc=plctest1_vplc01 force=False
TRACE: 1 11-18-36->14s=0:00:14.618719s status=KO step=gather_logs plc=plctest1_vplc01 force=True