2019-07-17 20:06:51,728 [salt.utils.decorators:613 ][WARNING ][1909] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 20:06:52,667 [salt.utils.decorators:613 ][WARNING ][1909] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 20:06:55,575 [salt.loaded.int.states.file:2298][WARNING ][2424] 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-07-17 20:07:27,138 [salt.state       :2022][WARNING ][2940] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-17 20:07:30,168 [salt.utils.decorators:613 ][WARNING ][2940] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 20:22:40,495 [salt.utils.decorators:613 ][WARNING ][2940] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 20:39:22,532 [salt.utils.decorators:613 ][WARNING ][2940] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 20:54:25,636 [salt.utils.decorators:613 ][WARNING ][2940] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 20:54:27,581 [salt.loaded.ext.module.maasng:1008][WARNING ][2940] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-07-17 20:54:27,583 [salt.loaded.ext.module.maasng:1011][WARNING ][2940] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-07-17 20:54:28,249 [salt.loaded.ext.module.maasng:1235][WARNING ][2940] Ignoring parameter vlan:0
2019-07-17 20:54:29,193 [salt.utils.decorators:613 ][WARNING ][2940] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 20:54:39,760 [salt.utils.decorators:613 ][WARNING ][3001] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 20:54:39,864 [salt.loaded.ext.module.maas:412 ][WARNING ][3001] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 20:54:44,106 [salt.loaded.ext.module.maas:412 ][WARNING ][3001] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 20:54:47,792 [salt.loaded.ext.module.maas:412 ][WARNING ][3001] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 20:54:51,333 [salt.loaded.ext.module.maas:412 ][WARNING ][3001] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 20:54:59,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3873] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-17 20:54:59,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3873] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-17 20:54:59,673 [salt.utils.parsers:1051][WARNING ][384] Minion received a SIGTERM. Exiting.
2019-07-17 20:55:01,351 [salt.cli.daemons :293 ][INFO    ][3926] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2019-07-17 20:55:01,473 [salt.cli.daemons :82  ][INFO    ][3926] Starting up the Salt Minion
2019-07-17 20:55:01,474 [salt.utils.event :1017][INFO    ][3926] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2019-07-17 20:55:02,696 [salt.minion      :976 ][INFO    ][3926] Creating minion process manager
2019-07-17 20:55:04,550 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3926] Executing command ['date', '+%z'] in directory '/root'
2019-07-17 20:55:04,575 [salt.utils.schedule:568 ][INFO    ][3926] Updating job settings for scheduled job: __mine_interval
2019-07-17 20:55:04,577 [salt.minion      :1108][INFO    ][3926] Added mine.update to scheduler
2019-07-17 20:55:04,582 [salt.minion      :1975][INFO    ][3926] Minion is starting as user 'root'
2019-07-17 20:55:04,607 [salt.minion      :2336][INFO    ][3926] Minion is ready to receive requests!
2019-07-17 20:55:26,734 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command state.apply with jid 20190717205526721568
2019-07-17 20:55:26,759 [salt.minion      :1432][INFO    ][4022] Starting a new job with PID 4022
2019-07-17 20:55:32,875 [salt.state       :915 ][INFO    ][4022] Loading fresh modules for state activity
2019-07-17 20:55:32,937 [salt.fileclient  :1219][INFO    ][4022] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-17 20:55:32,991 [salt.state       :1780][INFO    ][4022] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:32.991787
2019-07-17 20:55:32,992 [salt.state       :1813][INFO    ][4022] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-17 20:55:32,994 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4022] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-17 20:55:34,738 [salt.state       :300 ][INFO    ][4022] {'pid': 4065, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-17 20:55:34,739 [salt.state       :1951][INFO    ][4022] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:34.739380 duration_in_ms=1747.593
2019-07-17 20:55:34,742 [salt.state       :1780][INFO    ][4022] Running state [maas.wait_for_machine_status] at time 20:55:34.742809
2019-07-17 20:55:34,743 [salt.state       :1813][INFO    ][4022] Executing state module.run for [maas.wait_for_machine_status]
2019-07-17 20:55:34,743 [salt.utils.decorators:613 ][WARNING ][4022] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 20:55:35,353 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.39762783s left)
2019-07-17 20:55:41,839 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717205541829886
2019-07-17 20:55:41,865 [salt.minion      :1432][INFO    ][4082] Starting a new job with PID 4082
2019-07-17 20:55:41,894 [salt.minion      :1711][INFO    ][4082] Returning information for job: 20190717205541829886
2019-07-17 20:56:05,984 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.76640391s left)
2019-07-17 20:56:11,916 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717205611900298
2019-07-17 20:56:11,943 [salt.minion      :1432][INFO    ][4100] Starting a new job with PID 4100
2019-07-17 20:56:11,961 [salt.minion      :1711][INFO    ][4100] Returning information for job: 20190717205611900298
2019-07-17 20:56:36,619 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1438.131567s left)
2019-07-17 20:56:41,976 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717205641968682
2019-07-17 20:56:42,004 [salt.minion      :1432][INFO    ][4150] Starting a new job with PID 4150
2019-07-17 20:56:42,025 [salt.minion      :1711][INFO    ][4150] Returning information for job: 20190717205641968682
2019-07-17 20:57:07,238 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.51218891s left)
2019-07-17 20:57:12,046 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717205712026612
2019-07-17 20:57:12,072 [salt.minion      :1432][INFO    ][4169] Starting a new job with PID 4169
2019-07-17 20:57:12,093 [salt.minion      :1711][INFO    ][4169] Returning information for job: 20190717205712026612
2019-07-17 20:57:38,072 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.679003s left)
2019-07-17 20:57:42,089 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717205742075956
2019-07-17 20:57:42,112 [salt.minion      :1432][INFO    ][4279] Starting a new job with PID 4279
2019-07-17 20:57:42,135 [salt.minion      :1711][INFO    ][4279] Returning information for job: 20190717205742075956
2019-07-17 20:58:08,871 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1345.88013983s left)
2019-07-17 20:58:12,153 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717205812137173
2019-07-17 20:58:12,175 [salt.minion      :1432][INFO    ][4343] Starting a new job with PID 4343
2019-07-17 20:58:12,197 [salt.minion      :1711][INFO    ][4343] Returning information for job: 20190717205812137173
2019-07-17 20:58:39,620 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1315.13092184s left)
2019-07-17 20:58:42,187 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717205842168688
2019-07-17 20:58:42,209 [salt.minion      :1432][INFO    ][4419] Starting a new job with PID 4419
2019-07-17 20:58:42,232 [salt.minion      :1711][INFO    ][4419] Returning information for job: 20190717205842168688
2019-07-17 20:59:10,558 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1284.19298697s left)
2019-07-17 20:59:12,272 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717205912259310
2019-07-17 20:59:12,294 [salt.minion      :1432][INFO    ][4574] Starting a new job with PID 4574
2019-07-17 20:59:12,317 [salt.minion      :1711][INFO    ][4574] Returning information for job: 20190717205912259310
2019-07-17 20:59:41,305 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1253.44560599s left)
2019-07-17 20:59:42,364 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717205942353524
2019-07-17 20:59:42,392 [salt.minion      :1432][INFO    ][4634] Starting a new job with PID 4634
2019-07-17 20:59:42,416 [salt.minion      :1711][INFO    ][4634] Returning information for job: 20190717205942353524
2019-07-17 21:00:12,081 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1222.67017698s left)
2019-07-17 21:00:12,473 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210012461462
2019-07-17 21:00:12,499 [salt.minion      :1432][INFO    ][4652] Starting a new job with PID 4652
2019-07-17 21:00:12,521 [salt.minion      :1711][INFO    ][4652] Returning information for job: 20190717210012461462
2019-07-17 21:00:42,570 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210042558103
2019-07-17 21:00:42,585 [salt.minion      :1432][INFO    ][4703] Starting a new job with PID 4703
2019-07-17 21:00:42,607 [salt.minion      :1711][INFO    ][4703] Returning information for job: 20190717210042558103
2019-07-17 21:00:42,880 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1191.87088394s left)
2019-07-17 21:01:12,672 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210112654950
2019-07-17 21:01:12,701 [salt.minion      :1432][INFO    ][4719] Starting a new job with PID 4719
2019-07-17 21:01:12,724 [salt.minion      :1711][INFO    ][4719] Returning information for job: 20190717210112654950
2019-07-17 21:01:13,661 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1161.08966088s left)
2019-07-17 21:01:42,788 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210142773911
2019-07-17 21:01:42,813 [salt.minion      :1432][INFO    ][4773] Starting a new job with PID 4773
2019-07-17 21:01:42,835 [salt.minion      :1711][INFO    ][4773] Returning information for job: 20190717210142773911
2019-07-17 21:01:44,450 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1130.30051494s left)
2019-07-17 21:02:12,913 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210212902715
2019-07-17 21:02:12,942 [salt.minion      :1432][INFO    ][4792] Starting a new job with PID 4792
2019-07-17 21:02:12,965 [salt.minion      :1711][INFO    ][4792] Returning information for job: 20190717210212902715
2019-07-17 21:02:15,210 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1099.540663s left)
2019-07-17 21:02:43,033 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210243017540
2019-07-17 21:02:43,060 [salt.minion      :1432][INFO    ][4846] Starting a new job with PID 4846
2019-07-17 21:02:43,084 [salt.minion      :1711][INFO    ][4846] Returning information for job: 20190717210243017540
2019-07-17 21:02:46,372 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1068.37815499s left)
2019-07-17 21:03:13,169 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210313155466
2019-07-17 21:03:13,199 [salt.minion      :1432][INFO    ][4865] Starting a new job with PID 4865
2019-07-17 21:03:13,219 [salt.minion      :1711][INFO    ][4865] Returning information for job: 20190717210313155466
2019-07-17 21:03:17,161 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1037.58971s left)
2019-07-17 21:03:43,299 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210343284808
2019-07-17 21:03:43,329 [salt.minion      :1432][INFO    ][4915] Starting a new job with PID 4915
2019-07-17 21:03:43,350 [salt.minion      :1711][INFO    ][4915] Returning information for job: 20190717210343284808
2019-07-17 21:03:47,929 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1006.82172489s left)
2019-07-17 21:04:13,439 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210413431904
2019-07-17 21:04:13,464 [salt.minion      :1432][INFO    ][4934] Starting a new job with PID 4934
2019-07-17 21:04:13,486 [salt.minion      :1711][INFO    ][4934] Returning information for job: 20190717210413431904
2019-07-17 21:04:18,853 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (975.897997856s left)
2019-07-17 21:04:43,586 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210443570368
2019-07-17 21:04:43,613 [salt.minion      :1432][INFO    ][4987] Starting a new job with PID 4987
2019-07-17 21:04:43,633 [salt.minion      :1711][INFO    ][4987] Returning information for job: 20190717210443570368
2019-07-17 21:04:49,605 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (945.145635843s left)
2019-07-17 21:05:13,756 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210513745563
2019-07-17 21:05:13,781 [salt.minion      :1432][INFO    ][5006] Starting a new job with PID 5006
2019-07-17 21:05:13,801 [salt.minion      :1711][INFO    ][5006] Returning information for job: 20190717210513745563
2019-07-17 21:05:20,379 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (914.371647835s left)
2019-07-17 21:05:43,925 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210543913987
2019-07-17 21:05:43,943 [salt.minion      :1432][INFO    ][5059] Starting a new job with PID 5059
2019-07-17 21:05:43,967 [salt.minion      :1711][INFO    ][5059] Returning information for job: 20190717210543913987
2019-07-17 21:05:50,599 [salt.loaded.ext.module.maas:981 ][INFO    ][4022] Machine htbphh deleted
2019-07-17 21:05:51,008 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:05:51,009 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=gtw01 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:05:55,303 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:05:55,304 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=t8t7am architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:05:59,131 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:05:59,132 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=s7ccmg architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:03,014 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:06:03,015 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=ctl01 power_type=ipmi mac_addresses=[u'14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=xx6h4p architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:06,990 [salt.loaded.ext.module.maas:981 ][INFO    ][4022] Machine t8t7am deleted
2019-07-17 21:06:07,379 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:06:07,380 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=gtw01 power_type=ipmi mac_addresses=[u'14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=ycfya4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:11,134 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:06:11,135 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:14,085 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210614074707
2019-07-17 21:06:14,107 [salt.minion      :1432][INFO    ][5371] Starting a new job with PID 5371
2019-07-17 21:06:14,132 [salt.minion      :1711][INFO    ][5371] Returning information for job: 20190717210614074707
2019-07-17 21:06:15,460 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:06:15,462 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=s7ccmg architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:19,432 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:06:19,433 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=ctl01 power_type=ipmi mac_addresses=[u'14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=xx6h4p architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:23,470 [salt.loaded.ext.module.maas:981 ][INFO    ][4022] Machine s7ccmg deleted
2019-07-17 21:06:23,859 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:06:23,860 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=gtw01 power_type=ipmi mac_addresses=[u'14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=ycfya4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:27,615 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:06:27,616 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=gaw6rr architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:31,475 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:06:31,476 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:35,795 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:06:35,795 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=ctl01 power_type=ipmi mac_addresses=[u'14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=xx6h4p architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:39,697 [salt.loaded.ext.module.maas:981 ][INFO    ][4022] Machine xx6h4p deleted
2019-07-17 21:06:40,051 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:06:40,051 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=gtw01 power_type=ipmi mac_addresses=[u'14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=ycfya4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:43,926 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:06:43,927 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=gaw6rr architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:44,251 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210644238140
2019-07-17 21:06:44,277 [salt.minion      :1432][INFO    ][5746] Starting a new job with PID 5746
2019-07-17 21:06:44,300 [salt.minion      :1711][INFO    ][5746] Returning information for job: 20190717210644238140
2019-07-17 21:06:47,738 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:06:47,739 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=x84pqe architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:51,386 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:06:51,387 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=ctl01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:06:55,555 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (819.195749998s left)
2019-07-17 21:07:14,431 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210714415972
2019-07-17 21:07:14,460 [salt.minion      :1432][INFO    ][5897] Starting a new job with PID 5897
2019-07-17 21:07:14,480 [salt.minion      :1711][INFO    ][5897] Returning information for job: 20190717210714415972
2019-07-17 21:07:26,179 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (788.571365833s left)
2019-07-17 21:07:44,651 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210744640646
2019-07-17 21:07:44,673 [salt.minion      :1432][INFO    ][5998] Starting a new job with PID 5998
2019-07-17 21:07:44,699 [salt.minion      :1711][INFO    ][5998] Returning information for job: 20190717210744640646
2019-07-17 21:07:56,978 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (757.772691965s left)
2019-07-17 21:08:14,872 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210814861219
2019-07-17 21:08:14,890 [salt.minion      :1432][INFO    ][6074] Starting a new job with PID 6074
2019-07-17 21:08:14,908 [salt.minion      :1711][INFO    ][6074] Returning information for job: 20190717210814861219
2019-07-17 21:08:27,929 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (726.821629047s left)
2019-07-17 21:08:45,071 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210845062055
2019-07-17 21:08:45,104 [salt.minion      :1432][INFO    ][6192] Starting a new job with PID 6192
2019-07-17 21:08:45,152 [salt.minion      :1711][INFO    ][6192] Returning information for job: 20190717210845062055
2019-07-17 21:08:58,863 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (695.887861967s left)
2019-07-17 21:09:15,141 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210915126163
2019-07-17 21:09:15,168 [salt.minion      :1432][INFO    ][6248] Starting a new job with PID 6248
2019-07-17 21:09:15,192 [salt.minion      :1711][INFO    ][6248] Returning information for job: 20190717210915126163
2019-07-17 21:09:29,897 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (664.853966951s left)
2019-07-17 21:09:45,358 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717210945350946
2019-07-17 21:09:45,380 [salt.minion      :1432][INFO    ][6334] Starting a new job with PID 6334
2019-07-17 21:09:45,401 [salt.minion      :1711][INFO    ][6334] Returning information for job: 20190717210945350946
2019-07-17 21:10:07,649 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (627.101984978s left)
2019-07-17 21:10:15,448 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211015434960
2019-07-17 21:10:15,473 [salt.minion      :1432][INFO    ][6430] Starting a new job with PID 6430
2019-07-17 21:10:15,497 [salt.minion      :1711][INFO    ][6430] Returning information for job: 20190717211015434960
2019-07-17 21:10:38,487 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (596.263747931s left)
2019-07-17 21:10:45,511 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211045493306
2019-07-17 21:10:45,546 [salt.minion      :1432][INFO    ][6641] Starting a new job with PID 6641
2019-07-17 21:10:45,574 [salt.minion      :1711][INFO    ][6641] Returning information for job: 20190717211045493306
2019-07-17 21:11:09,511 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (565.240139961s left)
2019-07-17 21:11:15,545 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211115531819
2019-07-17 21:11:15,564 [salt.minion      :1432][INFO    ][6681] Starting a new job with PID 6681
2019-07-17 21:11:15,601 [salt.minion      :1711][INFO    ][6681] Returning information for job: 20190717211115531819
2019-07-17 21:11:40,381 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (534.369632959s left)
2019-07-17 21:11:45,643 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211145629117
2019-07-17 21:11:45,668 [salt.minion      :1432][INFO    ][6767] Starting a new job with PID 6767
2019-07-17 21:11:45,690 [salt.minion      :1711][INFO    ][6767] Returning information for job: 20190717211145629117
2019-07-17 21:12:11,190 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (503.56033802s left)
2019-07-17 21:12:15,738 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211215724676
2019-07-17 21:12:15,764 [salt.minion      :1432][INFO    ][6818] Starting a new job with PID 6818
2019-07-17 21:12:15,786 [salt.minion      :1711][INFO    ][6818] Returning information for job: 20190717211215724676
2019-07-17 21:12:42,144 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (472.606688976s left)
2019-07-17 21:12:45,852 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211245837909
2019-07-17 21:12:45,879 [salt.minion      :1432][INFO    ][6995] Starting a new job with PID 6995
2019-07-17 21:12:45,903 [salt.minion      :1711][INFO    ][6995] Returning information for job: 20190717211245837909
2019-07-17 21:13:13,116 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (441.634976864s left)
2019-07-17 21:13:15,968 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211315955121
2019-07-17 21:13:15,991 [salt.minion      :1432][INFO    ][7023] Starting a new job with PID 7023
2019-07-17 21:13:16,018 [salt.minion      :1711][INFO    ][7023] Returning information for job: 20190717211315955121
2019-07-17 21:13:44,355 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001']
sleep for:30s Timeout:1500s (410.395755053s left)
2019-07-17 21:13:46,133 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211346119085
2019-07-17 21:13:46,160 [salt.minion      :1432][INFO    ][7091] Starting a new job with PID 7091
2019-07-17 21:13:46,188 [salt.minion      :1711][INFO    ][7091] Returning information for job: 20190717211346119085
2019-07-17 21:14:15,670 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001']
sleep for:30s Timeout:1500s (379.080178976s left)
2019-07-17 21:14:16,304 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211416289170
2019-07-17 21:14:16,331 [salt.minion      :1432][INFO    ][7129] Starting a new job with PID 7129
2019-07-17 21:14:16,359 [salt.minion      :1711][INFO    ][7129] Returning information for job: 20190717211416289170
2019-07-17 21:14:46,469 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211446454802
2019-07-17 21:14:46,488 [salt.minion      :1432][INFO    ][7173] Starting a new job with PID 7173
2019-07-17 21:14:46,515 [salt.minion      :1711][INFO    ][7173] Returning information for job: 20190717211446454802
2019-07-17 21:14:47,026 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001']
sleep for:30s Timeout:1500s (347.724349976s left)
2019-07-17 21:15:16,665 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211516656935
2019-07-17 21:15:16,692 [salt.minion      :1432][INFO    ][7198] Starting a new job with PID 7198
2019-07-17 21:15:16,715 [salt.minion      :1711][INFO    ][7198] Returning information for job: 20190717211516656935
2019-07-17 21:15:18,278 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001']
sleep for:30s Timeout:1500s (316.472404003s left)
2019-07-17 21:15:46,869 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211546862582
2019-07-17 21:15:46,894 [salt.minion      :1432][INFO    ][7244] Starting a new job with PID 7244
2019-07-17 21:15:46,917 [salt.minion      :1711][INFO    ][7244] Returning information for job: 20190717211546862582
2019-07-17 21:15:49,521 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001']
sleep for:30s Timeout:1500s (285.229964972s left)
2019-07-17 21:16:17,083 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211617067509
2019-07-17 21:16:17,108 [salt.minion      :1432][INFO    ][7273] Starting a new job with PID 7273
2019-07-17 21:16:17,137 [salt.minion      :1711][INFO    ][7273] Returning information for job: 20190717211617067509
2019-07-17 21:16:20,864 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001']
sleep for:30s Timeout:1500s (253.886904001s left)
2019-07-17 21:16:47,292 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211647279516
2019-07-17 21:16:47,321 [salt.minion      :1432][INFO    ][7321] Starting a new job with PID 7321
2019-07-17 21:16:47,352 [salt.minion      :1711][INFO    ][7321] Returning information for job: 20190717211647279516
2019-07-17 21:16:51,197 [salt.loaded.ext.module.maas:981 ][INFO    ][4022] Machine ycfya4 deleted
2019-07-17 21:16:51,748 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:16:51,748 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=gtw01 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:16:53,130 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:16:53,131 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=gaw6rr architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:16:54,079 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:16:54,080 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=x84pqe architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:16:55,014 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:16:55,015 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=ctl01 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=nsbqee architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:16:56,537 [salt.loaded.ext.module.maas:981 ][INFO    ][4022] Machine gaw6rr deleted
2019-07-17 21:16:57,104 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:16:57,104 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=gtw01 power_type=ipmi mac_addresses=[u'14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=gwttft architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:16:57,958 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:16:57,958 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:16:59,535 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:16:59,536 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=x84pqe architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:17:00,538 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:17:00,539 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=ctl01 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=nsbqee architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:17:02,386 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001']
sleep for:30s Timeout:1500s (212.365502834s left)
2019-07-17 21:17:17,325 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211717311912
2019-07-17 21:17:17,355 [salt.minion      :1432][INFO    ][7735] Starting a new job with PID 7735
2019-07-17 21:17:17,380 [salt.minion      :1711][INFO    ][7735] Returning information for job: 20190717211717311912
2019-07-17 21:17:33,319 [salt.loaded.ext.module.maas:981 ][INFO    ][4022] Machine x84pqe deleted
2019-07-17 21:17:33,911 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:17:33,911 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=gtw01 power_type=ipmi mac_addresses=[u'14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=gwttft architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:17:34,722 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:17:34,722 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=myxbm6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:17:35,824 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:17:35,824 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:17:37,255 [salt.loaded.ext.module.maas:412 ][WARNING ][4022] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:17:37,256 [salt.loaded.ext.module.maas:92  ][INFO    ][4022] machine hostname=ctl01 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=nsbqee architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:17:38,791 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001']
sleep for:30s Timeout:1500s (175.959703922s left)
2019-07-17 21:17:47,416 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211747402826
2019-07-17 21:17:47,436 [salt.minion      :1432][INFO    ][8002] Starting a new job with PID 8002
2019-07-17 21:17:47,455 [salt.minion      :1711][INFO    ][8002] Returning information for job: 20190717211747402826
2019-07-17 21:18:09,940 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001']
sleep for:30s Timeout:1500s (144.811069965s left)
2019-07-17 21:18:17,476 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211817460943
2019-07-17 21:18:17,505 [salt.minion      :1432][INFO    ][8032] Starting a new job with PID 8032
2019-07-17 21:18:17,527 [salt.minion      :1711][INFO    ][8032] Returning information for job: 20190717211817460943
2019-07-17 21:18:41,152 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001']
sleep for:30s Timeout:1500s (113.59869504s left)
2019-07-17 21:18:47,580 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211847567267
2019-07-17 21:18:47,609 [salt.minion      :1432][INFO    ][8076] Starting a new job with PID 8076
2019-07-17 21:18:47,633 [salt.minion      :1711][INFO    ][8076] Returning information for job: 20190717211847567267
2019-07-17 21:19:12,337 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001']
sleep for:30s Timeout:1500s (82.4136359692s left)
2019-07-17 21:19:17,686 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211917670476
2019-07-17 21:19:17,712 [salt.minion      :1432][INFO    ][8107] Starting a new job with PID 8107
2019-07-17 21:19:17,734 [salt.minion      :1711][INFO    ][8107] Returning information for job: 20190717211917670476
2019-07-17 21:19:43,557 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001']
sleep for:30s Timeout:1500s (51.1937298775s left)
2019-07-17 21:19:47,720 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717211947706212
2019-07-17 21:19:47,752 [salt.minion      :1432][INFO    ][8176] Starting a new job with PID 8176
2019-07-17 21:19:47,776 [salt.minion      :1711][INFO    ][8176] Returning information for job: 20190717211947706212
2019-07-17 21:20:14,728 [salt.loaded.ext.module.maas:1023][INFO    ][4022] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001']
sleep for:30s Timeout:1500s (20.0225739479s left)
2019-07-17 21:20:17,871 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212017861025
2019-07-17 21:20:17,898 [salt.minion      :1432][INFO    ][8216] Starting a new job with PID 8216
2019-07-17 21:20:17,920 [salt.minion      :1711][INFO    ][8216] Returning information for job: 20190717212017861025
2019-07-17 21:20:46,076 [salt.state       :302 ][ERROR   ][4022] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['gtw01', 'cmp002', 'cmp001']not in Ready|Deployed state
2019-07-17 21:20:46,077 [salt.state       :1951][INFO    ][4022] Completed state [maas.wait_for_machine_status] at time 21:20:46.077133 duration_in_ms=1511334.318
2019-07-17 21:20:46,085 [salt.minion      :1711][INFO    ][4022] Returning information for job: 20190717205526721568
2019-07-17 21:20:57,044 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command pillar.get with jid 20190717212057031676
2019-07-17 21:20:57,073 [salt.minion      :1432][INFO    ][8452] Starting a new job with PID 8452
2019-07-17 21:20:57,084 [salt.minion      :1711][INFO    ][8452] Returning information for job: 20190717212057031676
2019-07-17 21:20:57,657 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command service.status with jid 20190717212057645100
2019-07-17 21:20:57,689 [salt.minion      :1432][INFO    ][8457] Starting a new job with PID 8457
2019-07-17 21:20:58,463 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][8457] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-07-17 21:20:58,508 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][8457] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-07-17 21:20:58,531 [salt.minion      :1711][INFO    ][8457] Returning information for job: 20190717212057645100
2019-07-17 21:20:59,156 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command state.apply with jid 20190717212059144909
2019-07-17 21:20:59,180 [salt.minion      :1432][INFO    ][8487] Starting a new job with PID 8487
2019-07-17 21:21:05,398 [salt.state       :915 ][INFO    ][8487] Loading fresh modules for state activity
2019-07-17 21:21:05,937 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command 'salt-minion --version' in directory '/root'
2019-07-17 21:21:06,296 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command 'salt-minion --version' in directory '/root'
2019-07-17 21:21:07,166 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command 'salt-minion --version' in directory '/root'
2019-07-17 21:21:07,448 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command 'salt-minion --version' in directory '/root'
2019-07-17 21:21:09,273 [salt.state       :1780][INFO    ][8487] Running state [salt-minion] at time 21:21:09.273567
2019-07-17 21:21:09,274 [salt.state       :1813][INFO    ][8487] Executing state pkg.installed for [salt-minion]
2019-07-17 21:21:09,274 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-17 21:21:09,394 [salt.state       :300 ][INFO    ][8487] All specified packages are already installed
2019-07-17 21:21:09,395 [salt.state       :1951][INFO    ][8487] Completed state [salt-minion] at time 21:21:09.395107 duration_in_ms=121.542
2019-07-17 21:21:09,395 [salt.state       :1780][INFO    ][8487] Running state [salt_minion_dependency_packages] at time 21:21:09.395444
2019-07-17 21:21:09,395 [salt.state       :1813][INFO    ][8487] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-07-17 21:21:09,405 [salt.state       :300 ][INFO    ][8487] All specified packages are already installed
2019-07-17 21:21:09,405 [salt.state       :1951][INFO    ][8487] Completed state [salt_minion_dependency_packages] at time 21:21:09.405323 duration_in_ms=9.879
2019-07-17 21:21:09,411 [salt.state       :1780][INFO    ][8487] Running state [/etc/salt/minion.d/minion.conf] at time 21:21:09.410964
2019-07-17 21:21:09,411 [salt.state       :1813][INFO    ][8487] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-07-17 21:21:09,693 [salt.state       :300 ][INFO    ][8487] File /etc/salt/minion.d/minion.conf is in the correct state
2019-07-17 21:21:09,694 [salt.state       :1951][INFO    ][8487] Completed state [/etc/salt/minion.d/minion.conf] at time 21:21:09.693974 duration_in_ms=283.009
2019-07-17 21:21:09,701 [salt.state       :1780][INFO    ][8487] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:21:09.701241
2019-07-17 21:21:09,701 [salt.state       :1813][INFO    ][8487] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-07-17 21:21:09,717 [salt.state       :300 ][INFO    ][8487] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-07-17 21:21:09,717 [salt.state       :1951][INFO    ][8487] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:21:09.717357 duration_in_ms=16.117
2019-07-17 21:21:09,719 [salt.state       :1780][INFO    ][8487] Running state [salt-minion] at time 21:21:09.719212
2019-07-17 21:21:09,719 [salt.state       :1813][INFO    ][8487] Executing state service.running for [salt-minion]
2019-07-17 21:21:09,720 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-17 21:21:09,761 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-07-17 21:21:09,778 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-07-17 21:21:09,803 [salt.state       :300 ][INFO    ][8487] The service salt-minion is already running
2019-07-17 21:21:09,804 [salt.state       :1951][INFO    ][8487] Completed state [salt-minion] at time 21:21:09.804328 duration_in_ms=85.115
2019-07-17 21:21:09,808 [salt.state       :1780][INFO    ][8487] Running state [/etc/salt/grains.d] at time 21:21:09.808708
2019-07-17 21:21:09,809 [salt.state       :1813][INFO    ][8487] Executing state file.directory for [/etc/salt/grains.d]
2019-07-17 21:21:09,811 [salt.state       :300 ][INFO    ][8487] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-07-17 21:21:09,812 [salt.state       :1951][INFO    ][8487] Completed state [/etc/salt/grains.d] at time 21:21:09.811950 duration_in_ms=3.243
2019-07-17 21:21:09,812 [salt.state       :1780][INFO    ][8487] Running state [/etc/salt/grains] at time 21:21:09.812900
2019-07-17 21:21:09,813 [salt.state       :1813][INFO    ][8487] Executing state file.managed for [/etc/salt/grains]
2019-07-17 21:21:09,814 [salt.state       :300 ][INFO    ][8487] File /etc/salt/grains exists with proper permissions. No changes made.
2019-07-17 21:21:09,814 [salt.state       :1951][INFO    ][8487] Completed state [/etc/salt/grains] at time 21:21:09.814567 duration_in_ms=1.668
2019-07-17 21:21:09,815 [salt.state       :1780][INFO    ][8487] Running state [/etc/salt/grains.d/placeholder] at time 21:21:09.815227
2019-07-17 21:21:09,815 [salt.state       :1813][INFO    ][8487] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-07-17 21:21:09,816 [salt.state       :300 ][INFO    ][8487] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-07-17 21:21:09,816 [salt.state       :1951][INFO    ][8487] Completed state [/etc/salt/grains.d/placeholder] at time 21:21:09.816850 duration_in_ms=1.622
2019-07-17 21:21:09,817 [salt.state       :1780][INFO    ][8487] Running state [/etc/salt/grains.d/sphinx] at time 21:21:09.817513
2019-07-17 21:21:09,819 [salt.state       :1813][INFO    ][8487] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-07-17 21:21:09,821 [salt.state       :300 ][INFO    ][8487] File /etc/salt/grains.d/sphinx is in the correct state
2019-07-17 21:21:09,821 [salt.state       :1951][INFO    ][8487] Completed state [/etc/salt/grains.d/sphinx] at time 21:21:09.821385 duration_in_ms=3.873
2019-07-17 21:21:09,824 [salt.state       :1780][INFO    ][8487] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:21:09.824734
2019-07-17 21:21:09,825 [salt.state       :1813][INFO    ][8487] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-07-17 21:21:09,825 [salt.state       :300 ][INFO    ][8487] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-07-17 21:21:09,825 [salt.state       :1951][INFO    ][8487] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:21:09.825501 duration_in_ms=0.767
2019-07-17 21:21:09,826 [salt.state       :1780][INFO    ][8487] Running state [/etc/salt/grains.d/dns_records] at time 21:21:09.825970
2019-07-17 21:21:09,826 [salt.state       :1813][INFO    ][8487] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-07-17 21:21:09,827 [salt.state       :300 ][INFO    ][8487] File /etc/salt/grains.d/dns_records is in the correct state
2019-07-17 21:21:09,827 [salt.state       :1951][INFO    ][8487] Completed state [/etc/salt/grains.d/dns_records] at time 21:21:09.827490 duration_in_ms=1.52
2019-07-17 21:21:09,828 [salt.state       :1780][INFO    ][8487] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:21:09.828379
2019-07-17 21:21:09,828 [salt.state       :1813][INFO    ][8487] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-07-17 21:21:09,828 [salt.state       :300 ][INFO    ][8487] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-07-17 21:21:09,829 [salt.state       :1951][INFO    ][8487] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:21:09.829124 duration_in_ms=0.745
2019-07-17 21:21:09,829 [salt.state       :1780][INFO    ][8487] Running state [/etc/salt/grains.d/salt] at time 21:21:09.829580
2019-07-17 21:21:09,830 [salt.state       :1813][INFO    ][8487] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-07-17 21:21:09,831 [salt.state       :300 ][INFO    ][8487] File /etc/salt/grains.d/salt is in the correct state
2019-07-17 21:21:09,832 [salt.state       :1951][INFO    ][8487] Completed state [/etc/salt/grains.d/salt] at time 21:21:09.831985 duration_in_ms=2.405
2019-07-17 21:21:09,832 [salt.state       :1780][INFO    ][8487] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:21:09.832829
2019-07-17 21:21:09,833 [salt.state       :1813][INFO    ][8487] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-07-17 21:21:09,833 [salt.state       :300 ][INFO    ][8487] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-07-17 21:21:09,833 [salt.state       :1951][INFO    ][8487] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:21:09.833572 duration_in_ms=0.743
2019-07-17 21:21:09,836 [salt.state       :1780][INFO    ][8487] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:21:09.835973
2019-07-17 21:21:09,836 [salt.state       :1813][INFO    ][8487] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-07-17 21:21:09,836 [salt.state       :300 ][INFO    ][8487] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-07-17 21:21:09,836 [salt.state       :1951][INFO    ][8487] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:21:09.836738 duration_in_ms=0.765
2019-07-17 21:21:09,837 [salt.state       :1780][INFO    ][8487] Running state [mine.update] at time 21:21:09.837376
2019-07-17 21:21:09,837 [salt.state       :1813][INFO    ][8487] Executing state module.wait for [mine.update]
2019-07-17 21:21:09,837 [salt.state       :300 ][INFO    ][8487] No changes made for mine.update
2019-07-17 21:21:09,838 [salt.state       :1951][INFO    ][8487] Completed state [mine.update] at time 21:21:09.838079 duration_in_ms=0.703
2019-07-17 21:21:09,838 [salt.state       :1780][INFO    ][8487] Running state [ca-certificates] at time 21:21:09.838313
2019-07-17 21:21:09,838 [salt.state       :1813][INFO    ][8487] Executing state pkg.installed for [ca-certificates]
2019-07-17 21:21:09,848 [salt.state       :300 ][INFO    ][8487] All specified packages are already installed
2019-07-17 21:21:09,848 [salt.state       :1951][INFO    ][8487] Completed state [ca-certificates] at time 21:21:09.848433 duration_in_ms=10.12
2019-07-17 21:21:09,849 [salt.state       :1780][INFO    ][8487] Running state [update-ca-certificates] at time 21:21:09.849333
2019-07-17 21:21:09,849 [salt.state       :1813][INFO    ][8487] Executing state cmd.wait for [update-ca-certificates]
2019-07-17 21:21:09,849 [salt.state       :300 ][INFO    ][8487] No changes made for update-ca-certificates
2019-07-17 21:21:09,850 [salt.state       :1951][INFO    ][8487] Completed state [update-ca-certificates] at time 21:21:09.850078 duration_in_ms=0.745
2019-07-17 21:21:09,850 [salt.state       :1780][INFO    ][8487] Running state [iptables] at time 21:21:09.850321
2019-07-17 21:21:09,850 [salt.state       :1813][INFO    ][8487] Executing state pkg.installed for [iptables]
2019-07-17 21:21:09,859 [salt.state       :300 ][INFO    ][8487] All specified packages are already installed
2019-07-17 21:21:09,859 [salt.state       :1951][INFO    ][8487] Completed state [iptables] at time 21:21:09.859846 duration_in_ms=9.525
2019-07-17 21:21:09,860 [salt.state       :1780][INFO    ][8487] Running state [iptables-persistent] at time 21:21:09.860082
2019-07-17 21:21:09,860 [salt.state       :1813][INFO    ][8487] Executing state pkg.installed for [iptables-persistent]
2019-07-17 21:21:09,870 [salt.state       :300 ][INFO    ][8487] All specified packages are already installed
2019-07-17 21:21:09,870 [salt.state       :1951][INFO    ][8487] Completed state [iptables-persistent] at time 21:21:09.870518 duration_in_ms=10.436
2019-07-17 21:21:09,871 [salt.state       :1780][INFO    ][8487] Running state [iptables_modules_v4_load] at time 21:21:09.871703
2019-07-17 21:21:09,871 [salt.state       :1813][INFO    ][8487] Executing state kmod.present for [iptables_modules_v4_load]
2019-07-17 21:21:09,872 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command 'lsmod' in directory '/root'
2019-07-17 21:21:09,893 [salt.state       :300 ][INFO    ][8487] Kernel modules iptable_filter, ip_tables are already present
2019-07-17 21:21:09,894 [salt.state       :1951][INFO    ][8487] Completed state [iptables_modules_v4_load] at time 21:21:09.894160 duration_in_ms=22.457
2019-07-17 21:21:09,895 [salt.state       :1780][INFO    ][8487] Running state [/etc/iptables/rules.v4] at time 21:21:09.895243
2019-07-17 21:21:09,895 [salt.state       :1813][INFO    ][8487] Executing state file.managed for [/etc/iptables/rules.v4]
2019-07-17 21:21:10,004 [salt.state       :300 ][INFO    ][8487] File /etc/iptables/rules.v4 is in the correct state
2019-07-17 21:21:10,004 [salt.state       :1951][INFO    ][8487] Completed state [/etc/iptables/rules.v4] at time 21:21:10.004626 duration_in_ms=109.382
2019-07-17 21:21:10,006 [salt.state       :1780][INFO    ][8487] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:21:10.006830
2019-07-17 21:21:10,007 [salt.state       :1813][INFO    ][8487] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-07-17 21:21:10,008 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-07-17 21:21:10,033 [salt.state       :300 ][INFO    ][8487] onlyif execution failed
2019-07-17 21:21:10,036 [salt.state       :1951][INFO    ][8487] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:21:10.036067 duration_in_ms=29.238
2019-07-17 21:21:10,037 [salt.state       :1780][INFO    ][8487] Running state [netfilter-persistent] at time 21:21:10.037704
2019-07-17 21:21:10,038 [salt.state       :1813][INFO    ][8487] Executing state service.running for [netfilter-persistent]
2019-07-17 21:21:10,039 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-07-17 21:21:10,060 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-07-17 21:21:10,084 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-07-17 21:21:10,105 [salt.state       :300 ][INFO    ][8487] The service netfilter-persistent is already running
2019-07-17 21:21:10,107 [salt.state       :1951][INFO    ][8487] Completed state [netfilter-persistent] at time 21:21:10.106999 duration_in_ms=69.295
2019-07-17 21:21:10,108 [salt.state       :1780][INFO    ][8487] Running state [iptables_extra.remove_stale_tables] at time 21:21:10.108328
2019-07-17 21:21:10,108 [salt.state       :1813][INFO    ][8487] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-07-17 21:21:10,109 [salt.state       :300 ][INFO    ][8487] No changes made for iptables_extra.remove_stale_tables
2019-07-17 21:21:10,110 [salt.state       :1951][INFO    ][8487] Completed state [iptables_extra.remove_stale_tables] at time 21:21:10.109673 duration_in_ms=1.344
2019-07-17 21:21:10,111 [salt.state       :1780][INFO    ][8487] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:21:10.111175
2019-07-17 21:21:10,111 [salt.state       :1813][INFO    ][8487] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-07-17 21:21:10,112 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8487] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-07-17 21:21:10,132 [salt.state       :300 ][INFO    ][8487] onlyif execution failed
2019-07-17 21:21:10,133 [salt.state       :1951][INFO    ][8487] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:21:10.132996 duration_in_ms=21.82
2019-07-17 21:21:10,134 [salt.state       :1780][INFO    ][8487] Running state [/etc/iptables/rules.v6] at time 21:21:10.134575
2019-07-17 21:21:10,135 [salt.state       :1813][INFO    ][8487] Executing state file.absent for [/etc/iptables/rules.v6]
2019-07-17 21:21:10,135 [salt.state       :300 ][INFO    ][8487] File /etc/iptables/rules.v6 is not present
2019-07-17 21:21:10,136 [salt.state       :1951][INFO    ][8487] Completed state [/etc/iptables/rules.v6] at time 21:21:10.136174 duration_in_ms=1.599
2019-07-17 21:21:10,137 [salt.state       :1780][INFO    ][8487] Running state [iptables_extra.flush_all] at time 21:21:10.137339
2019-07-17 21:21:10,137 [salt.state       :1813][INFO    ][8487] Executing state module.wait for [iptables_extra.flush_all]
2019-07-17 21:21:10,138 [salt.state       :300 ][INFO    ][8487] No changes made for iptables_extra.flush_all
2019-07-17 21:21:10,138 [salt.state       :1951][INFO    ][8487] Completed state [iptables_extra.flush_all] at time 21:21:10.138573 duration_in_ms=1.235
2019-07-17 21:21:10,145 [salt.minion      :1711][INFO    ][8487] Returning information for job: 20190717212059144909
2019-07-17 21:21:10,705 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command state.apply with jid 20190717212110692546
2019-07-17 21:21:10,727 [salt.minion      :1432][INFO    ][8570] Starting a new job with PID 8570
2019-07-17 21:21:11,620 [salt.state       :915 ][INFO    ][8570] Loading fresh modules for state activity
2019-07-17 21:21:12,586 [salt.state       :1780][INFO    ][8570] Running state [maas-rack-controller] at time 21:21:12.586409
2019-07-17 21:21:12,586 [salt.state       :1813][INFO    ][8570] Executing state pkg.installed for [maas-rack-controller]
2019-07-17 21:21:12,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8570] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-17 21:21:12,702 [salt.state       :300 ][INFO    ][8570] All specified packages are already installed
2019-07-17 21:21:12,703 [salt.state       :1951][INFO    ][8570] Completed state [maas-rack-controller] at time 21:21:12.703282 duration_in_ms=116.874
2019-07-17 21:21:12,703 [salt.state       :1780][INFO    ][8570] Running state [ipmitool] at time 21:21:12.703603
2019-07-17 21:21:12,703 [salt.state       :1813][INFO    ][8570] Executing state pkg.installed for [ipmitool]
2019-07-17 21:21:12,713 [salt.state       :300 ][INFO    ][8570] All specified packages are already installed
2019-07-17 21:21:12,713 [salt.state       :1951][INFO    ][8570] Completed state [ipmitool] at time 21:21:12.713287 duration_in_ms=9.684
2019-07-17 21:21:12,716 [salt.state       :1780][INFO    ][8570] Running state [/etc/maas/rackd.conf] at time 21:21:12.716504
2019-07-17 21:21:12,716 [salt.state       :1813][INFO    ][8570] Executing state file.line for [/etc/maas/rackd.conf]
2019-07-17 21:21:12,717 [salt.state       :300 ][INFO    ][8570] No changes needed to be made
2019-07-17 21:21:12,718 [salt.state       :1951][INFO    ][8570] Completed state [/etc/maas/rackd.conf] at time 21:21:12.718005 duration_in_ms=1.501
2019-07-17 21:21:12,718 [salt.state       :1780][INFO    ][8570] Running state [/etc/maas/rackd.conf] at time 21:21:12.718241
2019-07-17 21:21:12,718 [salt.state       :1813][INFO    ][8570] Executing state file.managed for [/etc/maas/rackd.conf]
2019-07-17 21:21:12,718 [salt.loaded.int.states.file:2298][WARNING ][8570] 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-07-17 21:21:12,719 [salt.state       :300 ][INFO    ][8570] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-07-17 21:21:12,719 [salt.state       :1951][INFO    ][8570] Completed state [/etc/maas/rackd.conf] at time 21:21:12.719518 duration_in_ms=1.277
2019-07-17 21:21:12,720 [salt.state       :1780][INFO    ][8570] Running state [maas-rackd] at time 21:21:12.720495
2019-07-17 21:21:12,720 [salt.state       :1813][INFO    ][8570] Executing state service.running for [maas-rackd]
2019-07-17 21:21:12,721 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8570] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-07-17 21:21:12,757 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8570] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-07-17 21:21:12,775 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8570] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-07-17 21:21:12,793 [salt.state       :300 ][INFO    ][8570] The service maas-rackd is already running
2019-07-17 21:21:12,793 [salt.state       :1951][INFO    ][8570] Completed state [maas-rackd] at time 21:21:12.793587 duration_in_ms=73.091
2019-07-17 21:21:12,798 [salt.minion      :1711][INFO    ][8570] Returning information for job: 20190717212110692546
2019-07-17 21:21:13,360 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command state.apply with jid 20190717212113352345
2019-07-17 21:21:13,381 [salt.minion      :1432][INFO    ][8593] Starting a new job with PID 8593
2019-07-17 21:21:14,243 [salt.state       :915 ][INFO    ][8593] Loading fresh modules for state activity
2019-07-17 21:21:15,333 [salt.state       :1780][INFO    ][8593] Running state [maas-region-controller] at time 21:21:15.333236
2019-07-17 21:21:15,333 [salt.state       :1813][INFO    ][8593] Executing state pkg.installed for [maas-region-controller]
2019-07-17 21:21:15,334 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8593] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-17 21:21:15,456 [salt.state       :300 ][INFO    ][8593] All specified packages are already installed
2019-07-17 21:21:15,457 [salt.state       :1951][INFO    ][8593] Completed state [maas-region-controller] at time 21:21:15.457175 duration_in_ms=123.94
2019-07-17 21:21:15,457 [salt.state       :1780][INFO    ][8593] Running state [python-oauth] at time 21:21:15.457511
2019-07-17 21:21:15,458 [salt.state       :1813][INFO    ][8593] Executing state pkg.installed for [python-oauth]
2019-07-17 21:21:15,467 [salt.state       :300 ][INFO    ][8593] All specified packages are already installed
2019-07-17 21:21:15,467 [salt.state       :1951][INFO    ][8593] Completed state [python-oauth] at time 21:21:15.467224 duration_in_ms=9.712
2019-07-17 21:21:15,471 [salt.state       :1780][INFO    ][8593] Running state [/etc/maas/regiond.conf] at time 21:21:15.471493
2019-07-17 21:21:15,471 [salt.state       :1813][INFO    ][8593] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-17 21:21:15,476 [salt.state       :300 ][INFO    ][8593] No changes needed to be made
2019-07-17 21:21:15,476 [salt.state       :1951][INFO    ][8593] Completed state [/etc/maas/regiond.conf] at time 21:21:15.476454 duration_in_ms=4.961
2019-07-17 21:21:15,476 [salt.state       :1780][INFO    ][8593] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:21:15.476930
2019-07-17 21:21:15,477 [salt.state       :1813][INFO    ][8593] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-17 21:21:15,546 [salt.state       :300 ][INFO    ][8593] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-17 21:21:15,546 [salt.state       :1951][INFO    ][8593] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:21:15.546522 duration_in_ms=69.592
2019-07-17 21:21:15,547 [salt.state       :1780][INFO    ][8593] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:21:15.547044
2019-07-17 21:21:15,547 [salt.state       :1813][INFO    ][8593] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-17 21:21:15,551 [salt.state       :300 ][INFO    ][8593] No changes needed to be made
2019-07-17 21:21:15,551 [salt.state       :1951][INFO    ][8593] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:21:15.551704 duration_in_ms=4.659
2019-07-17 21:21:15,552 [salt.state       :1780][INFO    ][8593] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:21:15.552176
2019-07-17 21:21:15,552 [salt.state       :1813][INFO    ][8593] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-17 21:21:15,557 [salt.state       :300 ][INFO    ][8593] No changes needed to be made
2019-07-17 21:21:15,557 [salt.state       :1951][INFO    ][8593] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:21:15.557182 duration_in_ms=5.006
2019-07-17 21:21:15,557 [salt.state       :1780][INFO    ][8593] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:21:15.557663
2019-07-17 21:21:15,557 [salt.state       :1813][INFO    ][8593] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-17 21:21:15,596 [salt.state       :300 ][INFO    ][8593] No changes needed to be made
2019-07-17 21:21:15,597 [salt.state       :1951][INFO    ][8593] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:21:15.596999 duration_in_ms=39.335
2019-07-17 21:21:15,597 [salt.state       :1780][INFO    ][8593] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:21:15.597606
2019-07-17 21:21:15,597 [salt.state       :1813][INFO    ][8593] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-17 21:21:15,624 [salt.state       :300 ][INFO    ][8593] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-17 21:21:15,624 [salt.state       :1951][INFO    ][8593] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:21:15.624818 duration_in_ms=27.211
2019-07-17 21:21:15,633 [salt.state       :1780][INFO    ][8593] Running state [a2enmod headers] at time 21:21:15.633074
2019-07-17 21:21:15,633 [salt.state       :1813][INFO    ][8593] Executing state cmd.run for [a2enmod headers]
2019-07-17 21:21:15,634 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8593] Executing command 'a2enmod headers' in directory '/root'
2019-07-17 21:21:15,721 [salt.state       :300 ][INFO    ][8593] {'pid': 8627, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-17 21:21:15,721 [salt.state       :1951][INFO    ][8593] Completed state [a2enmod headers] at time 21:21:15.721456 duration_in_ms=88.382
2019-07-17 21:21:15,724 [salt.state       :1780][INFO    ][8593] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:21:15.724081
2019-07-17 21:21:15,724 [salt.state       :1813][INFO    ][8593] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-17 21:21:15,751 [salt.state       :300 ][INFO    ][8593] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-17 21:21:15,751 [salt.state       :1951][INFO    ][8593] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:21:15.751430 duration_in_ms=27.348
2019-07-17 21:21:15,752 [salt.state       :1780][INFO    ][8593] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:21:15.752129
2019-07-17 21:21:15,752 [salt.state       :1813][INFO    ][8593] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-17 21:21:15,820 [salt.state       :300 ][INFO    ][8593] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-17 21:21:15,820 [salt.state       :1951][INFO    ][8593] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:21:15.820842 duration_in_ms=68.713
2019-07-17 21:21:15,821 [salt.state       :1780][INFO    ][8593] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:21:15.821351
2019-07-17 21:21:15,821 [salt.state       :1813][INFO    ][8593] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-17 21:21:15,884 [salt.state       :300 ][INFO    ][8593] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-17 21:21:15,884 [salt.state       :1951][INFO    ][8593] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:21:15.884243 duration_in_ms=62.892
2019-07-17 21:21:15,884 [salt.state       :1780][INFO    ][8593] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:21:15.884726
2019-07-17 21:21:15,885 [salt.state       :1813][INFO    ][8593] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-17 21:21:15,974 [salt.state       :300 ][INFO    ][8593] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-17 21:21:15,974 [salt.state       :1951][INFO    ][8593] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:21:15.974842 duration_in_ms=90.116
2019-07-17 21:21:15,975 [salt.state       :1780][INFO    ][8593] Running state [/root/.pgpass] at time 21:21:15.975095
2019-07-17 21:21:15,975 [salt.state       :1813][INFO    ][8593] Executing state file.managed for [/root/.pgpass]
2019-07-17 21:21:16,029 [salt.state       :300 ][INFO    ][8593] File /root/.pgpass is in the correct state
2019-07-17 21:21:16,030 [salt.state       :1951][INFO    ][8593] Completed state [/root/.pgpass] at time 21:21:16.030029 duration_in_ms=54.934
2019-07-17 21:21:16,036 [salt.state       :1780][INFO    ][8593] Running state [maas-region syncdb --noinput] at time 21:21:16.036030
2019-07-17 21:21:16,036 [salt.state       :1813][INFO    ][8593] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-17 21:21:16,037 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8593] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-17 21:21:18,956 [salt.state       :300 ][INFO    ][8593] {'pid': 8641, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, contenttypes, sessions, piston3, metadataserver, auth, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-07-17 21:21:18,957 [salt.state       :1951][INFO    ][8593] Completed state [maas-region syncdb --noinput] at time 21:21:18.957035 duration_in_ms=2921.003
2019-07-17 21:21:18,957 [salt.state       :2022][WARNING ][8593] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-17 21:21:18,963 [salt.state       :1780][INFO    ][8593] Running state [maas-regiond] at time 21:21:18.963139
2019-07-17 21:21:18,963 [salt.state       :1813][INFO    ][8593] Executing state service.running for [maas-regiond]
2019-07-17 21:21:18,965 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8593] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-17 21:21:19,012 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8593] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-17 21:21:19,032 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8593] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-17 21:21:19,055 [salt.state       :300 ][INFO    ][8593] The service maas-regiond is already running
2019-07-17 21:21:19,056 [salt.state       :1951][INFO    ][8593] Completed state [maas-regiond] at time 21:21:19.056031 duration_in_ms=92.892
2019-07-17 21:21:19,060 [salt.state       :1780][INFO    ][8593] Running state [bind9] at time 21:21:19.060513
2019-07-17 21:21:19,060 [salt.state       :1813][INFO    ][8593] Executing state service.running for [bind9]
2019-07-17 21:21:19,061 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8593] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-17 21:21:19,081 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8593] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-17 21:21:19,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8593] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-17 21:21:19,122 [salt.state       :300 ][INFO    ][8593] The service bind9 is already running
2019-07-17 21:21:19,123 [salt.state       :1951][INFO    ][8593] Completed state [bind9] at time 21:21:19.123250 duration_in_ms=62.737
2019-07-17 21:21:19,126 [salt.state       :1780][INFO    ][8593] Running state [apache2] at time 21:21:19.126812
2019-07-17 21:21:19,127 [salt.state       :1813][INFO    ][8593] Executing state service.running for [apache2]
2019-07-17 21:21:19,129 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8593] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-17 21:21:19,158 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8593] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-17 21:21:19,181 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8593] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-17 21:21:19,207 [salt.state       :300 ][INFO    ][8593] The service apache2 is already running
2019-07-17 21:21:19,207 [salt.state       :1951][INFO    ][8593] Completed state [apache2] at time 21:21:19.207746 duration_in_ms=80.934
2019-07-17 21:21:19,209 [salt.state       :1780][INFO    ][8593] Running state [maasng.wait_for_http_code] at time 21:21:19.209594
2019-07-17 21:21:19,210 [salt.state       :1813][INFO    ][8593] Executing state module.run for [maasng.wait_for_http_code]
2019-07-17 21:21:19,211 [salt.utils.decorators:613 ][WARNING ][8593] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 21:21:19,473 [salt.state       :300 ][INFO    ][8593] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-17 21:21:19,474 [salt.state       :1951][INFO    ][8593] Completed state [maasng.wait_for_http_code] at time 21:21:19.474590 duration_in_ms=264.995
2019-07-17 21:21:19,475 [salt.state       :1780][INFO    ][8593] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:21:19.475852
2019-07-17 21:21:19,476 [salt.state       :1813][INFO    ][8593] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-17 21:21:19,477 [salt.state       :300 ][INFO    ][8593] /var/lib/maas/.setup_admin exists
2019-07-17 21:21:19,477 [salt.state       :1951][INFO    ][8593] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:21:19.477344 duration_in_ms=1.491
2019-07-17 21:21:19,479 [salt.state       :1780][INFO    ][8593] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:19.479652
2019-07-17 21:21:19,479 [salt.state       :1813][INFO    ][8593] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-17 21:21:19,480 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8593] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-17 21:21:21,259 [salt.state       :300 ][INFO    ][8593] {'pid': 8663, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-17 21:21:21,260 [salt.state       :1951][INFO    ][8593] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:21.259859 duration_in_ms=1780.205
2019-07-17 21:21:21,272 [salt.state       :1780][INFO    ][8593] Running state [maas_region_boot_source_resources_mirror] at time 21:21:21.272823
2019-07-17 21:21:21,273 [salt.state       :1813][INFO    ][8593] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-17 21:21:21,379 [salt.state       :300 ][INFO    ][8593] {'changes': {}}
2019-07-17 21:21:21,379 [salt.state       :1951][INFO    ][8593] Completed state [maas_region_boot_source_resources_mirror] at time 21:21:21.379902 duration_in_ms=107.081
2019-07-17 21:21:21,380 [salt.state       :1780][INFO    ][8593] Running state [maasng.boot_resources_import] at time 21:21:21.380787
2019-07-17 21:21:21,381 [salt.state       :1813][INFO    ][8593] Executing state module.run for [maasng.boot_resources_import]
2019-07-17 21:21:21,381 [salt.utils.decorators:613 ][WARNING ][8593] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 21:21:21,476 [salt.loaded.ext.module.maasng:1600][INFO    ][8593] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-07-17 21:21:26,523 [salt.loaded.ext.module.maasng:1600][INFO    ][8593] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-07-17 21:21:28,447 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212128432281
2019-07-17 21:21:28,475 [salt.minion      :1432][INFO    ][8702] Starting a new job with PID 8702
2019-07-17 21:21:28,499 [salt.minion      :1711][INFO    ][8702] Returning information for job: 20190717212128432281
2019-07-17 21:21:31,620 [salt.state       :300 ][INFO    ][8593] {'ret': True}
2019-07-17 21:21:31,621 [salt.state       :1951][INFO    ][8593] Completed state [maasng.boot_resources_import] at time 21:21:31.620957 duration_in_ms=10240.159
2019-07-17 21:21:31,624 [salt.state       :1780][INFO    ][8593] Running state [maas_region_boot_sources_selection_xenial] at time 21:21:31.624812
2019-07-17 21:21:31,625 [salt.state       :1813][INFO    ][8593] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-17 21:21:31,856 [salt.state       :300 ][INFO    ][8593] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-17 21:21:31,856 [salt.state       :1951][INFO    ][8593] Completed state [maas_region_boot_sources_selection_xenial] at time 21:21:31.856795 duration_in_ms=231.982
2019-07-17 21:21:31,858 [salt.state       :1780][INFO    ][8593] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:21:31.858426
2019-07-17 21:21:31,858 [salt.state       :1813][INFO    ][8593] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-17 21:21:31,859 [salt.utils.decorators:613 ][WARNING ][8593] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 21:21:31,859 [salt.loaded.ext.module.maasng:1771][INFO    ][8593] boot-sources sync initiated for ALL Rack's
2019-07-17 21:21:33,073 [salt.state       :300 ][INFO    ][8593] {'ret': True}
2019-07-17 21:21:33,076 [salt.state       :1951][INFO    ][8593] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:21:33.073689 duration_in_ms=1215.262
2019-07-17 21:21:33,080 [salt.state       :1780][INFO    ][8593] Running state [maas.process_maas_config] at time 21:21:33.079879
2019-07-17 21:21:33,080 [salt.state       :1813][INFO    ][8593] Executing state module.run for [maas.process_maas_config]
2019-07-17 21:21:33,081 [salt.utils.decorators:613 ][WARNING ][8593] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 21:21:33,082 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=enable_http_proxy value=True
2019-07-17 21:21:33,143 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=upstream_dns value=8.8.8.8
2019-07-17 21:21:33,202 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=commissioning_distro_series value=xenial
2019-07-17 21:21:34,577 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=default_osystem value=ubuntu
2019-07-17 21:21:34,643 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=active_discovery_interval value=600
2019-07-17 21:21:34,709 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=dnssec_validation value=no
2019-07-17 21:21:34,756 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=maas_name value=mas01
2019-07-17 21:21:34,803 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=network_discovery value=enabled
2019-07-17 21:21:34,913 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=enable_third_party_drivers value=True
2019-07-17 21:21:34,964 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=default_storage_layout value=lvm
2019-07-17 21:21:35,015 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=ntp_external_only value=True
2019-07-17 21:21:35,064 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-17 21:21:35,115 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=default_distro_series value=xenial
2019-07-17 21:21:35,224 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-07-17 21:21:35,351 [salt.state       :300 ][INFO    ][8593] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-07-17 21:21:35,352 [salt.state       :1951][INFO    ][8593] Completed state [maas.process_maas_config] at time 21:21:35.352165 duration_in_ms=2272.286
2019-07-17 21:21:35,353 [salt.state       :1780][INFO    ][8593] Running state [pxe_admin] at time 21:21:35.353150
2019-07-17 21:21:35,353 [salt.state       :1813][INFO    ][8593] Executing state maasng.fabric_present for [pxe_admin]
2019-07-17 21:21:35,416 [salt.loaded.ext.module.maasng:945 ][INFO    ][8593] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-17 21:21:35,479 [salt.loaded.ext.module.maasng:1008][WARNING ][8593] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-17 21:21:35,480 [salt.loaded.ext.module.maasng:1011][WARNING ][8593] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-17 21:21:35,543 [salt.state       :300 ][INFO    ][8593] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-17 21:21:35,543 [salt.state       :1951][INFO    ][8593] Completed state [pxe_admin] at time 21:21:35.543643 duration_in_ms=190.493
2019-07-17 21:21:35,544 [salt.state       :1780][INFO    ][8593] Running state [vlan 0] at time 21:21:35.544030
2019-07-17 21:21:35,544 [salt.state       :1813][INFO    ][8593] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-17 21:21:35,597 [salt.loaded.ext.module.maasng:945 ][INFO    ][8593] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-07-17 21:21:35,719 [salt.loaded.ext.module.maasng:945 ][INFO    ][8593] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, 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'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8fsyfw', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-07-17 21:21:35,970 [salt.loaded.ext.module.maasng:945 ][INFO    ][8593] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-17 21:21:36,054 [salt.state       :300 ][INFO    ][8593] {'new': 'Vlan untagged was updated'}
2019-07-17 21:21:36,055 [salt.state       :1951][INFO    ][8593] Completed state [vlan 0] at time 21:21:36.054951 duration_in_ms=510.921
2019-07-17 21:21:36,056 [salt.state       :1780][INFO    ][8593] Running state [192.168.11.0/24] at time 21:21:36.055987
2019-07-17 21:21:36,056 [salt.state       :1813][INFO    ][8593] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-17 21:21:36,249 [salt.loaded.ext.module.maasng:945 ][INFO    ][8593] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-17 21:21:36,250 [salt.loaded.ext.module.maasng:1235][WARNING ][8593] Ignoring parameter vlan:0
2019-07-17 21:21:36,328 [salt.state       :300 ][INFO    ][8593] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-17 21:21:36,329 [salt.state       :1951][INFO    ][8593] Completed state [192.168.11.0/24] at time 21:21:36.329129 duration_in_ms=273.142
2019-07-17 21:21:36,330 [salt.state       :1780][INFO    ][8593] Running state [maas_create_iprange_1] at time 21:21:36.330821
2019-07-17 21:21:36,331 [salt.state       :1813][INFO    ][8593] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-17 21:21:36,390 [salt.state       :300 ][INFO    ][8593] Iprange maas_create_iprange_1 already exist.
2019-07-17 21:21:36,390 [salt.state       :1951][INFO    ][8593] Completed state [maas_create_iprange_1] at time 21:21:36.390884 duration_in_ms=60.063
2019-07-17 21:21:36,391 [salt.state       :1780][INFO    ][8593] Running state [vlan 0] at time 21:21:36.391162
2019-07-17 21:21:36,391 [salt.state       :1813][INFO    ][8593] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-17 21:21:36,459 [salt.loaded.ext.module.maasng:945 ][INFO    ][8593] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'id': 1}]
2019-07-17 21:21:36,567 [salt.loaded.ext.module.maasng:945 ][INFO    ][8593] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'id': 1}]
2019-07-17 21:21:36,858 [salt.loaded.ext.module.maasng:945 ][INFO    ][8593] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'id': 1}]
2019-07-17 21:21:36,952 [salt.state       :300 ][INFO    ][8593] {'new': 'Vlan untagged was updated'}
2019-07-17 21:21:36,952 [salt.state       :1951][INFO    ][8593] Completed state [vlan 0] at time 21:21:36.952843 duration_in_ms=561.68
2019-07-17 21:21:36,954 [salt.state       :1780][INFO    ][8593] Running state [opnfv] at time 21:21:36.953702
2019-07-17 21:21:36,955 [salt.state       :1813][INFO    ][8593] Executing state maasng.sshkey_present for [opnfv]
2019-07-17 21:21:37,014 [salt.loaded.ext.module.maasng:1903][INFO    ][8593] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-07-17 21:21:37,015 [salt.state       :300 ][INFO    ][8593] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-07-17 21:21:37,015 [salt.state       :1951][INFO    ][8593] Completed state [opnfv] at time 21:21:37.015527 duration_in_ms=61.824
2019-07-17 21:21:37,016 [salt.state       :1780][INFO    ][8593] Running state [maas.process_tags] at time 21:21:37.016313
2019-07-17 21:21:37,016 [salt.state       :1813][INFO    ][8593] Executing state module.run for [maas.process_tags]
2019-07-17 21:21:37,017 [salt.utils.decorators:613 ][WARNING ][8593] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 21:21:37,057 [salt.loaded.ext.module.maas:92  ][INFO    ][8593] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-07-17 21:21:37,121 [salt.state       :300 ][INFO    ][8593] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-07-17 21:21:37,121 [salt.state       :1951][INFO    ][8593] Completed state [maas.process_tags] at time 21:21:37.121444 duration_in_ms=105.13
2019-07-17 21:21:37,124 [salt.minion      :1711][INFO    ][8593] Returning information for job: 20190717212113352345
2019-07-17 21:21:37,725 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command state.apply with jid 20190717212137713539
2019-07-17 21:21:37,741 [salt.minion      :1432][INFO    ][9230] Starting a new job with PID 9230
2019-07-17 21:21:44,104 [salt.state       :915 ][INFO    ][9230] Loading fresh modules for state activity
2019-07-17 21:21:44,216 [salt.state       :1780][INFO    ][9230] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:44.215933
2019-07-17 21:21:44,216 [salt.state       :1813][INFO    ][9230] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-17 21:21:44,218 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9230] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-17 21:21:46,112 [salt.state       :300 ][INFO    ][9230] {'pid': 9263, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-17 21:21:46,113 [salt.state       :1951][INFO    ][9230] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:46.113202 duration_in_ms=1897.268
2019-07-17 21:21:46,116 [salt.state       :1780][INFO    ][9230] Running state [maas.process_machines] at time 21:21:46.116409
2019-07-17 21:21:46,116 [salt.state       :1813][INFO    ][9230] Executing state module.run for [maas.process_machines]
2019-07-17 21:21:46,118 [salt.utils.decorators:613 ][WARNING ][9230] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 21:21:46,509 [salt.loaded.ext.module.maas:412 ][WARNING ][9230] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:21:46,510 [salt.loaded.ext.module.maas:92  ][INFO    ][9230] machine hostname=gtw01 power_type=ipmi mac_addresses=[u'14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=gwttft architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:21:47,452 [salt.loaded.ext.module.maas:412 ][WARNING ][9230] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:21:47,455 [salt.loaded.ext.module.maas:92  ][INFO    ][9230] 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=myxbm6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:21:48,690 [salt.loaded.ext.module.maas:412 ][WARNING ][9230] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:21:48,691 [salt.loaded.ext.module.maas:92  ][INFO    ][9230] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=dsndks architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:21:49,649 [salt.loaded.ext.module.maas:412 ][WARNING ][9230] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-17 21:21:49,650 [salt.loaded.ext.module.maas:92  ][INFO    ][9230] machine hostname=ctl01 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=nsbqee architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-17 21:21:50,890 [salt.state       :300 ][INFO    ][9230] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-07-17 21:21:50,890 [salt.state       :1951][INFO    ][9230] Completed state [maas.process_machines] at time 21:21:50.890374 duration_in_ms=4773.965
2019-07-17 21:21:50,896 [salt.minion      :1711][INFO    ][9230] Returning information for job: 20190717212137713539
2019-07-17 21:22:22,013 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command state.apply with jid 20190717212222002446
2019-07-17 21:22:22,040 [salt.minion      :1432][INFO    ][9527] Starting a new job with PID 9527
2019-07-17 21:22:28,016 [salt.state       :915 ][INFO    ][9527] Loading fresh modules for state activity
2019-07-17 21:22:28,117 [salt.state       :1780][INFO    ][9527] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:22:28.116953
2019-07-17 21:22:28,117 [salt.state       :1813][INFO    ][9527] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-17 21:22:28,119 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9527] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-17 21:22:29,841 [salt.state       :300 ][INFO    ][9527] {'pid': 9538, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-17 21:22:29,843 [salt.state       :1951][INFO    ][9527] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:22:29.842857 duration_in_ms=1725.903
2019-07-17 21:22:29,847 [salt.state       :1780][INFO    ][9527] Running state [maas.wait_for_machine_status] at time 21:22:29.847508
2019-07-17 21:22:29,847 [salt.state       :1813][INFO    ][9527] Executing state module.run for [maas.wait_for_machine_status]
2019-07-17 21:22:29,848 [salt.utils.decorators:613 ][WARNING ][9527] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 21:22:32,172 [salt.loaded.ext.module.maas:1023][INFO    ][9527] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp001']
sleep for:30s Timeout:1500s (1497.68320608s left)
2019-07-17 21:22:37,132 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212237115464
2019-07-17 21:22:37,161 [salt.minion      :1432][INFO    ][9682] Starting a new job with PID 9682
2019-07-17 21:22:37,185 [salt.minion      :1711][INFO    ][9682] Returning information for job: 20190717212237115464
2019-07-17 21:23:04,031 [salt.loaded.ext.module.maas:1023][INFO    ][9527] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1465.8241961s left)
2019-07-17 21:23:07,232 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212307221189
2019-07-17 21:23:07,260 [salt.minion      :1432][INFO    ][9728] Starting a new job with PID 9728
2019-07-17 21:23:07,283 [salt.minion      :1711][INFO    ][9728] Returning information for job: 20190717212307221189
2019-07-17 21:23:36,172 [salt.state       :300 ][INFO    ][9527] {'ret': True}
2019-07-17 21:23:36,173 [salt.state       :1951][INFO    ][9527] Completed state [maas.wait_for_machine_status] at time 21:23:36.173181 duration_in_ms=66325.672
2019-07-17 21:23:36,176 [salt.minion      :1711][INFO    ][9527] Returning information for job: 20190717212222002446
2019-07-17 21:23:36,725 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command state.apply with jid 20190717212336716658
2019-07-17 21:23:36,745 [salt.minion      :1432][INFO    ][9810] Starting a new job with PID 9810
2019-07-17 21:23:42,759 [salt.state       :915 ][INFO    ][9810] Loading fresh modules for state activity
2019-07-17 21:23:42,823 [salt.fileclient  :1219][INFO    ][9810] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-17 21:23:42,923 [salt.state       :1780][INFO    ][9810] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:23:42.923257
2019-07-17 21:23:42,923 [salt.state       :1813][INFO    ][9810] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-17 21:23:42,925 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9810] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-17 21:23:44,685 [salt.state       :300 ][INFO    ][9810] {'pid': 9817, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-17 21:23:44,687 [salt.state       :1951][INFO    ][9810] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:23:44.686884 duration_in_ms=1763.626
2019-07-17 21:23:44,691 [salt.state       :1780][INFO    ][9810] Running state [maas_machines_storage_cmp002_lvm] at time 21:23:44.691631
2019-07-17 21:23:44,692 [salt.state       :1813][INFO    ][9810] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-17 21:23:45,705 [salt.loaded.ext.module.maasng:610 ][INFO    ][9810] myxbm6
2019-07-17 21:23:45,706 [salt.loaded.ext.module.maasng:626 ][INFO    ][9810] sda
2019-07-17 21:23:46,175 [salt.loaded.ext.module.maasng:361 ][INFO    ][9810] myxbm6
2019-07-17 21:23:46,269 [salt.loaded.ext.module.maasng:367 ][INFO    ][9810] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/blockdevices/3/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'myxbm6', 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'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'id': 3, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'5f39ea9e-7760-46a1-8de2-c639495728c4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'myxbm6', u'device_id': 3, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'4197f69d-2a3e-4c2a-bab6-c9e9ed65cfc0', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/blockdevices/3/partition/2'}]}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/blockdevices/4/', u'used_size': 800097042432, u'tags': [], u'uuid': u'b412c0b9-4c54-417e-966c-154aeaf52fa7', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'myxbm6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'bb0f2ea0-08e0-435d-8d37-bdff7f1f5c8d', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 800097042432, u'type': u'virtual', u'id': 4, u'serial': None, u'partitions': []}]
2019-07-17 21:23:46,270 [salt.loaded.ext.module.maasng:632 ][INFO    ][9810] vgroot
2019-07-17 21:23:46,270 [salt.loaded.ext.module.maasng:635 ][INFO    ][9810] lvroot
2019-07-17 21:23:46,271 [salt.loaded.ext.module.maasng:639 ][INFO    ][9810] 107374182400
2019-07-17 21:23:46,868 [salt.loaded.ext.module.maasng:645 ][INFO    ][9810] {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'swap_size': None, u'memory_test_status': -1, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8fsyfw', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 29}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8fsyfw', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8fsyfw', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'system_id': u'myxbm6', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/interfaces/18/'}, u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/blockdevices/3/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 3, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'myxbm6', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'dc880a0a-422f-4162-a53a-b6a19298bcb6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'myxbm6', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'20f08fb2-995f-4050-a694-256744c1888d', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/blockdevices/3/partition/5', u'type': u'partition', u'id': 5, u'device_id': 3}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'uuid': None}, u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 3, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'myxbm6', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/blockdevices/3/', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'dc880a0a-422f-4162-a53a-b6a19298bcb6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'myxbm6', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'20f08fb2-995f-4050-a694-256744c1888d', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/blockdevices/3/partition/5', u'type': u'partition', u'id': 5, u'device_id': 3}]}, {u'size': 107374182400, u'block_size': 4096, u'uuid': u'0a25bb7b-621d-4b0c-ae0d-664adb4905e0', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 10, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'6e299863-c717-41b7-a464-971afb4f4f42', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'myxbm6', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/blockdevices/10/', u'type': u'virtual', u'model': None, u'partitions': []}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'hostname': u'cmp002', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'myxbm6', 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'192.168.11.38', u'192.168.11.43'], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'myxbm6', u'id': 5}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8fsyfw', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 29}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8fsyfw', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8fsyfw', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'system_id': u'myxbm6', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/interfaces/18/'}, {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'params': u'', u'effective_mtu': 1500, u'system_id': u'myxbm6', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/interfaces/20/'}, {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'params': u'', u'effective_mtu': 1500, u'system_id': u'myxbm6', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/interfaces/23/'}, {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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8fsyfw', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 30}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8fsyfw', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8fsyfw', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'ip_address': u'192.168.11.43'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'system_id': u'myxbm6', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/interfaces/21/'}, {u'children': [], u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 31}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, 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'fabric': u'fabric-0'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'system_id': u'myxbm6', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/interfaces/22/'}, {u'children': [], u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 32}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, 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'fabric': u'fabric-0'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'system_id': u'myxbm6', u'type': u'physical', u'id': 24, u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/interfaces/24/'}], u'current_testing_result_id': 21, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/blockdevices/3/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 3, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'myxbm6', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'dc880a0a-422f-4162-a53a-b6a19298bcb6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'myxbm6', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'20f08fb2-995f-4050-a694-256744c1888d', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/blockdevices/3/partition/5', u'type': u'partition', u'id': 5, u'device_id': 3}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'uuid': None}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/myxbm6/blockdevices/10/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 10, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'6e299863-c717-41b7-a464-971afb4f4f42', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'myxbm6', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'block_size': 4096, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'uuid': u'0a25bb7b-621d-4b0c-ae0d-664adb4905e0'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 20, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/myxbm6/'}
2019-07-17 21:23:46,873 [salt.state       :300 ][INFO    ][9810] {'new': {'storage_layout': 'lvm'}}
2019-07-17 21:23:46,873 [salt.state       :1951][INFO    ][9810] Completed state [maas_machines_storage_cmp002_lvm] at time 21:23:46.873548 duration_in_ms=2181.914
2019-07-17 21:23:46,875 [salt.state       :1780][INFO    ][9810] Running state [maas_machines_storage_cmp001_lvm] at time 21:23:46.875844
2019-07-17 21:23:46,876 [salt.state       :1813][INFO    ][9810] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-17 21:23:47,831 [salt.loaded.ext.module.maasng:610 ][INFO    ][9810] dsndks
2019-07-17 21:23:47,831 [salt.loaded.ext.module.maasng:626 ][INFO    ][9810] sda
2019-07-17 21:23:48,303 [salt.loaded.ext.module.maasng:361 ][INFO    ][9810] dsndks
2019-07-17 21:23:48,398 [salt.loaded.ext.module.maasng:367 ][INFO    ][9810] [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 5, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dsndks', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/blockdevices/5/', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'820774b6-2502-4acf-9f8f-6dddd04a4437', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dsndks', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'015a1604-b30c-410e-8a20-1bc1e8033996', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/blockdevices/5/partition/3', u'type': u'partition', u'id': 3, u'device_id': 5}]}, {u'size': 800097042432, u'block_size': 4096, u'uuid': u'c39e72f9-192c-468c-aa09-1716df955430', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'id': 8, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'2a243b56-cf4b-4bcb-966a-81cb775424b2', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dsndks', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/blockdevices/8/', u'type': u'virtual', u'model': None, u'partitions': []}]
2019-07-17 21:23:48,399 [salt.loaded.ext.module.maasng:632 ][INFO    ][9810] vgroot
2019-07-17 21:23:48,399 [salt.loaded.ext.module.maasng:635 ][INFO    ][9810] lvroot
2019-07-17 21:23:48,400 [salt.loaded.ext.module.maasng:639 ][INFO    ][9810] 107374182400
2019-07-17 21:23:48,981 [salt.loaded.ext.module.maasng:645 ][INFO    ][9810] {u'hwe_kernel': u'', u'status_action': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', 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'memory_test_status_name': u'Unknown', 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'block_size': 4096, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/blockdevices/5/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dsndks', 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'size': 800109715456, u'type': u'physical', u'id': 5, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'2aeb53e2-e0ad-4a19-8230-68200e860305', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dsndks', u'device_id': 5, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'0611f5fa-ea34-47dd-b01c-22b8ca5c9c06', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/blockdevices/5/partition/6'}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/dsndks/', u'current_commissioning_result_id': 22, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'dsndks', u'power_state': u'off', 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'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/blockdevices/11/', u'used_size': 107374182400, u'tags': [], u'uuid': u'930c732c-96b3-4e8d-acbe-55c9aa8e6e4e', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dsndks', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'ab07ab72-ee26-470c-9115-d7748d2109a8', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 11, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/blockdevices/5/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dsndks', 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'size': 800109715456, u'type': u'physical', u'id': 5, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'2aeb53e2-e0ad-4a19-8230-68200e860305', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dsndks', u'device_id': 5, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'0611f5fa-ea34-47dd-b01c-22b8ca5c9c06', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/blockdevices/5/partition/6'}]}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/blockdevices/11/', u'used_size': 107374182400, u'tags': [], u'uuid': u'930c732c-96b3-4e8d-acbe-55c9aa8e6e4e', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dsndks', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'ab07ab72-ee26-470c-9115-d7748d2109a8', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 11, u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/blockdevices/5/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dsndks', 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'size': 800109715456, u'type': u'physical', u'id': 5, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'2aeb53e2-e0ad-4a19-8230-68200e860305', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dsndks', u'device_id': 5, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'0611f5fa-ea34-47dd-b01c-22b8ca5c9c06', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/blockdevices/5/partition/6'}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'dsndks', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 23, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 35}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 19, u'discovered': [{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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'dsndks', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/interfaces/19/'}, u'interface_set': [{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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 35}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 19, u'discovered': [{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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'dsndks', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/interfaces/19/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 28, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'dsndks', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/interfaces/28/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 25, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'dsndks', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/interfaces/25/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 36}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 29, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'dsndks', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/interfaces/29/'}, {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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 37}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 26, u'discovered': [{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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fsyfw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'ip_address': u'192.168.11.44'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'dsndks', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/interfaces/26/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 38}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 27, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'dsndks', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsndks/interfaces/27/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-07-17 21:23:48,984 [salt.state       :300 ][INFO    ][9810] {'new': {'storage_layout': 'lvm'}}
2019-07-17 21:23:48,985 [salt.state       :1951][INFO    ][9810] Completed state [maas_machines_storage_cmp001_lvm] at time 21:23:48.985037 duration_in_ms=2109.191
2019-07-17 21:23:48,990 [salt.minion      :1711][INFO    ][9810] Returning information for job: 20190717212336716658
2019-07-17 21:23:49,570 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command state.apply with jid 20190717212349554290
2019-07-17 21:23:49,597 [salt.minion      :1432][INFO    ][9848] Starting a new job with PID 9848
2019-07-17 21:23:50,490 [salt.state       :915 ][INFO    ][9848] Loading fresh modules for state activity
2019-07-17 21:23:50,551 [salt.fileclient  :1219][INFO    ][9848] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-17 21:23:50,598 [salt.state       :1780][INFO    ][9848] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:23:50.598672
2019-07-17 21:23:50,599 [salt.state       :1813][INFO    ][9848] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-17 21:23:50,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9848] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-17 21:23:52,361 [salt.state       :300 ][INFO    ][9848] {'pid': 9856, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-17 21:23:52,362 [salt.state       :1951][INFO    ][9848] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:23:52.362622 duration_in_ms=1763.949
2019-07-17 21:23:52,366 [salt.state       :1780][INFO    ][9848] Running state [maas.deploy_machines] at time 21:23:52.365616
2019-07-17 21:23:52,366 [salt.state       :1813][INFO    ][9848] Executing state module.run for [maas.deploy_machines]
2019-07-17 21:23:52,367 [salt.utils.decorators:613 ][WARNING ][9848] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 21:23:52,854 [salt.loaded.ext.module.maas:684 ][INFO    ][9848] deploymachines hwe_kernel=hwe-16.04 system_id=gwttft distro_series=xenial
2019-07-17 21:23:55,368 [salt.loaded.ext.module.maas:684 ][INFO    ][9848] deploymachines hwe_kernel=hwe-16.04 system_id=myxbm6 distro_series=xenial
2019-07-17 21:23:57,811 [salt.loaded.ext.module.maas:684 ][INFO    ][9848] deploymachines hwe_kernel=hwe-16.04 system_id=dsndks distro_series=xenial
2019-07-17 21:24:00,344 [salt.loaded.ext.module.maas:684 ][INFO    ][9848] deploymachines hwe_kernel=hwe-16.04 system_id=nsbqee distro_series=xenial
2019-07-17 21:24:02,951 [salt.state       :300 ][INFO    ][9848] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-07-17 21:24:02,951 [salt.state       :1951][INFO    ][9848] Completed state [maas.deploy_machines] at time 21:24:02.951582 duration_in_ms=10585.956
2019-07-17 21:24:02,959 [salt.minion      :1711][INFO    ][9848] Returning information for job: 20190717212349554290
2019-07-17 21:24:03,528 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command state.apply with jid 20190717212403514520
2019-07-17 21:24:03,557 [salt.minion      :1432][INFO    ][10073] Starting a new job with PID 10073
2019-07-17 21:24:09,583 [salt.state       :915 ][INFO    ][10073] Loading fresh modules for state activity
2019-07-17 21:24:09,646 [salt.fileclient  :1219][INFO    ][10073] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-17 21:24:09,695 [salt.state       :1780][INFO    ][10073] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:09.695887
2019-07-17 21:24:09,696 [salt.state       :1813][INFO    ][10073] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-17 21:24:09,698 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10073] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-17 21:24:11,548 [salt.state       :300 ][INFO    ][10073] {'pid': 10086, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-17 21:24:11,549 [salt.state       :1951][INFO    ][10073] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:11.549393 duration_in_ms=1853.506
2019-07-17 21:24:11,552 [salt.state       :1780][INFO    ][10073] Running state [maas.wait_for_machine_status] at time 21:24:11.552198
2019-07-17 21:24:11,552 [salt.state       :1813][INFO    ][10073] Executing state module.run for [maas.wait_for_machine_status]
2019-07-17 21:24:11,552 [salt.utils.decorators:613 ][WARNING ][10073] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-17 21:24:13,546 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2248.01473308s left)
2019-07-17 21:24:18,553 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212418539043
2019-07-17 21:24:18,579 [salt.minion      :1432][INFO    ][10119] Starting a new job with PID 10119
2019-07-17 21:24:18,604 [salt.minion      :1711][INFO    ][10119] Returning information for job: 20190717212418539043
2019-07-17 21:24:45,721 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2215.84000611s left)
2019-07-17 21:24:48,616 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212448604967
2019-07-17 21:24:48,646 [salt.minion      :1432][INFO    ][10171] Starting a new job with PID 10171
2019-07-17 21:24:48,671 [salt.minion      :1711][INFO    ][10171] Returning information for job: 20190717212448604967
2019-07-17 21:25:17,657 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2183.90361404s left)
2019-07-17 21:25:18,713 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212518696154
2019-07-17 21:25:18,742 [salt.minion      :1432][INFO    ][10199] Starting a new job with PID 10199
2019-07-17 21:25:18,775 [salt.minion      :1711][INFO    ][10199] Returning information for job: 20190717212518696154
2019-07-17 21:25:48,797 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212548787076
2019-07-17 21:25:48,816 [salt.minion      :1432][INFO    ][10244] Starting a new job with PID 10244
2019-07-17 21:25:48,844 [salt.minion      :1711][INFO    ][10244] Returning information for job: 20190717212548787076
2019-07-17 21:25:49,751 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2151.80977821s left)
2019-07-17 21:26:18,868 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212618856420
2019-07-17 21:26:18,894 [salt.minion      :1432][INFO    ][10270] Starting a new job with PID 10270
2019-07-17 21:26:18,920 [salt.minion      :1711][INFO    ][10270] Returning information for job: 20190717212618856420
2019-07-17 21:26:21,790 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2119.77081323s left)
2019-07-17 21:26:48,930 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212648915145
2019-07-17 21:26:48,953 [salt.minion      :1432][INFO    ][10335] Starting a new job with PID 10335
2019-07-17 21:26:48,982 [salt.minion      :1711][INFO    ][10335] Returning information for job: 20190717212648915145
2019-07-17 21:26:53,776 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2087.78521013s left)
2019-07-17 21:27:18,994 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212718976437
2019-07-17 21:27:19,024 [salt.minion      :1432][INFO    ][10414] Starting a new job with PID 10414
2019-07-17 21:27:19,048 [salt.minion      :1711][INFO    ][10414] Returning information for job: 20190717212718976437
2019-07-17 21:27:25,772 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2055.7891202s left)
2019-07-17 21:27:49,101 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212749079262
2019-07-17 21:27:49,132 [salt.minion      :1432][INFO    ][10587] Starting a new job with PID 10587
2019-07-17 21:27:49,157 [salt.minion      :1711][INFO    ][10587] Returning information for job: 20190717212749079262
2019-07-17 21:27:57,851 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2023.71027017s left)
2019-07-17 21:28:19,176 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212819165060
2019-07-17 21:28:19,204 [salt.minion      :1432][INFO    ][10640] Starting a new job with PID 10640
2019-07-17 21:28:19,227 [salt.minion      :1711][INFO    ][10640] Returning information for job: 20190717212819165060
2019-07-17 21:28:29,653 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1991.90813112s left)
2019-07-17 21:28:49,304 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212849291552
2019-07-17 21:28:49,327 [salt.minion      :1432][INFO    ][10965] Starting a new job with PID 10965
2019-07-17 21:28:49,352 [salt.minion      :1711][INFO    ][10965] Returning information for job: 20190717212849291552
2019-07-17 21:29:01,900 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1959.66091418s left)
2019-07-17 21:29:19,422 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212919407861
2019-07-17 21:29:19,448 [salt.minion      :1432][INFO    ][11027] Starting a new job with PID 11027
2019-07-17 21:29:19,476 [salt.minion      :1711][INFO    ][11027] Returning information for job: 20190717212919407861
2019-07-17 21:29:34,771 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1926.78939605s left)
2019-07-17 21:29:49,588 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717212949575849
2019-07-17 21:29:49,613 [salt.minion      :1432][INFO    ][11358] Starting a new job with PID 11358
2019-07-17 21:29:49,636 [salt.minion      :1711][INFO    ][11358] Returning information for job: 20190717212949575849
2019-07-17 21:30:06,828 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1894.73239112s left)
2019-07-17 21:30:19,695 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213019681022
2019-07-17 21:30:19,725 [salt.minion      :1432][INFO    ][11390] Starting a new job with PID 11390
2019-07-17 21:30:19,745 [salt.minion      :1711][INFO    ][11390] Returning information for job: 20190717213019681022
2019-07-17 21:30:38,962 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1862.59873915s left)
2019-07-17 21:30:49,879 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213049868883
2019-07-17 21:30:49,904 [salt.minion      :1432][INFO    ][11679] Starting a new job with PID 11679
2019-07-17 21:30:49,928 [salt.minion      :1711][INFO    ][11679] Returning information for job: 20190717213049868883
2019-07-17 21:31:11,156 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1830.40468311s left)
2019-07-17 21:31:20,000 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213119992884
2019-07-17 21:31:20,022 [salt.minion      :1432][INFO    ][11714] Starting a new job with PID 11714
2019-07-17 21:31:20,051 [salt.minion      :1711][INFO    ][11714] Returning information for job: 20190717213119992884
2019-07-17 21:31:43,511 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1798.04982519s left)
2019-07-17 21:31:50,191 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213150175751
2019-07-17 21:31:50,213 [salt.minion      :1432][INFO    ][12029] Starting a new job with PID 12029
2019-07-17 21:31:50,241 [salt.minion      :1711][INFO    ][12029] Returning information for job: 20190717213150175751
2019-07-17 21:32:15,487 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1766.07417607s left)
2019-07-17 21:32:20,332 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213220318459
2019-07-17 21:32:20,360 [salt.minion      :1432][INFO    ][12059] Starting a new job with PID 12059
2019-07-17 21:32:20,396 [salt.minion      :1711][INFO    ][12059] Returning information for job: 20190717213220318459
2019-07-17 21:32:47,552 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1734.00883913s left)
2019-07-17 21:32:50,527 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213250511252
2019-07-17 21:32:50,556 [salt.minion      :1432][INFO    ][12299] Starting a new job with PID 12299
2019-07-17 21:32:50,582 [salt.minion      :1711][INFO    ][12299] Returning information for job: 20190717213250511252
2019-07-17 21:33:19,524 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1702.03726912s left)
2019-07-17 21:33:20,708 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213320692773
2019-07-17 21:33:20,737 [salt.minion      :1432][INFO    ][12349] Starting a new job with PID 12349
2019-07-17 21:33:20,758 [salt.minion      :1711][INFO    ][12349] Returning information for job: 20190717213320692773
2019-07-17 21:33:50,812 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213350799133
2019-07-17 21:33:50,836 [salt.minion      :1432][INFO    ][12432] Starting a new job with PID 12432
2019-07-17 21:33:50,859 [salt.minion      :1711][INFO    ][12432] Returning information for job: 20190717213350799133
2019-07-17 21:33:51,760 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1669.80042601s left)
2019-07-17 21:34:21,005 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213420992080
2019-07-17 21:34:21,033 [salt.minion      :1432][INFO    ][12483] Starting a new job with PID 12483
2019-07-17 21:34:21,056 [salt.minion      :1711][INFO    ][12483] Returning information for job: 20190717213420992080
2019-07-17 21:34:23,724 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1637.83689904s left)
2019-07-17 21:34:51,213 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213451199312
2019-07-17 21:34:51,240 [salt.minion      :1432][INFO    ][12770] Starting a new job with PID 12770
2019-07-17 21:34:51,263 [salt.minion      :1711][INFO    ][12770] Returning information for job: 20190717213451199312
2019-07-17 21:34:55,678 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1605.88326311s left)
2019-07-17 21:35:21,389 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213521376342
2019-07-17 21:35:21,414 [salt.minion      :1432][INFO    ][12799] Starting a new job with PID 12799
2019-07-17 21:35:21,439 [salt.minion      :1711][INFO    ][12799] Returning information for job: 20190717213521376342
2019-07-17 21:35:27,660 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1573.90057111s left)
2019-07-17 21:35:51,570 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213551554253
2019-07-17 21:35:51,594 [salt.minion      :1432][INFO    ][12865] Starting a new job with PID 12865
2019-07-17 21:35:51,619 [salt.minion      :1711][INFO    ][12865] Returning information for job: 20190717213551554253
2019-07-17 21:35:59,764 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1541.79706216s left)
2019-07-17 21:36:21,592 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213621577674
2019-07-17 21:36:21,621 [salt.minion      :1432][INFO    ][12900] Starting a new job with PID 12900
2019-07-17 21:36:21,653 [salt.minion      :1711][INFO    ][12900] Returning information for job: 20190717213621577674
2019-07-17 21:36:32,472 [salt.loaded.ext.module.maas:1023][INFO    ][10073] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1509.08849406s left)
2019-07-17 21:36:51,815 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command saltutil.find_job with jid 20190717213651801960
2019-07-17 21:36:51,841 [salt.minion      :1432][INFO    ][13099] Starting a new job with PID 13099
2019-07-17 21:36:51,878 [salt.minion      :1711][INFO    ][13099] Returning information for job: 20190717213651801960
2019-07-17 21:37:04,557 [salt.state       :300 ][INFO    ][10073] {'ret': True}
2019-07-17 21:37:04,557 [salt.state       :1951][INFO    ][10073] Completed state [maas.wait_for_machine_status] at time 21:37:04.557501 duration_in_ms=773005.302
2019-07-17 21:37:04,560 [salt.minion      :1711][INFO    ][10073] Returning information for job: 20190717212403514520
2019-07-17 21:55:05,614 [salt.utils.schedule:1377][INFO    ][3926] Running scheduled job: __mine_interval
2019-07-17 22:33:38,332 [salt.minion      :1308][INFO    ][3926] User sudo_ubuntu Executing command cp.push_dir with jid 20190717223338320209
2019-07-17 22:33:38,362 [salt.minion      :1432][INFO    ][17253] Starting a new job with PID 17253
