2019-05-23 02:05:08,759 [salt.minion      :870 ][ERROR   ][388] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-23 02:06:30,397 [salt.utils.decorators:613 ][WARNING ][2089] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 02:06:31,597 [salt.utils.decorators:613 ][WARNING ][2089] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 02:06:35,384 [salt.loaded.int.states.file:2298][WARNING ][2565] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-23 02:07:04,991 [salt.state       :2022][WARNING ][2982] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-23 02:07:07,232 [salt.utils.decorators:613 ][WARNING ][2982] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 02:07:23,035 [salt.utils.decorators:613 ][WARNING ][2982] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 02:07:48,827 [salt.utils.decorators:613 ][WARNING ][2982] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 02:07:49,939 [salt.utils.decorators:613 ][WARNING ][2982] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 02:07:52,536 [salt.loaded.ext.module.maasng:1008][WARNING ][2982] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-23 02:07:52,536 [salt.loaded.ext.module.maasng:1011][WARNING ][2982] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-23 02:07:53,241 [salt.loaded.ext.module.maasng:1235][WARNING ][2982] Ignoring parameter vlan:0
2019-05-23 02:08:05,055 [salt.utils.decorators:613 ][WARNING ][5472] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 02:08:05,117 [salt.loaded.ext.module.maas:412 ][WARNING ][5472] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 02:08:06,378 [salt.loaded.ext.module.maas:412 ][WARNING ][5472] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 02:08:07,710 [salt.loaded.ext.module.maas:412 ][WARNING ][5472] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 02:08:09,309 [salt.loaded.ext.module.maas:412 ][WARNING ][5472] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 02:08:10,678 [salt.loaded.ext.module.maas:412 ][WARNING ][5472] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 02:08:15,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6083] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-23 02:08:16,026 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6083] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-23 02:08:16,071 [salt.utils.parsers:1051][WARNING ][388] Minion received a SIGTERM. Exiting.
2019-05-23 02:08:17,120 [salt.cli.daemons :293 ][INFO    ][6140] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-05-23 02:08:17,244 [salt.cli.daemons :82  ][INFO    ][6140] Starting up the Salt Minion
2019-05-23 02:08:17,245 [salt.utils.event :1017][INFO    ][6140] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-05-23 02:08:18,463 [salt.minion      :976 ][INFO    ][6140] Creating minion process manager
2019-05-23 02:08:20,553 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6140] Executing command ['date', '+%z'] in directory '/root'
2019-05-23 02:08:20,584 [salt.utils.schedule:568 ][INFO    ][6140] Updating job settings for scheduled job: __mine_interval
2019-05-23 02:08:20,586 [salt.minion      :1108][INFO    ][6140] Added mine.update to scheduler
2019-05-23 02:08:20,591 [salt.minion      :1975][INFO    ][6140] Minion is starting as user 'root'
2019-05-23 02:08:20,613 [salt.minion      :2336][INFO    ][6140] Minion is ready to receive requests!
2019-05-23 02:08:43,266 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command state.apply with jid 20190523020843248384
2019-05-23 02:08:43,292 [salt.minion      :1432][INFO    ][6229] Starting a new job with PID 6229
2019-05-23 02:08:50,528 [salt.state       :915 ][INFO    ][6229] Loading fresh modules for state activity
2019-05-23 02:08:50,585 [salt.fileclient  :1219][INFO    ][6229] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-23 02:08:50,646 [salt.state       :1780][INFO    ][6229] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:50.646328
2019-05-23 02:08:50,646 [salt.state       :1813][INFO    ][6229] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 02:08:50,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6229] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 02:08:52,396 [salt.state       :300 ][INFO    ][6229] {'pid': 6236, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 02:08:52,398 [salt.state       :1951][INFO    ][6229] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:52.398138 duration_in_ms=1751.811
2019-05-23 02:08:52,401 [salt.state       :1780][INFO    ][6229] Running state [maas.wait_for_machine_status] at time 02:08:52.400905
2019-05-23 02:08:52,401 [salt.state       :1813][INFO    ][6229] Executing state module.run for [maas.wait_for_machine_status]
2019-05-23 02:08:52,402 [salt.utils.decorators:613 ][WARNING ][6229] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 02:08:53,223 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.19086194s left)
2019-05-23 02:08:58,389 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523020858372230
2019-05-23 02:08:58,421 [salt.minion      :1432][INFO    ][6260] Starting a new job with PID 6260
2019-05-23 02:08:58,445 [salt.minion      :1711][INFO    ][6260] Returning information for job: 20190523020858372230
2019-05-23 02:09:24,049 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.36521792s left)
2019-05-23 02:09:28,469 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523020928452947
2019-05-23 02:09:28,495 [salt.minion      :1432][INFO    ][6304] Starting a new job with PID 6304
2019-05-23 02:09:28,515 [salt.minion      :1711][INFO    ][6304] Returning information for job: 20190523020928452947
2019-05-23 02:09:54,905 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.50976491s left)
2019-05-23 02:09:58,584 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523020958572819
2019-05-23 02:09:58,612 [salt.minion      :1432][INFO    ][6334] Starting a new job with PID 6334
2019-05-23 02:09:58,632 [salt.minion      :1711][INFO    ][6334] Returning information for job: 20190523020958572819
2019-05-23 02:10:25,884 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.53057384s left)
2019-05-23 02:10:28,651 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021028638578
2019-05-23 02:10:28,675 [salt.minion      :1432][INFO    ][6380] Starting a new job with PID 6380
2019-05-23 02:10:28,696 [salt.minion      :1711][INFO    ][6380] Returning information for job: 20190523021028638578
2019-05-23 02:10:56,969 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.44549894s left)
2019-05-23 02:10:58,739 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021058725422
2019-05-23 02:10:58,764 [salt.minion      :1432][INFO    ][6439] Starting a new job with PID 6439
2019-05-23 02:10:58,786 [salt.minion      :1711][INFO    ][6439] Returning information for job: 20190523021058725422
2019-05-23 02:11:28,160 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.25417304s left)
2019-05-23 02:11:28,834 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021128820130
2019-05-23 02:11:28,861 [salt.minion      :1432][INFO    ][6608] Starting a new job with PID 6608
2019-05-23 02:11:28,881 [salt.minion      :1711][INFO    ][6608] Returning information for job: 20190523021128820130
2019-05-23 02:11:58,952 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021158935922
2019-05-23 02:11:58,980 [salt.minion      :1432][INFO    ][6693] Starting a new job with PID 6693
2019-05-23 02:11:59,005 [salt.minion      :1711][INFO    ][6693] Returning information for job: 20190523021158935922
2019-05-23 02:11:59,471 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.94317198s left)
2019-05-23 02:12:29,087 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021229079768
2019-05-23 02:12:29,105 [salt.minion      :1432][INFO    ][7019] Starting a new job with PID 7019
2019-05-23 02:12:29,125 [salt.minion      :1711][INFO    ][7019] Returning information for job: 20190523021229079768
2019-05-23 02:12:30,749 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.66541004s left)
2019-05-23 02:12:59,181 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021259173258
2019-05-23 02:12:59,207 [salt.minion      :1432][INFO    ][7123] Starting a new job with PID 7123
2019-05-23 02:12:59,226 [salt.minion      :1711][INFO    ][7123] Returning information for job: 20190523021259173258
2019-05-23 02:13:02,495 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.91941786s left)
2019-05-23 02:13:29,348 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021329337555
2019-05-23 02:13:29,376 [salt.minion      :1432][INFO    ][7522] Starting a new job with PID 7522
2019-05-23 02:13:29,398 [salt.minion      :1711][INFO    ][7522] Returning information for job: 20190523021329337555
2019-05-23 02:13:34,664 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.75024486s left)
2019-05-23 02:13:59,455 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021359438952
2019-05-23 02:13:59,484 [salt.minion      :1432][INFO    ][7564] Starting a new job with PID 7564
2019-05-23 02:13:59,508 [salt.minion      :1711][INFO    ][7564] Returning information for job: 20190523021359438952
2019-05-23 02:14:06,755 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.65927696s left)
2019-05-23 02:14:29,585 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021429572652
2019-05-23 02:14:29,614 [salt.minion      :1432][INFO    ][7692] Starting a new job with PID 7692
2019-05-23 02:14:29,636 [salt.minion      :1711][INFO    ][7692] Returning information for job: 20190523021429572652
2019-05-23 02:14:39,335 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1153.07966185s left)
2019-05-23 02:14:59,706 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021459690541
2019-05-23 02:14:59,734 [salt.minion      :1432][INFO    ][7736] Starting a new job with PID 7736
2019-05-23 02:14:59,754 [salt.minion      :1711][INFO    ][7736] Returning information for job: 20190523021459690541
2019-05-23 02:15:11,953 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1120.46124601s left)
2019-05-23 02:15:29,837 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021529824239
2019-05-23 02:15:29,863 [salt.minion      :1432][INFO    ][7783] Starting a new job with PID 7783
2019-05-23 02:15:29,884 [salt.minion      :1711][INFO    ][7783] Returning information for job: 20190523021529824239
2019-05-23 02:15:44,432 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1087.98244691s left)
2019-05-23 02:15:59,960 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021559945772
2019-05-23 02:15:59,986 [salt.minion      :1432][INFO    ][7813] Starting a new job with PID 7813
2019-05-23 02:16:00,009 [salt.minion      :1711][INFO    ][7813] Returning information for job: 20190523021559945772
2019-05-23 02:16:17,111 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1055.3037219s left)
2019-05-23 02:16:30,115 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021630097666
2019-05-23 02:16:30,145 [salt.minion      :1432][INFO    ][7859] Starting a new job with PID 7859
2019-05-23 02:16:30,174 [salt.minion      :1711][INFO    ][7859] Returning information for job: 20190523021630097666
2019-05-23 02:16:49,786 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1022.62864685s left)
2019-05-23 02:17:00,263 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021700247022
2019-05-23 02:17:00,291 [salt.minion      :1432][INFO    ][7895] Starting a new job with PID 7895
2019-05-23 02:17:00,315 [salt.minion      :1711][INFO    ][7895] Returning information for job: 20190523021700247022
2019-05-23 02:17:22,435 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (989.979751825s left)
2019-05-23 02:17:30,420 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021730406171
2019-05-23 02:17:30,448 [salt.minion      :1432][INFO    ][7959] Starting a new job with PID 7959
2019-05-23 02:17:30,468 [salt.minion      :1711][INFO    ][7959] Returning information for job: 20190523021730406171
2019-05-23 02:17:54,978 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (957.435777903s left)
2019-05-23 02:18:00,581 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021800566318
2019-05-23 02:18:00,610 [salt.minion      :1432][INFO    ][7987] Starting a new job with PID 7987
2019-05-23 02:18:00,633 [salt.minion      :1711][INFO    ][7987] Returning information for job: 20190523021800566318
2019-05-23 02:18:27,663 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (924.750844002s left)
2019-05-23 02:18:30,766 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021830750060
2019-05-23 02:18:30,793 [salt.minion      :1432][INFO    ][8030] Starting a new job with PID 8030
2019-05-23 02:18:30,817 [salt.minion      :1711][INFO    ][8030] Returning information for job: 20190523021830750060
2019-05-23 02:19:00,232 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (892.182235956s left)
2019-05-23 02:19:00,931 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021900924475
2019-05-23 02:19:00,954 [salt.minion      :1432][INFO    ][8056] Starting a new job with PID 8056
2019-05-23 02:19:00,981 [salt.minion      :1711][INFO    ][8056] Returning information for job: 20190523021900924475
2019-05-23 02:19:31,108 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523021931095377
2019-05-23 02:19:31,128 [salt.minion      :1432][INFO    ][8103] Starting a new job with PID 8103
2019-05-23 02:19:31,157 [salt.minion      :1711][INFO    ][8103] Returning information for job: 20190523021931095377
2019-05-23 02:19:32,600 [salt.loaded.ext.module.maas:981 ][INFO    ][6229] Machine tch7p8 deleted
2019-05-23 02:19:33,450 [salt.loaded.ext.module.maas:412 ][WARNING ][6229] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 02:19:33,451 [salt.loaded.ext.module.maas:92  ][INFO    ][6229] machine hostname=cmp002 power_type=ipmi mac_addresses=['9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=dkh7w6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-23 02:19:34,623 [salt.loaded.ext.module.maas:412 ][WARNING ][6229] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 02:19:34,624 [salt.loaded.ext.module.maas:92  ][INFO    ][6229] machine hostname=cmp001 power_type=ipmi mac_addresses=['9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=pcxypq architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-23 02:19:35,829 [salt.loaded.ext.module.maas:412 ][WARNING ][6229] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 02:19:35,830 [salt.loaded.ext.module.maas:92  ][INFO    ][6229] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=cs3ays architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-23 02:19:37,044 [salt.loaded.ext.module.maas:412 ][WARNING ][6229] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 02:19:37,044 [salt.loaded.ext.module.maas:92  ][INFO    ][6229] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-23 02:19:38,442 [salt.loaded.ext.module.maas:412 ][WARNING ][6229] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 02:19:38,443 [salt.loaded.ext.module.maas:92  ][INFO    ][6229] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=q837ad architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-23 02:19:40,200 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (852.214751959s left)
2019-05-23 02:20:01,291 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022001279647
2019-05-23 02:20:01,320 [salt.minion      :1432][INFO    ][8356] Starting a new job with PID 8356
2019-05-23 02:20:01,345 [salt.minion      :1711][INFO    ][8356] Returning information for job: 20190523022001279647
2019-05-23 02:20:12,693 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (819.721125841s left)
2019-05-23 02:20:31,494 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022031478737
2019-05-23 02:20:31,518 [salt.minion      :1432][INFO    ][8398] Starting a new job with PID 8398
2019-05-23 02:20:31,540 [salt.minion      :1711][INFO    ][8398] Returning information for job: 20190523022031478737
2019-05-23 02:20:45,248 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (787.166618824s left)
2019-05-23 02:21:01,688 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022101678812
2019-05-23 02:21:01,714 [salt.minion      :1432][INFO    ][8433] Starting a new job with PID 8433
2019-05-23 02:21:01,735 [salt.minion      :1711][INFO    ][8433] Returning information for job: 20190523022101678812
2019-05-23 02:21:17,579 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (754.835242033s left)
2019-05-23 02:21:31,861 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022131853291
2019-05-23 02:21:31,881 [salt.minion      :1432][INFO    ][8482] Starting a new job with PID 8482
2019-05-23 02:21:31,905 [salt.minion      :1711][INFO    ][8482] Returning information for job: 20190523022131853291
2019-05-23 02:21:49,987 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (722.42797184s left)
2019-05-23 02:22:02,091 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022202072232
2019-05-23 02:22:02,116 [salt.minion      :1432][INFO    ][8517] Starting a new job with PID 8517
2019-05-23 02:22:02,139 [salt.minion      :1711][INFO    ][8517] Returning information for job: 20190523022202072232
2019-05-23 02:22:22,496 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (689.917948961s left)
2019-05-23 02:22:32,116 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022232100223
2019-05-23 02:22:32,141 [salt.minion      :1432][INFO    ][8572] Starting a new job with PID 8572
2019-05-23 02:22:32,164 [salt.minion      :1711][INFO    ][8572] Returning information for job: 20190523022232100223
2019-05-23 02:22:54,672 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (657.742342949s left)
2019-05-23 02:23:02,146 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022302133398
2019-05-23 02:23:02,177 [salt.minion      :1432][INFO    ][8609] Starting a new job with PID 8609
2019-05-23 02:23:02,200 [salt.minion      :1711][INFO    ][8609] Returning information for job: 20190523022302133398
2019-05-23 02:23:26,880 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (625.534749031s left)
2019-05-23 02:23:32,183 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022332171588
2019-05-23 02:23:32,209 [salt.minion      :1432][INFO    ][8667] Starting a new job with PID 8667
2019-05-23 02:23:32,229 [salt.minion      :1711][INFO    ][8667] Returning information for job: 20190523022332171588
2019-05-23 02:23:59,068 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (593.345994949s left)
2019-05-23 02:24:02,251 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022402235070
2019-05-23 02:24:02,280 [salt.minion      :1432][INFO    ][8723] Starting a new job with PID 8723
2019-05-23 02:24:02,303 [salt.minion      :1711][INFO    ][8723] Returning information for job: 20190523022402235070
2019-05-23 02:24:31,313 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (561.10176301s left)
2019-05-23 02:24:32,312 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022432304310
2019-05-23 02:24:32,336 [salt.minion      :1432][INFO    ][8783] Starting a new job with PID 8783
2019-05-23 02:24:32,360 [salt.minion      :1711][INFO    ][8783] Returning information for job: 20190523022432304310
2019-05-23 02:25:02,394 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022502382489
2019-05-23 02:25:02,418 [salt.minion      :1432][INFO    ][8855] Starting a new job with PID 8855
2019-05-23 02:25:02,445 [salt.minion      :1711][INFO    ][8855] Returning information for job: 20190523022502382489
2019-05-23 02:25:03,637 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (528.77715683s left)
2019-05-23 02:25:32,530 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022532517112
2019-05-23 02:25:32,557 [salt.minion      :1432][INFO    ][9012] Starting a new job with PID 9012
2019-05-23 02:25:32,583 [salt.minion      :1711][INFO    ][9012] Returning information for job: 20190523022532517112
2019-05-23 02:25:35,816 [salt.loaded.ext.module.maas:1023][INFO    ][6229] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (496.598316908s left)
2019-05-23 02:26:02,608 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022602593139
2019-05-23 02:26:02,633 [salt.minion      :1432][INFO    ][9083] Starting a new job with PID 9083
2019-05-23 02:26:02,660 [salt.minion      :1711][INFO    ][9083] Returning information for job: 20190523022602593139
2019-05-23 02:26:08,466 [salt.state       :300 ][INFO    ][6229] {'ret': True}
2019-05-23 02:26:08,467 [salt.state       :1951][INFO    ][6229] Completed state [maas.wait_for_machine_status] at time 02:26:08.467002 duration_in_ms=1036066.094
2019-05-23 02:26:08,473 [salt.minion      :1711][INFO    ][6229] Returning information for job: 20190523020843248384
2019-05-23 02:26:09,126 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command state.apply with jid 20190523022609114821
2019-05-23 02:26:09,151 [salt.minion      :1432][INFO    ][9132] Starting a new job with PID 9132
2019-05-23 02:26:16,424 [salt.state       :915 ][INFO    ][9132] Loading fresh modules for state activity
2019-05-23 02:26:16,485 [salt.fileclient  :1219][INFO    ][9132] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-23 02:26:16,586 [salt.state       :1780][INFO    ][9132] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:26:16.586768
2019-05-23 02:26:16,587 [salt.state       :1813][INFO    ][9132] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 02:26:16,588 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9132] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 02:26:18,456 [salt.state       :300 ][INFO    ][9132] {'pid': 9150, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 02:26:18,457 [salt.state       :1951][INFO    ][9132] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:26:18.457179 duration_in_ms=1870.41
2019-05-23 02:26:18,461 [salt.state       :1780][INFO    ][9132] Running state [maas_machines_storage_cmp002_lvm] at time 02:26:18.461661
2019-05-23 02:26:18,462 [salt.state       :1813][INFO    ][9132] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-23 02:26:19,488 [salt.loaded.ext.module.maasng:610 ][INFO    ][9132] dkh7w6
2019-05-23 02:26:19,488 [salt.loaded.ext.module.maasng:626 ][INFO    ][9132] sda
2019-05-23 02:26:19,984 [salt.loaded.ext.module.maasng:361 ][INFO    ][9132] dkh7w6
2019-05-23 02:26:20,071 [salt.loaded.ext.module.maasng:367 ][INFO    ][9132] [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/blockdevices/1/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'820c70a5-c98f-4199-b8ae-9f77353146ce', u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dkh7w6', u'filesystem': {u'uuid': u'ad778a30-ddf2-4a4e-a933-d4e2c0b5d85d', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}], u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dkh7w6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'name': u'sda'}, {u'size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/blockdevices/3/', u'available_size': 0, u'uuid': u'1f207f97-63ec-48a9-9197-1faa1deceda2', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dkh7w6', u'partition_table_type': None, u'filesystem': {u'uuid': u'41e7a625-f0b9-42ad-aaa0-4dd69eb2b927', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'name': u'vgroot-lvroot'}]
2019-05-23 02:26:20,072 [salt.loaded.ext.module.maasng:632 ][INFO    ][9132] vgroot
2019-05-23 02:26:20,072 [salt.loaded.ext.module.maasng:635 ][INFO    ][9132] lvroot
2019-05-23 02:26:20,072 [salt.loaded.ext.module.maasng:639 ][INFO    ][9132] 107374182400
2019-05-23 02:26:20,657 [salt.loaded.ext.module.maasng:645 ][INFO    ][9132] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'children': [], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'spscwy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'spscwy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'dkh7w6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/interfaces/4/'}, u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/blockdevices/1/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'dkh7w6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'5b296f56-3842-4fde-8caa-7378b39f5a81', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dkh7w6', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'485ccf46-5217-4f51-bd5e-1d3356354a21', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'uuid': None}, u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/blockdevices/1/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'dkh7w6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'5b296f56-3842-4fde-8caa-7378b39f5a81', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dkh7w6', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'485ccf46-5217-4f51-bd5e-1d3356354a21', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'uuid': None}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/blockdevices/11/', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'dkh7w6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'09196159-1dfd-4dbb-8990-c2a3ee6bade9', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 11, u'serial': None, u'uuid': u'147f7f43-6078-446b-836b-d09e2fc7092f'}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/dkh7w6/', u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'dkh7w6', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/blockdevices/1/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'dkh7w6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'5b296f56-3842-4fde-8caa-7378b39f5a81', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dkh7w6', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'485ccf46-5217-4f51-bd5e-1d3356354a21', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'uuid': None}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'dkh7w6', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/blockdevices/11/', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'dkh7w6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'09196159-1dfd-4dbb-8990-c2a3ee6bade9', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 11, u'serial': None, u'uuid': u'147f7f43-6078-446b-836b-d09e2fc7092f'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'children': [], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'spscwy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'spscwy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'dkh7w6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/interfaces/4/'}, {u'children': [], u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'dkh7w6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/interfaces/13/'}, {u'children': [], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'spscwy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'spscwy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'dkh7w6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/interfaces/14/'}, {u'children': [], u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'dkh7w6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/interfaces/15/'}, {u'children': [], u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'dkh7w6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/interfaces/11/'}, {u'children': [], u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'dkh7w6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/dkh7w6/interfaces/12/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-23 02:26:20,659 [salt.state       :300 ][INFO    ][9132] {'new': {'storage_layout': 'lvm'}}
2019-05-23 02:26:20,659 [salt.state       :1951][INFO    ][9132] Completed state [maas_machines_storage_cmp002_lvm] at time 02:26:20.659925 duration_in_ms=2198.264
2019-05-23 02:26:20,660 [salt.state       :1780][INFO    ][9132] Running state [maas_machines_storage_cmp001_lvm] at time 02:26:20.660284
2019-05-23 02:26:20,660 [salt.state       :1813][INFO    ][9132] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-23 02:26:21,772 [salt.loaded.ext.module.maasng:610 ][INFO    ][9132] pcxypq
2019-05-23 02:26:21,773 [salt.loaded.ext.module.maasng:626 ][INFO    ][9132] sda
2019-05-23 02:26:22,263 [salt.loaded.ext.module.maasng:361 ][INFO    ][9132] pcxypq
2019-05-23 02:26:22,358 [salt.loaded.ext.module.maasng:367 ][INFO    ][9132] [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'id': 2, u'name': u'sda', u'system_id': u'pcxypq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'eb251c53-d9c3-479f-9b87-c6ac19008dcf', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pcxypq', u'filesystem': {u'label': None, u'uuid': u'e70ce170-3866-40c2-bb92-e5633dd1d6b9', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'device_id': 2}]}, {u'size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/blockdevices/4/', u'available_size': 0, u'uuid': u'5f9bda6e-e914-4e47-989c-c094831c8672', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'id': 4, u'name': u'vgroot-lvroot', u'system_id': u'pcxypq', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'6a343306-b834-45d3-995d-0b18e8d9b914', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'serial': None, u'partitions': []}]
2019-05-23 02:26:22,359 [salt.loaded.ext.module.maasng:632 ][INFO    ][9132] vgroot
2019-05-23 02:26:22,359 [salt.loaded.ext.module.maasng:635 ][INFO    ][9132] lvroot
2019-05-23 02:26:22,359 [salt.loaded.ext.module.maasng:639 ][INFO    ][9132] 107374182400
2019-05-23 02:26:22,937 [salt.loaded.ext.module.maasng:645 ][INFO    ][9132] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'children': [], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'spscwy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'spscwy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'pcxypq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'pcxypq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'3d8853eb-38c0-4caa-9320-93ec073b8690', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pcxypq', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e7eb467a-ab33-45fd-9652-65af505dc4a6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}, u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'pcxypq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'3d8853eb-38c0-4caa-9320-93ec073b8690', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pcxypq', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e7eb467a-ab33-45fd-9652-65af505dc4a6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/blockdevices/12/', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'pcxypq', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4ecdd5aa-09fc-44eb-8463-fc740aa3ecfb', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 12, u'serial': None, u'uuid': u'293417e5-b0cd-422d-bc66-27af3d012eac'}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/pcxypq/', u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'pcxypq', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'pcxypq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'3d8853eb-38c0-4caa-9320-93ec073b8690', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pcxypq', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e7eb467a-ab33-45fd-9652-65af505dc4a6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'pcxypq', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/blockdevices/12/', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'pcxypq', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4ecdd5aa-09fc-44eb-8463-fc740aa3ecfb', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 12, u'serial': None, u'uuid': u'293417e5-b0cd-422d-bc66-27af3d012eac'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'children': [], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'spscwy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'spscwy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'pcxypq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/interfaces/5/'}, {u'children': [], u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'pcxypq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/interfaces/17/'}, {u'children': [], u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'pcxypq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/interfaces/18/'}, {u'children': [], u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'pcxypq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/interfaces/16/'}, {u'children': [], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'spscwy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'spscwy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'pcxypq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/interfaces/19/'}, {u'children': [], u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'pcxypq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/pcxypq/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-23 02:26:22,940 [salt.state       :300 ][INFO    ][9132] {'new': {'storage_layout': 'lvm'}}
2019-05-23 02:26:22,941 [salt.state       :1951][INFO    ][9132] Completed state [maas_machines_storage_cmp001_lvm] at time 02:26:22.941201 duration_in_ms=2280.914
2019-05-23 02:26:22,949 [salt.minion      :1711][INFO    ][9132] Returning information for job: 20190523022609114821
2019-05-23 02:26:23,528 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command state.apply with jid 20190523022623508270
2019-05-23 02:26:23,553 [salt.minion      :1432][INFO    ][9171] Starting a new job with PID 9171
2019-05-23 02:26:24,625 [salt.state       :915 ][INFO    ][9171] Loading fresh modules for state activity
2019-05-23 02:26:24,689 [salt.fileclient  :1219][INFO    ][9171] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-23 02:26:24,743 [salt.state       :1780][INFO    ][9171] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:26:24.742955
2019-05-23 02:26:24,743 [salt.state       :1813][INFO    ][9171] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 02:26:24,745 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9171] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 02:26:26,468 [salt.state       :300 ][INFO    ][9171] {'pid': 9178, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 02:26:26,469 [salt.state       :1951][INFO    ][9171] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:26:26.468942 duration_in_ms=1725.988
2019-05-23 02:26:26,471 [salt.state       :1780][INFO    ][9171] Running state [maas.deploy_machines] at time 02:26:26.471817
2019-05-23 02:26:26,472 [salt.state       :1813][INFO    ][9171] Executing state module.run for [maas.deploy_machines]
2019-05-23 02:26:26,473 [salt.utils.decorators:613 ][WARNING ][9171] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 02:26:26,993 [salt.loaded.ext.module.maas:684 ][INFO    ][9171] deploymachines hwe_kernel=ga-16.04 system_id=dkh7w6 distro_series=xenial
2019-05-23 02:26:29,470 [salt.loaded.ext.module.maas:684 ][INFO    ][9171] deploymachines hwe_kernel=ga-16.04 system_id=pcxypq distro_series=xenial
2019-05-23 02:26:31,933 [salt.loaded.ext.module.maas:684 ][INFO    ][9171] deploymachines hwe_kernel=ga-16.04 system_id=cs3ays distro_series=xenial
2019-05-23 02:26:34,424 [salt.loaded.ext.module.maas:684 ][INFO    ][9171] deploymachines hwe_kernel=ga-16.04 system_id=narhby distro_series=xenial
2019-05-23 02:26:36,923 [salt.loaded.ext.module.maas:684 ][INFO    ][9171] deploymachines hwe_kernel=ga-16.04 system_id=q837ad distro_series=xenial
2019-05-23 02:26:38,599 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022638588404
2019-05-23 02:26:38,622 [salt.minion      :1432][INFO    ][9439] Starting a new job with PID 9439
2019-05-23 02:26:38,648 [salt.minion      :1711][INFO    ][9439] Returning information for job: 20190523022638588404
2019-05-23 02:26:39,572 [salt.state       :300 ][INFO    ][9171] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-23 02:26:39,572 [salt.state       :1951][INFO    ][9171] Completed state [maas.deploy_machines] at time 02:26:39.572720 duration_in_ms=13100.902
2019-05-23 02:26:39,577 [salt.minion      :1711][INFO    ][9171] Returning information for job: 20190523022623508270
2019-05-23 02:26:40,163 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command state.apply with jid 20190523022640149379
2019-05-23 02:26:40,189 [salt.minion      :1432][INFO    ][9456] Starting a new job with PID 9456
2019-05-23 02:26:48,043 [salt.state       :915 ][INFO    ][9456] Loading fresh modules for state activity
2019-05-23 02:26:48,101 [salt.fileclient  :1219][INFO    ][9456] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-23 02:26:48,153 [salt.state       :1780][INFO    ][9456] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:26:48.153416
2019-05-23 02:26:48,153 [salt.state       :1813][INFO    ][9456] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 02:26:48,155 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9456] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 02:26:49,866 [salt.state       :300 ][INFO    ][9456] {'pid': 9470, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 02:26:49,866 [salt.state       :1951][INFO    ][9456] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:26:49.866756 duration_in_ms=1713.341
2019-05-23 02:26:49,868 [salt.state       :1780][INFO    ][9456] Running state [maas.wait_for_machine_status] at time 02:26:49.868702
2019-05-23 02:26:49,869 [salt.state       :1813][INFO    ][9456] Executing state module.run for [maas.wait_for_machine_status]
2019-05-23 02:26:49,869 [salt.utils.decorators:613 ][WARNING ][9456] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 02:26:52,624 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.25445104s left)
2019-05-23 02:26:55,246 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022655238727
2019-05-23 02:26:55,276 [salt.minion      :1432][INFO    ][9625] Starting a new job with PID 9625
2019-05-23 02:26:55,298 [salt.minion      :1711][INFO    ][9625] Returning information for job: 20190523022655238727
2019-05-23 02:27:25,141 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.73783422s left)
2019-05-23 02:27:25,325 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022725314466
2019-05-23 02:27:25,354 [salt.minion      :1432][INFO    ][9683] Starting a new job with PID 9683
2019-05-23 02:27:25,380 [salt.minion      :1711][INFO    ][9683] Returning information for job: 20190523022725314466
2019-05-23 02:27:55,409 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022755400387
2019-05-23 02:27:55,428 [salt.minion      :1432][INFO    ][9711] Starting a new job with PID 9711
2019-05-23 02:27:55,451 [salt.minion      :1711][INFO    ][9711] Returning information for job: 20190523022755400387
2019-05-23 02:27:57,624 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2182.25457215s left)
2019-05-23 02:28:25,469 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022825460474
2019-05-23 02:28:25,493 [salt.minion      :1432][INFO    ][9756] Starting a new job with PID 9756
2019-05-23 02:28:25,515 [salt.minion      :1711][INFO    ][9756] Returning information for job: 20190523022825460474
2019-05-23 02:28:30,160 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2149.71902609s left)
2019-05-23 02:28:55,533 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022855526585
2019-05-23 02:28:55,547 [salt.minion      :1432][INFO    ][9786] Starting a new job with PID 9786
2019-05-23 02:28:55,567 [salt.minion      :1711][INFO    ][9786] Returning information for job: 20190523022855526585
2019-05-23 02:29:02,755 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2117.12342715s left)
2019-05-23 02:29:25,595 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022925586481
2019-05-23 02:29:25,616 [salt.minion      :1432][INFO    ][9868] Starting a new job with PID 9868
2019-05-23 02:29:25,638 [salt.minion      :1711][INFO    ][9868] Returning information for job: 20190523022925586481
2019-05-23 02:29:35,564 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2084.31462407s left)
2019-05-23 02:29:55,696 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523022955679510
2019-05-23 02:29:55,720 [salt.minion      :1432][INFO    ][9965] Starting a new job with PID 9965
2019-05-23 02:29:55,749 [salt.minion      :1711][INFO    ][9965] Returning information for job: 20190523022955679510
2019-05-23 02:30:08,239 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2051.63944411s left)
2019-05-23 02:30:25,833 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023025818529
2019-05-23 02:30:25,854 [salt.minion      :1432][INFO    ][10209] Starting a new job with PID 10209
2019-05-23 02:30:25,878 [salt.minion      :1711][INFO    ][10209] Returning information for job: 20190523023025818529
2019-05-23 02:30:40,793 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2019.0860641s left)
2019-05-23 02:30:55,950 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023055939583
2019-05-23 02:30:55,968 [salt.minion      :1432][INFO    ][10309] Starting a new job with PID 10309
2019-05-23 02:30:55,996 [salt.minion      :1711][INFO    ][10309] Returning information for job: 20190523023055939583
2019-05-23 02:31:13,745 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1986.13395405s left)
2019-05-23 02:31:26,068 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023126054380
2019-05-23 02:31:26,094 [salt.minion      :1432][INFO    ][10540] Starting a new job with PID 10540
2019-05-23 02:31:26,116 [salt.minion      :1711][INFO    ][10540] Returning information for job: 20190523023126054380
2019-05-23 02:31:46,637 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1953.24112916s left)
2019-05-23 02:31:56,171 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023156158960
2019-05-23 02:31:56,194 [salt.minion      :1432][INFO    ][10590] Starting a new job with PID 10590
2019-05-23 02:31:56,219 [salt.minion      :1711][INFO    ][10590] Returning information for job: 20190523023156158960
2019-05-23 02:32:19,654 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1920.22424507s left)
2019-05-23 02:32:26,371 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023226360410
2019-05-23 02:32:26,395 [salt.minion      :1432][INFO    ][11152] Starting a new job with PID 11152
2019-05-23 02:32:26,419 [salt.minion      :1711][INFO    ][11152] Returning information for job: 20190523023226360410
2019-05-23 02:32:52,523 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1887.35582018s left)
2019-05-23 02:32:56,503 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023256489439
2019-05-23 02:32:56,532 [salt.minion      :1432][INFO    ][11182] Starting a new job with PID 11182
2019-05-23 02:32:56,559 [salt.minion      :1711][INFO    ][11182] Returning information for job: 20190523023256489439
2019-05-23 02:33:25,421 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1854.458076s left)
2019-05-23 02:33:26,672 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023326659805
2019-05-23 02:33:26,696 [salt.minion      :1432][INFO    ][11367] Starting a new job with PID 11367
2019-05-23 02:33:26,722 [salt.minion      :1711][INFO    ][11367] Returning information for job: 20190523023326659805
2019-05-23 02:33:56,832 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023356818512
2019-05-23 02:33:56,855 [salt.minion      :1432][INFO    ][11396] Starting a new job with PID 11396
2019-05-23 02:33:56,879 [salt.minion      :1711][INFO    ][11396] Returning information for job: 20190523023356818512
2019-05-23 02:33:58,470 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1821.40877318s left)
2019-05-23 02:34:27,040 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023427028289
2019-05-23 02:34:27,056 [salt.minion      :1432][INFO    ][11865] Starting a new job with PID 11865
2019-05-23 02:34:27,080 [salt.minion      :1711][INFO    ][11865] Returning information for job: 20190523023427028289
2019-05-23 02:34:31,444 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1788.43487811s left)
2019-05-23 02:34:57,203 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023457190030
2019-05-23 02:34:57,226 [salt.minion      :1432][INFO    ][11917] Starting a new job with PID 11917
2019-05-23 02:34:57,253 [salt.minion      :1711][INFO    ][11917] Returning information for job: 20190523023457190030
2019-05-23 02:35:04,449 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1755.42931318s left)
2019-05-23 02:35:27,427 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023527410819
2019-05-23 02:35:27,456 [salt.minion      :1432][INFO    ][12261] Starting a new job with PID 12261
2019-05-23 02:35:27,480 [salt.minion      :1711][INFO    ][12261] Returning information for job: 20190523023527410819
2019-05-23 02:35:37,216 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1722.66309309s left)
2019-05-23 02:35:57,601 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023557586711
2019-05-23 02:35:57,624 [salt.minion      :1432][INFO    ][12291] Starting a new job with PID 12291
2019-05-23 02:35:57,653 [salt.minion      :1711][INFO    ][12291] Returning information for job: 20190523023557586711
2019-05-23 02:36:10,226 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1689.65333009s left)
2019-05-23 02:36:27,781 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023627766538
2019-05-23 02:36:27,810 [salt.minion      :1432][INFO    ][12339] Starting a new job with PID 12339
2019-05-23 02:36:27,838 [salt.minion      :1711][INFO    ][12339] Returning information for job: 20190523023627766538
2019-05-23 02:36:43,217 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1656.66167808s left)
2019-05-23 02:36:57,896 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023657887194
2019-05-23 02:36:57,917 [salt.minion      :1432][INFO    ][12413] Starting a new job with PID 12413
2019-05-23 02:36:57,947 [salt.minion      :1711][INFO    ][12413] Returning information for job: 20190523023657887194
2019-05-23 02:37:16,114 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1623.76474714s left)
2019-05-23 02:37:27,992 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023727975716
2019-05-23 02:37:28,023 [salt.minion      :1432][INFO    ][12705] Starting a new job with PID 12705
2019-05-23 02:37:28,048 [salt.minion      :1711][INFO    ][12705] Returning information for job: 20190523023727975716
2019-05-23 02:37:48,917 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1590.96204114s left)
2019-05-23 02:37:58,182 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023758172405
2019-05-23 02:37:58,211 [salt.minion      :1432][INFO    ][12739] Starting a new job with PID 12739
2019-05-23 02:37:58,233 [salt.minion      :1711][INFO    ][12739] Returning information for job: 20190523023758172405
2019-05-23 02:38:21,833 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1558.04577422s left)
2019-05-23 02:38:28,386 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023828372443
2019-05-23 02:38:28,404 [salt.minion      :1432][INFO    ][12830] Starting a new job with PID 12830
2019-05-23 02:38:28,433 [salt.minion      :1711][INFO    ][12830] Returning information for job: 20190523023828372443
2019-05-23 02:38:54,656 [salt.loaded.ext.module.maas:1023][INFO    ][9456] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1525.22237921s left)
2019-05-23 02:38:58,537 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523023858530148
2019-05-23 02:38:58,555 [salt.minion      :1432][INFO    ][12859] Starting a new job with PID 12859
2019-05-23 02:38:58,581 [salt.minion      :1711][INFO    ][12859] Returning information for job: 20190523023858530148
2019-05-23 02:39:27,695 [salt.state       :300 ][INFO    ][9456] {'ret': True}
2019-05-23 02:39:27,695 [salt.state       :1951][INFO    ][9456] Completed state [maas.wait_for_machine_status] at time 02:39:27.695341 duration_in_ms=757826.636
2019-05-23 02:39:27,701 [salt.minion      :1711][INFO    ][9456] Returning information for job: 20190523022640149379
2019-05-23 03:08:21,618 [salt.utils.schedule:1377][INFO    ][6140] Running scheduled job: __mine_interval
2019-05-23 04:08:21,618 [salt.utils.schedule:1377][INFO    ][6140] Running scheduled job: __mine_interval
2019-05-23 04:12:53,099 [salt.minion      :1308][INFO    ][6140] User sudo_ubuntu Executing command cp.push_dir with jid 20190523041253088251
2019-05-23 04:12:53,130 [salt.minion      :1432][INFO    ][19847] Starting a new job with PID 19847
