2019-05-21 04:48:48,377 [salt.minion      :870 ][ERROR   ][382] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-21 04:50:12,667 [salt.utils.decorators:613 ][WARNING ][2115] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 04:50:13,580 [salt.utils.decorators:613 ][WARNING ][2115] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 04:50:17,271 [salt.loaded.int.states.file:2298][WARNING ][2393] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-21 04:50:32,111 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2709] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-21 04:50:32,149 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2709] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-21 04:50:32,204 [salt.utils.parsers:1051][WARNING ][382] Minion received a SIGTERM. Exiting.
2019-05-21 04:50:33,303 [salt.cli.daemons :293 ][INFO    ][2820] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-05-21 04:50:33,476 [salt.cli.daemons :82  ][INFO    ][2820] Starting up the Salt Minion
2019-05-21 04:50:33,477 [salt.utils.event :1017][INFO    ][2820] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-05-21 04:50:34,732 [salt.minion      :976 ][INFO    ][2820] Creating minion process manager
2019-05-21 04:50:36,920 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][2820] Executing command ['date', '+%z'] in directory '/root'
2019-05-21 04:50:36,956 [salt.utils.schedule:568 ][INFO    ][2820] Updating job settings for scheduled job: __mine_interval
2019-05-21 04:50:36,958 [salt.minion      :1108][INFO    ][2820] Added mine.update to scheduler
2019-05-21 04:50:36,965 [salt.minion      :1975][INFO    ][2820] Minion is starting as user 'root'
2019-05-21 04:50:36,982 [salt.minion      :2336][INFO    ][2820] Minion is ready to receive requests!
2019-05-21 04:50:42,954 [salt.state       :2022][WARNING ][2713] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-21 04:50:44,436 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045044423901
2019-05-21 04:50:44,457 [salt.minion      :1432][INFO    ][3069] Starting a new job with PID 3069
2019-05-21 04:50:44,495 [salt.minion      :1711][INFO    ][3069] Returning information for job: 20190521045044423901
2019-05-21 04:50:46,172 [salt.utils.decorators:613 ][WARNING ][2713] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 04:51:00,934 [salt.utils.decorators:613 ][WARNING ][2713] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 04:51:14,611 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045114597192
2019-05-21 04:51:14,640 [salt.minion      :1432][INFO    ][3852] Starting a new job with PID 3852
2019-05-21 04:51:14,671 [salt.minion      :1711][INFO    ][3852] Returning information for job: 20190521045114597192
2019-05-21 04:51:27,976 [salt.utils.decorators:613 ][WARNING ][2713] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 04:51:29,063 [salt.utils.decorators:613 ][WARNING ][2713] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 04:51:31,047 [salt.loaded.ext.module.maasng:1008][WARNING ][2713] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-21 04:51:31,047 [salt.loaded.ext.module.maasng:1011][WARNING ][2713] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-21 04:51:31,928 [salt.loaded.ext.module.maasng:1235][WARNING ][2713] Ignoring parameter vlan:0
2019-05-21 04:51:33,804 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command state.apply with jid 20190521045133786282
2019-05-21 04:51:33,825 [salt.minion      :1432][INFO    ][4243] Starting a new job with PID 4243
2019-05-21 04:51:42,112 [salt.state       :915 ][INFO    ][4243] Loading fresh modules for state activity
2019-05-21 04:51:42,180 [salt.fileclient  :1219][INFO    ][4243] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-05-21 04:51:42,233 [salt.state       :1780][INFO    ][4243] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:51:42.233760
2019-05-21 04:51:42,234 [salt.state       :1813][INFO    ][4243] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 04:51:42,235 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4243] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 04:51:44,164 [salt.state       :300 ][INFO    ][4243] {'pid': 4272, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 04:51:44,165 [salt.state       :1951][INFO    ][4243] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:51:44.165308 duration_in_ms=1931.549
2019-05-21 04:51:44,167 [salt.state       :1780][INFO    ][4243] Running state [maas.process_machines] at time 04:51:44.167530
2019-05-21 04:51:44,167 [salt.state       :1813][INFO    ][4243] Executing state module.run for [maas.process_machines]
2019-05-21 04:51:44,168 [salt.utils.decorators:613 ][WARNING ][4243] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 04:51:44,228 [salt.loaded.ext.module.maas:412 ][WARNING ][4243] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 04:51:44,228 [salt.loaded.ext.module.maas:92  ][INFO    ][4243] 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-05-21 04:51:45,949 [salt.loaded.ext.module.maas:412 ][WARNING ][4243] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 04:51:45,953 [salt.loaded.ext.module.maas:92  ][INFO    ][4243] 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-05-21 04:51:47,311 [salt.loaded.ext.module.maas:412 ][WARNING ][4243] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 04:51:47,312 [salt.loaded.ext.module.maas:92  ][INFO    ][4243] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 04:51:48,649 [salt.loaded.ext.module.maas:412 ][WARNING ][4243] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 04:51:48,654 [salt.loaded.ext.module.maas:92  ][INFO    ][4243] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 04:51:48,863 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045148853307
2019-05-21 04:51:48,887 [salt.minion      :1432][INFO    ][4472] Starting a new job with PID 4472
2019-05-21 04:51:48,918 [salt.minion      :1711][INFO    ][4472] Returning information for job: 20190521045148853307
2019-05-21 04:51:50,023 [salt.loaded.ext.module.maas:412 ][WARNING ][4243] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 04:51:50,023 [salt.loaded.ext.module.maas:92  ][INFO    ][4243] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 04:51:51,464 [salt.state       :300 ][INFO    ][4243] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-21 04:51:51,465 [salt.state       :1951][INFO    ][4243] Completed state [maas.process_machines] at time 04:51:51.465074 duration_in_ms=7297.543
2019-05-21 04:51:51,468 [salt.minion      :1711][INFO    ][4243] Returning information for job: 20190521045133786282
2019-05-21 04:52:22,880 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command state.apply with jid 20190521045222865966
2019-05-21 04:52:22,907 [salt.minion      :1432][INFO    ][4610] Starting a new job with PID 4610
2019-05-21 04:52:31,055 [salt.state       :915 ][INFO    ][4610] Loading fresh modules for state activity
2019-05-21 04:52:31,109 [salt.fileclient  :1219][INFO    ][4610] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-21 04:52:31,166 [salt.state       :1780][INFO    ][4610] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:52:31.166207
2019-05-21 04:52:31,166 [salt.state       :1813][INFO    ][4610] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 04:52:31,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4610] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 04:52:32,901 [salt.state       :300 ][INFO    ][4610] {'pid': 4627, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 04:52:32,902 [salt.state       :1951][INFO    ][4610] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:52:32.902632 duration_in_ms=1736.425
2019-05-21 04:52:32,907 [salt.state       :1780][INFO    ][4610] Running state [maas.wait_for_machine_status] at time 04:52:32.907033
2019-05-21 04:52:32,907 [salt.state       :1813][INFO    ][4610] Executing state module.run for [maas.wait_for_machine_status]
2019-05-21 04:52:32,908 [salt.utils.decorators:613 ][WARNING ][4610] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 04:52:33,731 [salt.loaded.ext.module.maas:1023][INFO    ][4610] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.19046092s left)
2019-05-21 04:52:37,930 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045237908528
2019-05-21 04:52:37,960 [salt.minion      :1432][INFO    ][4639] Starting a new job with PID 4639
2019-05-21 04:52:37,979 [salt.minion      :1711][INFO    ][4639] Returning information for job: 20190521045237908528
2019-05-21 04:53:04,668 [salt.loaded.ext.module.maas:1023][INFO    ][4610] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.25256491s left)
2019-05-21 04:53:08,010 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045307995975
2019-05-21 04:53:08,040 [salt.minion      :1432][INFO    ][4682] Starting a new job with PID 4682
2019-05-21 04:53:08,060 [salt.minion      :1711][INFO    ][4682] Returning information for job: 20190521045307995975
2019-05-21 04:53:35,489 [salt.loaded.ext.module.maas:1023][INFO    ][4610] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.43192291s left)
2019-05-21 04:53:38,115 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045338098496
2019-05-21 04:53:38,144 [salt.minion      :1432][INFO    ][4710] Starting a new job with PID 4710
2019-05-21 04:53:38,166 [salt.minion      :1711][INFO    ][4710] Returning information for job: 20190521045338098496
2019-05-21 04:54:06,366 [salt.loaded.ext.module.maas:1023][INFO    ][4610] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.55507493s left)
2019-05-21 04:54:08,204 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045408190394
2019-05-21 04:54:08,237 [salt.minion      :1432][INFO    ][4755] Starting a new job with PID 4755
2019-05-21 04:54:08,260 [salt.minion      :1711][INFO    ][4755] Returning information for job: 20190521045408190394
2019-05-21 04:54:37,422 [salt.loaded.ext.module.maas:1023][INFO    ][4610] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.49924994s left)
2019-05-21 04:54:38,320 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045438303666
2019-05-21 04:54:38,347 [salt.minion      :1432][INFO    ][4815] Starting a new job with PID 4815
2019-05-21 04:54:38,377 [salt.minion      :1711][INFO    ][4815] Returning information for job: 20190521045438303666
2019-05-21 04:55:08,431 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045508415422
2019-05-21 04:55:08,458 [salt.minion      :1432][INFO    ][4976] Starting a new job with PID 4976
2019-05-21 04:55:08,485 [salt.minion      :1711][INFO    ][4976] Returning information for job: 20190521045508415422
2019-05-21 04:55:08,635 [salt.loaded.ext.module.maas:1023][INFO    ][4610] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.28597999s left)
2019-05-21 04:55:38,561 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045538547304
2019-05-21 04:55:38,589 [salt.minion      :1432][INFO    ][5086] Starting a new job with PID 5086
2019-05-21 04:55:38,611 [salt.minion      :1711][INFO    ][5086] Returning information for job: 20190521045538547304
2019-05-21 04:55:39,954 [salt.loaded.ext.module.maas:1023][INFO    ][4610] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.96728611s left)
2019-05-21 04:56:08,699 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045608688605
2019-05-21 04:56:08,713 [salt.minion      :1432][INFO    ][5371] Starting a new job with PID 5371
2019-05-21 04:56:08,734 [salt.minion      :1711][INFO    ][5371] Returning information for job: 20190521045608688605
2019-05-21 04:56:11,286 [salt.loaded.ext.module.maas:1023][INFO    ][4610] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.6348269s left)
2019-05-21 04:56:38,813 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045638792777
2019-05-21 04:56:38,843 [salt.minion      :1432][INFO    ][5527] Starting a new job with PID 5527
2019-05-21 04:56:38,863 [salt.minion      :1711][INFO    ][5527] Returning information for job: 20190521045638792777
2019-05-21 04:56:43,409 [salt.loaded.ext.module.maas:1023][INFO    ][4610] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.51203108s left)
2019-05-21 04:57:08,976 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045708962698
2019-05-21 04:57:09,006 [salt.minion      :1432][INFO    ][5999] Starting a new job with PID 5999
2019-05-21 04:57:09,030 [salt.minion      :1711][INFO    ][5999] Returning information for job: 20190521045708962698
2019-05-21 04:57:15,708 [salt.loaded.ext.module.maas:1023][INFO    ][4610] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.21335411s left)
2019-05-21 04:57:39,094 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045739078310
2019-05-21 04:57:39,120 [salt.minion      :1432][INFO    ][6041] Starting a new job with PID 6041
2019-05-21 04:57:39,140 [salt.minion      :1711][INFO    ][6041] Returning information for job: 20190521045739078310
2019-05-21 04:57:48,761 [salt.loaded.ext.module.maas:1023][INFO    ][4610] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.16043806s left)
2019-05-21 04:58:09,232 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045809218760
2019-05-21 04:58:09,260 [salt.minion      :1432][INFO    ][6291] Starting a new job with PID 6291
2019-05-21 04:58:09,282 [salt.minion      :1711][INFO    ][6291] Returning information for job: 20190521045809218760
2019-05-21 04:58:21,744 [salt.state       :300 ][INFO    ][4610] {'ret': True}
2019-05-21 04:58:21,745 [salt.state       :1951][INFO    ][4610] Completed state [maas.wait_for_machine_status] at time 04:58:21.745542 duration_in_ms=348838.508
2019-05-21 04:58:21,750 [salt.minion      :1711][INFO    ][4610] Returning information for job: 20190521045222865966
2019-05-21 04:58:22,305 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command state.apply with jid 20190521045822294217
2019-05-21 04:58:22,326 [salt.minion      :1432][INFO    ][6319] Starting a new job with PID 6319
2019-05-21 04:58:30,665 [salt.state       :915 ][INFO    ][6319] Loading fresh modules for state activity
2019-05-21 04:58:30,726 [salt.fileclient  :1219][INFO    ][6319] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-21 04:58:30,835 [salt.state       :1780][INFO    ][6319] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:58:30.835632
2019-05-21 04:58:30,835 [salt.state       :1813][INFO    ][6319] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 04:58:30,838 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6319] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 04:58:32,554 [salt.state       :300 ][INFO    ][6319] {'pid': 6355, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 04:58:32,555 [salt.state       :1951][INFO    ][6319] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:58:32.555191 duration_in_ms=1719.558
2019-05-21 04:58:32,559 [salt.state       :1780][INFO    ][6319] Running state [maas_machines_storage_cmp002_lvm] at time 04:58:32.559820
2019-05-21 04:58:32,560 [salt.state       :1813][INFO    ][6319] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-21 04:58:33,846 [salt.loaded.ext.module.maasng:610 ][INFO    ][6319] n4mq4a
2019-05-21 04:58:33,846 [salt.loaded.ext.module.maasng:626 ][INFO    ][6319] sda
2019-05-21 04:58:34,463 [salt.loaded.ext.module.maasng:361 ][INFO    ][6319] n4mq4a
2019-05-21 04:58:34,570 [salt.loaded.ext.module.maasng:367 ][INFO    ][6319] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'n4mq4a', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/blockdevices/1/', u'partitions': [{u'uuid': u'9ede5e20-1f08-4545-b3db-6f25e0daa13b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n4mq4a', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'89afd780-c980-4fcb-9920-2af407eaec61', 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': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/blockdevices/1/partition/1'}]}, {u'size': 800097042432, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'id': 3, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'ec25061a-3f6e-40be-9734-95526483653b', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'6b255d37-cea2-46bf-8fa4-55ad11d34655', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'n4mq4a', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/blockdevices/3/', u'partitions': []}]
2019-05-21 04:58:34,571 [salt.loaded.ext.module.maasng:632 ][INFO    ][6319] vgroot
2019-05-21 04:58:34,571 [salt.loaded.ext.module.maasng:635 ][INFO    ][6319] lvroot
2019-05-21 04:58:34,571 [salt.loaded.ext.module.maasng:639 ][INFO    ][6319] 107374182400
2019-05-21 04:58:35,189 [salt.loaded.ext.module.maasng:645 ][INFO    ][6319] {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'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qmqkfd', 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.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qmqkfd', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'children': [], 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qmqkfd', 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.38'}], u'system_id': u'n4mq4a', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/interfaces/4/'}, u'min_hwe_kernel': u'ga-16.04', 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'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'341a4bd3-32f3-40a6-9c10-2fe423ef2b3b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n4mq4a', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'1d38f7bc-571d-4054-881f-fbfdecb2095b', 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/n4mq4a/blockdevices/1/partition/6'}], u'filesystem': None, u'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'n4mq4a', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/blockdevices/1/', u'size': 800109715456}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/n4mq4a/', u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'n4mq4a', 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'disable_ipv4': False, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'n4mq4a', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/blockdevices/1/', u'partitions': [{u'uuid': u'341a4bd3-32f3-40a6-9c10-2fe423ef2b3b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n4mq4a', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'1d38f7bc-571d-4054-881f-fbfdecb2095b', 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/n4mq4a/blockdevices/1/partition/6'}]}, {u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 11, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'1d66b40a-8e1c-468a-a7e4-653ce8e7340f', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'865263ef-9afd-4ada-b006-25166ab342c4', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'n4mq4a', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/blockdevices/11/', u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'341a4bd3-32f3-40a6-9c10-2fe423ef2b3b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n4mq4a', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'1d38f7bc-571d-4054-881f-fbfdecb2095b', 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/n4mq4a/blockdevices/1/partition/6'}], u'filesystem': None, u'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'n4mq4a', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/blockdevices/1/', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'n4mq4a', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'1d66b40a-8e1c-468a-a7e4-653ce8e7340f', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'865263ef-9afd-4ada-b006-25166ab342c4', u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'n4mq4a', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/blockdevices/11/', u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qmqkfd', 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.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qmqkfd', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'children': [], 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qmqkfd', 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.38'}], u'system_id': u'n4mq4a', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'n4mq4a', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/interfaces/14/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'n4mq4a', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'n4mq4a', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/interfaces/11/'}, {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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qmqkfd', 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': 20}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qmqkfd', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'children': [], 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qmqkfd', 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.40'}], u'system_id': u'n4mq4a', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/interfaces/12/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'n4mq4a', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/n4mq4a/interfaces/13/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-05-21 04:58:35,191 [salt.state       :300 ][INFO    ][6319] {'new': {'storage_layout': 'lvm'}}
2019-05-21 04:58:35,191 [salt.state       :1951][INFO    ][6319] Completed state [maas_machines_storage_cmp002_lvm] at time 04:58:35.191762 duration_in_ms=2631.939
2019-05-21 04:58:35,192 [salt.state       :1780][INFO    ][6319] Running state [maas_machines_storage_cmp001_lvm] at time 04:58:35.192432
2019-05-21 04:58:35,192 [salt.state       :1813][INFO    ][6319] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-21 04:58:36,317 [salt.loaded.ext.module.maasng:610 ][INFO    ][6319] xabcwd
2019-05-21 04:58:36,318 [salt.loaded.ext.module.maasng:626 ][INFO    ][6319] sda
2019-05-21 04:58:36,888 [salt.loaded.ext.module.maasng:361 ][INFO    ][6319] xabcwd
2019-05-21 04:58:36,999 [salt.loaded.ext.module.maasng:367 ][INFO    ][6319] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'000ca8d2-2cdb-4c73-8856-cf1141244d2f', u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xabcwd', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3ca594e0-5836-4eeb-bb48-35af489a3d94', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xabcwd', 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'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/blockdevices/2/'}, {u'size': 800097042432, u'model': None, u'available_size': 0, u'uuid': u'96143d45-42e4-4a75-8a38-3fc8499c1f3d', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xabcwd', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'6de16890-f190-4fd5-8403-fe03003b7497', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/blockdevices/4/'}]
2019-05-21 04:58:36,999 [salt.loaded.ext.module.maasng:632 ][INFO    ][6319] vgroot
2019-05-21 04:58:37,000 [salt.loaded.ext.module.maasng:635 ][INFO    ][6319] lvroot
2019-05-21 04:58:37,000 [salt.loaded.ext.module.maasng:639 ][INFO    ][6319] 107374182400
2019-05-21 04:58:37,415 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045837400346
2019-05-21 04:58:37,447 [salt.minion      :1432][INFO    ][6372] Starting a new job with PID 6372
2019-05-21 04:58:37,472 [salt.minion      :1711][INFO    ][6372] Returning information for job: 20190521045837400346
2019-05-21 04:58:37,633 [salt.loaded.ext.module.maasng:645 ][INFO    ][6319] {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'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'node_type': 0, 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xabcwd', 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/xabcwd/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'c467a60a-23fe-486c-bbf2-2bbf8815c63c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xabcwd', u'filesystem': {u'uuid': u'f69ea8ae-32e8-4502-9f0a-a65f18aab963', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xabcwd', 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/xabcwd/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'c467a60a-23fe-486c-bbf2-2bbf8815c63c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xabcwd', u'filesystem': {u'uuid': u'f69ea8ae-32e8-4502-9f0a-a65f18aab963', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'a480f714-cc14-4278-826b-ea466f68ffd5', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'uuid': u'35c21758-db63-4173-8798-7cfcd40c9c9e', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xabcwd', 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/xabcwd/blockdevices/12/', u'type': u'virtual', u'id': 12, u'partitions': []}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'xabcwd', 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'owner_data': {}, u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xabcwd', 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/xabcwd/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'c467a60a-23fe-486c-bbf2-2bbf8815c63c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xabcwd', u'filesystem': {u'uuid': u'f69ea8ae-32e8-4502-9f0a-a65f18aab963', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xabcwd', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'a480f714-cc14-4278-826b-ea466f68ffd5', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'uuid': u'35c21758-db63-4173-8798-7cfcd40c9c9e', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xabcwd', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/blockdevices/12/', u'type': u'virtual', u'id': 12, u'partitions': []}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'min_hwe_kernel': u'ga-16.04', u'boot_interface': {u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmqkfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmqkfd', u'relay_vlan': None, u'external_dhcp': None, 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'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmqkfd', u'relay_vlan': None, u'external_dhcp': None, 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'parents': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'system_id': u'xabcwd', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/interfaces/5/'}, u'interface_set': [{u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmqkfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmqkfd', u'relay_vlan': None, u'external_dhcp': None, 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'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmqkfd', u'relay_vlan': None, u'external_dhcp': None, 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'parents': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'system_id': u'xabcwd', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'system_id': u'xabcwd', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/interfaces/18/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'system_id': u'xabcwd', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/interfaces/19/'}, {u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'system_id': u'xabcwd', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/interfaces/16/'}, {u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmqkfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmqkfd', u'relay_vlan': None, u'external_dhcp': None, 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'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmqkfd', u'relay_vlan': None, u'external_dhcp': None, 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.41'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'system_id': u'xabcwd', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/interfaces/17/'}, {u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'system_id': u'xabcwd', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/xabcwd/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/xabcwd/'}
2019-05-21 04:58:37,637 [salt.state       :300 ][INFO    ][6319] {'new': {'storage_layout': 'lvm'}}
2019-05-21 04:58:37,637 [salt.state       :1951][INFO    ][6319] Completed state [maas_machines_storage_cmp001_lvm] at time 04:58:37.637333 duration_in_ms=2444.9
2019-05-21 04:58:37,641 [salt.minion      :1711][INFO    ][6319] Returning information for job: 20190521045822294217
2019-05-21 04:58:38,205 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command state.apply with jid 20190521045838192238
2019-05-21 04:58:38,233 [salt.minion      :1432][INFO    ][6379] Starting a new job with PID 6379
2019-05-21 04:58:39,430 [salt.state       :915 ][INFO    ][6379] Loading fresh modules for state activity
2019-05-21 04:58:39,498 [salt.fileclient  :1219][INFO    ][6379] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-21 04:58:39,547 [salt.state       :1780][INFO    ][6379] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:58:39.547690
2019-05-21 04:58:39,548 [salt.state       :1813][INFO    ][6379] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 04:58:39,549 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6379] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 04:58:41,278 [salt.state       :300 ][INFO    ][6379] {'pid': 6386, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 04:58:41,280 [salt.state       :1951][INFO    ][6379] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:58:41.279946 duration_in_ms=1732.256
2019-05-21 04:58:41,283 [salt.state       :1780][INFO    ][6379] Running state [maas.deploy_machines] at time 04:58:41.283393
2019-05-21 04:58:41,283 [salt.state       :1813][INFO    ][6379] Executing state module.run for [maas.deploy_machines]
2019-05-21 04:58:41,284 [salt.utils.decorators:613 ][WARNING ][6379] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 04:58:41,878 [salt.loaded.ext.module.maas:684 ][INFO    ][6379] deploymachines hwe_kernel=ga-16.04 system_id=n4mq4a distro_series=xenial
2019-05-21 04:58:44,372 [salt.loaded.ext.module.maas:684 ][INFO    ][6379] deploymachines hwe_kernel=ga-16.04 system_id=xabcwd distro_series=xenial
2019-05-21 04:58:47,238 [salt.loaded.ext.module.maas:684 ][INFO    ][6379] deploymachines hwe_kernel=ga-16.04 system_id=6pmqbr distro_series=xenial
2019-05-21 04:58:50,086 [salt.loaded.ext.module.maas:684 ][INFO    ][6379] deploymachines hwe_kernel=ga-16.04 system_id=dypc66 distro_series=xenial
2019-05-21 04:58:52,654 [salt.loaded.ext.module.maas:684 ][INFO    ][6379] deploymachines hwe_kernel=ga-16.04 system_id=7btd7h distro_series=xenial
2019-05-21 04:58:53,296 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045853286331
2019-05-21 04:58:53,319 [salt.minion      :1432][INFO    ][6670] Starting a new job with PID 6670
2019-05-21 04:58:53,344 [salt.minion      :1711][INFO    ][6670] Returning information for job: 20190521045853286331
2019-05-21 04:58:55,435 [salt.state       :300 ][INFO    ][6379] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-21 04:58:55,435 [salt.state       :1951][INFO    ][6379] Completed state [maas.deploy_machines] at time 04:58:55.435891 duration_in_ms=14152.497
2019-05-21 04:58:55,440 [salt.minion      :1711][INFO    ][6379] Returning information for job: 20190521045838192238
2019-05-21 04:58:56,008 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command state.apply with jid 20190521045855995630
2019-05-21 04:58:56,032 [salt.minion      :1432][INFO    ][6728] Starting a new job with PID 6728
2019-05-21 04:59:04,230 [salt.state       :915 ][INFO    ][6728] Loading fresh modules for state activity
2019-05-21 04:59:04,288 [salt.fileclient  :1219][INFO    ][6728] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-21 04:59:04,337 [salt.state       :1780][INFO    ][6728] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:59:04.337827
2019-05-21 04:59:04,338 [salt.state       :1813][INFO    ][6728] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 04:59:04,339 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6728] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 04:59:06,099 [salt.state       :300 ][INFO    ][6728] {'pid': 6743, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 04:59:06,100 [salt.state       :1951][INFO    ][6728] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:59:06.100034 duration_in_ms=1762.207
2019-05-21 04:59:06,102 [salt.state       :1780][INFO    ][6728] Running state [maas.wait_for_machine_status] at time 04:59:06.102168
2019-05-21 04:59:06,102 [salt.state       :1813][INFO    ][6728] Executing state module.run for [maas.wait_for_machine_status]
2019-05-21 04:59:06,102 [salt.utils.decorators:613 ][WARNING ][6728] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 04:59:09,140 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.97097015s left)
2019-05-21 04:59:11,091 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045911080946
2019-05-21 04:59:11,116 [salt.minion      :1432][INFO    ][6757] Starting a new job with PID 6757
2019-05-21 04:59:11,137 [salt.minion      :1711][INFO    ][6757] Returning information for job: 20190521045911080946
2019-05-21 04:59:41,152 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521045941139579
2019-05-21 04:59:41,173 [salt.minion      :1432][INFO    ][6798] Starting a new job with PID 6798
2019-05-21 04:59:41,197 [salt.minion      :1711][INFO    ][6798] Returning information for job: 20190521045941139579
2019-05-21 04:59:42,052 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.05903316s left)
2019-05-21 05:00:11,260 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050011242994
2019-05-21 05:00:11,290 [salt.minion      :1432][INFO    ][6841] Starting a new job with PID 6841
2019-05-21 05:00:11,310 [salt.minion      :1711][INFO    ][6841] Returning information for job: 20190521050011242994
2019-05-21 05:00:14,926 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.18466711s left)
2019-05-21 05:00:41,335 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050041319730
2019-05-21 05:00:41,364 [salt.minion      :1432][INFO    ][6880] Starting a new job with PID 6880
2019-05-21 05:00:41,387 [salt.minion      :1711][INFO    ][6880] Returning information for job: 20190521050041319730
2019-05-21 05:00:47,940 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.17116308s left)
2019-05-21 05:01:11,418 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050111402864
2019-05-21 05:01:11,443 [salt.minion      :1432][INFO    ][6936] Starting a new job with PID 6936
2019-05-21 05:01:11,464 [salt.minion      :1711][INFO    ][6936] Returning information for job: 20190521050111402864
2019-05-21 05:01:20,825 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.2859261s left)
2019-05-21 05:01:41,478 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050141461360
2019-05-21 05:01:41,496 [salt.minion      :1432][INFO    ][7024] Starting a new job with PID 7024
2019-05-21 05:01:41,527 [salt.minion      :1711][INFO    ][7024] Returning information for job: 20190521050141461360
2019-05-21 05:01:54,060 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.051229s left)
2019-05-21 05:02:11,598 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050211581560
2019-05-21 05:02:11,626 [salt.minion      :1432][INFO    ][7189] Starting a new job with PID 7189
2019-05-21 05:02:11,646 [salt.minion      :1711][INFO    ][7189] Returning information for job: 20190521050211581560
2019-05-21 05:02:27,070 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.04052806s left)
2019-05-21 05:02:41,694 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050241686164
2019-05-21 05:02:41,719 [salt.minion      :1432][INFO    ][7221] Starting a new job with PID 7221
2019-05-21 05:02:41,740 [salt.minion      :1711][INFO    ][7221] Returning information for job: 20190521050241686164
2019-05-21 05:03:00,079 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.03144312s left)
2019-05-21 05:03:11,777 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050311760722
2019-05-21 05:03:11,807 [salt.minion      :1432][INFO    ][7523] Starting a new job with PID 7523
2019-05-21 05:03:11,833 [salt.minion      :1711][INFO    ][7523] Returning information for job: 20190521050311760722
2019-05-21 05:03:33,324 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.78624415s left)
2019-05-21 05:03:41,866 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050341847409
2019-05-21 05:03:41,893 [salt.minion      :1432][INFO    ][7652] Starting a new job with PID 7652
2019-05-21 05:03:41,917 [salt.minion      :1711][INFO    ][7652] Returning information for job: 20190521050341847409
2019-05-21 05:04:06,329 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.78159308s left)
2019-05-21 05:04:12,060 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050412040025
2019-05-21 05:04:12,094 [salt.minion      :1432][INFO    ][7998] Starting a new job with PID 7998
2019-05-21 05:04:12,117 [salt.minion      :1711][INFO    ][7998] Returning information for job: 20190521050412040025
2019-05-21 05:04:39,415 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.69614005s left)
2019-05-21 05:04:42,202 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050442186642
2019-05-21 05:04:42,235 [salt.minion      :1432][INFO    ][8037] Starting a new job with PID 8037
2019-05-21 05:04:42,266 [salt.minion      :1711][INFO    ][8037] Returning information for job: 20190521050442186642
2019-05-21 05:05:12,405 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050512390196
2019-05-21 05:05:12,426 [salt.minion      :1432][INFO    ][8431] Starting a new job with PID 8431
2019-05-21 05:05:12,430 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.68085694s left)
2019-05-21 05:05:12,459 [salt.minion      :1711][INFO    ][8431] Returning information for job: 20190521050512390196
2019-05-21 05:05:42,550 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050542542690
2019-05-21 05:05:42,573 [salt.minion      :1432][INFO    ][8477] Starting a new job with PID 8477
2019-05-21 05:05:42,596 [salt.minion      :1711][INFO    ][8477] Returning information for job: 20190521050542542690
2019-05-21 05:05:45,460 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.65079117s left)
2019-05-21 05:06:12,749 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050612731774
2019-05-21 05:06:12,772 [salt.minion      :1432][INFO    ][8841] Starting a new job with PID 8841
2019-05-21 05:06:12,795 [salt.minion      :1711][INFO    ][8841] Returning information for job: 20190521050612731774
2019-05-21 05:06:18,601 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.51008105s left)
2019-05-21 05:06:42,907 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050642893183
2019-05-21 05:06:42,935 [salt.minion      :1432][INFO    ][8879] Starting a new job with PID 8879
2019-05-21 05:06:42,958 [salt.minion      :1711][INFO    ][8879] Returning information for job: 20190521050642893183
2019-05-21 05:06:52,171 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.93961906s left)
2019-05-21 05:07:13,062 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050713050954
2019-05-21 05:07:13,090 [salt.minion      :1432][INFO    ][9088] Starting a new job with PID 9088
2019-05-21 05:07:13,113 [salt.minion      :1711][INFO    ][9088] Returning information for job: 20190521050713050954
2019-05-21 05:07:25,208 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.90253615s left)
2019-05-21 05:07:43,229 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050743209546
2019-05-21 05:07:43,258 [salt.minion      :1432][INFO    ][9141] Starting a new job with PID 9141
2019-05-21 05:07:43,280 [salt.minion      :1711][INFO    ][9141] Returning information for job: 20190521050743209546
2019-05-21 05:07:58,139 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.97174907s left)
2019-05-21 05:08:13,351 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050813340931
2019-05-21 05:08:13,374 [salt.minion      :1432][INFO    ][9283] Starting a new job with PID 9283
2019-05-21 05:08:13,397 [salt.minion      :1711][INFO    ][9283] Returning information for job: 20190521050813340931
2019-05-21 05:08:31,140 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.97122312s left)
2019-05-21 05:08:43,508 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050843495943
2019-05-21 05:08:43,536 [salt.minion      :1432][INFO    ][9333] Starting a new job with PID 9333
2019-05-21 05:08:43,566 [salt.minion      :1711][INFO    ][9333] Returning information for job: 20190521050843495943
2019-05-21 05:09:03,967 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.14360714s left)
2019-05-21 05:09:13,713 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050913697416
2019-05-21 05:09:13,744 [salt.minion      :1432][INFO    ][9560] Starting a new job with PID 9560
2019-05-21 05:09:13,767 [salt.minion      :1711][INFO    ][9560] Returning information for job: 20190521050913697416
2019-05-21 05:09:36,821 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.29004717s left)
2019-05-21 05:09:43,884 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521050943867240
2019-05-21 05:09:43,917 [salt.minion      :1432][INFO    ][9589] Starting a new job with PID 9589
2019-05-21 05:09:43,944 [salt.minion      :1711][INFO    ][9589] Returning information for job: 20190521050943867240
2019-05-21 05:10:09,766 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1586.34491396s left)
2019-05-21 05:10:14,085 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051014071516
2019-05-21 05:10:14,111 [salt.minion      :1432][INFO    ][9639] Starting a new job with PID 9639
2019-05-21 05:10:14,136 [salt.minion      :1711][INFO    ][9639] Returning information for job: 20190521051014071516
2019-05-21 05:10:42,896 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1553.21497202s left)
2019-05-21 05:10:44,289 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051044271872
2019-05-21 05:10:44,321 [salt.minion      :1432][INFO    ][9805] Starting a new job with PID 9805
2019-05-21 05:10:44,357 [salt.minion      :1711][INFO    ][9805] Returning information for job: 20190521051044271872
2019-05-21 05:11:14,435 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051114425703
2019-05-21 05:11:14,454 [salt.minion      :1432][INFO    ][9922] Starting a new job with PID 9922
2019-05-21 05:11:14,480 [salt.minion      :1711][INFO    ][9922] Returning information for job: 20190521051114425703
2019-05-21 05:11:15,797 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1520.31367612s left)
2019-05-21 05:11:44,579 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051144566749
2019-05-21 05:11:44,609 [salt.minion      :1432][INFO    ][9984] Starting a new job with PID 9984
2019-05-21 05:11:44,634 [salt.minion      :1711][INFO    ][9984] Returning information for job: 20190521051144566749
2019-05-21 05:11:49,893 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1486.21755815s left)
2019-05-21 05:12:14,622 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051214603890
2019-05-21 05:12:14,646 [salt.minion      :1432][INFO    ][10227] Starting a new job with PID 10227
2019-05-21 05:12:14,667 [salt.minion      :1711][INFO    ][10227] Returning information for job: 20190521051214603890
2019-05-21 05:12:22,909 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.20175195s left)
2019-05-21 05:12:44,836 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051244820981
2019-05-21 05:12:44,865 [salt.minion      :1432][INFO    ][10260] Starting a new job with PID 10260
2019-05-21 05:12:44,886 [salt.minion      :1711][INFO    ][10260] Returning information for job: 20190521051244820981
2019-05-21 05:12:55,919 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.191782s left)
2019-05-21 05:13:14,875 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051314858569
2019-05-21 05:13:14,903 [salt.minion      :1432][INFO    ][10305] Starting a new job with PID 10305
2019-05-21 05:13:14,925 [salt.minion      :1711][INFO    ][10305] Returning information for job: 20190521051314858569
2019-05-21 05:13:28,804 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.30687404s left)
2019-05-21 05:13:44,905 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051344896708
2019-05-21 05:13:44,932 [salt.minion      :1432][INFO    ][10334] Starting a new job with PID 10334
2019-05-21 05:13:44,952 [salt.minion      :1711][INFO    ][10334] Returning information for job: 20190521051344896708
2019-05-21 05:14:01,714 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1354.39704514s left)
2019-05-21 05:14:14,951 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051414936378
2019-05-21 05:14:14,980 [salt.minion      :1432][INFO    ][10390] Starting a new job with PID 10390
2019-05-21 05:14:15,005 [salt.minion      :1711][INFO    ][10390] Returning information for job: 20190521051414936378
2019-05-21 05:14:34,616 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1321.49523807s left)
2019-05-21 05:14:45,021 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051445001103
2019-05-21 05:14:45,054 [salt.minion      :1432][INFO    ][10421] Starting a new job with PID 10421
2019-05-21 05:14:45,077 [salt.minion      :1711][INFO    ][10421] Returning information for job: 20190521051445001103
2019-05-21 05:15:07,508 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1288.60229897s left)
2019-05-21 05:15:15,077 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051515068197
2019-05-21 05:15:15,107 [salt.minion      :1432][INFO    ][10471] Starting a new job with PID 10471
2019-05-21 05:15:15,131 [salt.minion      :1711][INFO    ][10471] Returning information for job: 20190521051515068197
2019-05-21 05:15:40,444 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1255.66647315s left)
2019-05-21 05:15:45,183 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051545167123
2019-05-21 05:15:45,214 [salt.minion      :1432][INFO    ][10500] Starting a new job with PID 10500
2019-05-21 05:15:45,236 [salt.minion      :1711][INFO    ][10500] Returning information for job: 20190521051545167123
2019-05-21 05:16:13,176 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1222.93451905s left)
2019-05-21 05:16:15,260 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051615249016
2019-05-21 05:16:15,285 [salt.minion      :1432][INFO    ][10557] Starting a new job with PID 10557
2019-05-21 05:16:15,309 [salt.minion      :1711][INFO    ][10557] Returning information for job: 20190521051615249016
2019-05-21 05:16:45,369 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051645356410
2019-05-21 05:16:45,402 [salt.minion      :1432][INFO    ][10588] Starting a new job with PID 10588
2019-05-21 05:16:45,426 [salt.minion      :1711][INFO    ][10588] Returning information for job: 20190521051645356410
2019-05-21 05:16:45,906 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.20484805s left)
2019-05-21 05:17:15,499 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051715485149
2019-05-21 05:17:15,527 [salt.minion      :1432][INFO    ][10635] Starting a new job with PID 10635
2019-05-21 05:17:15,550 [salt.minion      :1711][INFO    ][10635] Returning information for job: 20190521051715485149
2019-05-21 05:17:19,072 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.03834605s left)
2019-05-21 05:17:45,644 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051745635339
2019-05-21 05:17:45,671 [salt.minion      :1432][INFO    ][10664] Starting a new job with PID 10664
2019-05-21 05:17:45,700 [salt.minion      :1711][INFO    ][10664] Returning information for job: 20190521051745635339
2019-05-21 05:17:51,800 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.31060314s left)
2019-05-21 05:18:15,823 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051815805475
2019-05-21 05:18:15,857 [salt.minion      :1432][INFO    ][10715] Starting a new job with PID 10715
2019-05-21 05:18:15,881 [salt.minion      :1711][INFO    ][10715] Returning information for job: 20190521051815805475
2019-05-21 05:18:24,664 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.44703412s left)
2019-05-21 05:18:46,000 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051845982943
2019-05-21 05:18:46,025 [salt.minion      :1432][INFO    ][10744] Starting a new job with PID 10744
2019-05-21 05:18:46,048 [salt.minion      :1711][INFO    ][10744] Returning information for job: 20190521051845982943
2019-05-21 05:18:57,486 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1058.625489s left)
2019-05-21 05:19:16,187 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051916176396
2019-05-21 05:19:16,209 [salt.minion      :1432][INFO    ][10793] Starting a new job with PID 10793
2019-05-21 05:19:16,234 [salt.minion      :1711][INFO    ][10793] Returning information for job: 20190521051916176396
2019-05-21 05:19:30,305 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1025.80586815s left)
2019-05-21 05:19:46,375 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521051946361140
2019-05-21 05:19:46,404 [salt.minion      :1432][INFO    ][10827] Starting a new job with PID 10827
2019-05-21 05:19:46,427 [salt.minion      :1711][INFO    ][10827] Returning information for job: 20190521051946361140
2019-05-21 05:20:03,317 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (992.794039011s left)
2019-05-21 05:20:16,585 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052016570706
2019-05-21 05:20:16,611 [salt.minion      :1432][INFO    ][10877] Starting a new job with PID 10877
2019-05-21 05:20:16,639 [salt.minion      :1711][INFO    ][10877] Returning information for job: 20190521052016570706
2019-05-21 05:20:36,233 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (959.877927065s left)
2019-05-21 05:20:46,786 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052046776296
2019-05-21 05:20:46,811 [salt.minion      :1432][INFO    ][10933] Starting a new job with PID 10933
2019-05-21 05:20:46,840 [salt.minion      :1711][INFO    ][10933] Returning information for job: 20190521052046776296
2019-05-21 05:21:09,003 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.107800007s left)
2019-05-21 05:21:16,808 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052116792750
2019-05-21 05:21:16,834 [salt.minion      :1432][INFO    ][10981] Starting a new job with PID 10981
2019-05-21 05:21:16,861 [salt.minion      :1711][INFO    ][10981] Returning information for job: 20190521052116792750
2019-05-21 05:21:41,693 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (894.418095112s left)
2019-05-21 05:21:46,844 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052146831593
2019-05-21 05:21:46,871 [salt.minion      :1432][INFO    ][11028] Starting a new job with PID 11028
2019-05-21 05:21:46,893 [salt.minion      :1711][INFO    ][11028] Returning information for job: 20190521052146831593
2019-05-21 05:22:14,611 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (861.499851942s left)
2019-05-21 05:22:16,925 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052216909037
2019-05-21 05:22:16,955 [salt.minion      :1432][INFO    ][11062] Starting a new job with PID 11062
2019-05-21 05:22:16,978 [salt.minion      :1711][INFO    ][11062] Returning information for job: 20190521052216909037
2019-05-21 05:22:46,966 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052246957290
2019-05-21 05:22:46,988 [salt.minion      :1432][INFO    ][11106] Starting a new job with PID 11106
2019-05-21 05:22:47,012 [salt.minion      :1711][INFO    ][11106] Returning information for job: 20190521052246957290
2019-05-21 05:22:47,627 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (828.483947992s left)
2019-05-21 05:23:17,047 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052317032081
2019-05-21 05:23:17,075 [salt.minion      :1432][INFO    ][11136] Starting a new job with PID 11136
2019-05-21 05:23:17,097 [salt.minion      :1711][INFO    ][11136] Returning information for job: 20190521052317032081
2019-05-21 05:23:20,495 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (795.61611414s left)
2019-05-21 05:23:47,153 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052347136798
2019-05-21 05:23:47,178 [salt.minion      :1432][INFO    ][11178] Starting a new job with PID 11178
2019-05-21 05:23:47,202 [salt.minion      :1711][INFO    ][11178] Returning information for job: 20190521052347136798
2019-05-21 05:23:53,219 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (762.89223814s left)
2019-05-21 05:24:17,289 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052417270712
2019-05-21 05:24:17,322 [salt.minion      :1432][INFO    ][11215] Starting a new job with PID 11215
2019-05-21 05:24:17,355 [salt.minion      :1711][INFO    ][11215] Returning information for job: 20190521052417270712
2019-05-21 05:24:26,118 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (729.992341995s left)
2019-05-21 05:24:47,399 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052447387795
2019-05-21 05:24:47,425 [salt.minion      :1432][INFO    ][11256] Starting a new job with PID 11256
2019-05-21 05:24:47,451 [salt.minion      :1711][INFO    ][11256] Returning information for job: 20190521052447387795
2019-05-21 05:24:58,882 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (697.228718996s left)
2019-05-21 05:25:17,580 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052517565221
2019-05-21 05:25:17,605 [salt.minion      :1432][INFO    ][11299] Starting a new job with PID 11299
2019-05-21 05:25:17,633 [salt.minion      :1711][INFO    ][11299] Returning information for job: 20190521052517565221
2019-05-21 05:25:31,689 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (664.421919107s left)
2019-05-21 05:25:47,768 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052547758910
2019-05-21 05:25:47,790 [salt.minion      :1432][INFO    ][11340] Starting a new job with PID 11340
2019-05-21 05:25:47,814 [salt.minion      :1711][INFO    ][11340] Returning information for job: 20190521052547758910
2019-05-21 05:26:04,608 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (631.502629995s left)
2019-05-21 05:26:17,921 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052617908068
2019-05-21 05:26:17,946 [salt.minion      :1432][INFO    ][11376] Starting a new job with PID 11376
2019-05-21 05:26:17,981 [salt.minion      :1711][INFO    ][11376] Returning information for job: 20190521052617908068
2019-05-21 05:26:37,545 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (598.566236973s left)
2019-05-21 05:26:47,944 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052647928409
2019-05-21 05:26:47,972 [salt.minion      :1432][INFO    ][11417] Starting a new job with PID 11417
2019-05-21 05:26:47,995 [salt.minion      :1711][INFO    ][11417] Returning information for job: 20190521052647928409
2019-05-21 05:27:10,463 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (565.647642136s left)
2019-05-21 05:27:17,983 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052717964673
2019-05-21 05:27:18,011 [salt.minion      :1432][INFO    ][11455] Starting a new job with PID 11455
2019-05-21 05:27:18,031 [salt.minion      :1711][INFO    ][11455] Returning information for job: 20190521052717964673
2019-05-21 05:27:43,224 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (532.887069941s left)
2019-05-21 05:27:48,024 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052748014530
2019-05-21 05:27:48,051 [salt.minion      :1432][INFO    ][11497] Starting a new job with PID 11497
2019-05-21 05:27:48,078 [salt.minion      :1711][INFO    ][11497] Returning information for job: 20190521052748014530
2019-05-21 05:28:15,896 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (500.214454174s left)
2019-05-21 05:28:18,115 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052818105292
2019-05-21 05:28:18,145 [salt.minion      :1432][INFO    ][11535] Starting a new job with PID 11535
2019-05-21 05:28:18,171 [salt.minion      :1711][INFO    ][11535] Returning information for job: 20190521052818105292
2019-05-21 05:28:48,210 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052848197567
2019-05-21 05:28:48,236 [salt.minion      :1432][INFO    ][11575] Starting a new job with PID 11575
2019-05-21 05:28:48,258 [salt.minion      :1711][INFO    ][11575] Returning information for job: 20190521052848197567
2019-05-21 05:28:48,930 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (467.180850983s left)
2019-05-21 05:29:18,316 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052918300018
2019-05-21 05:29:18,345 [salt.minion      :1432][INFO    ][11607] Starting a new job with PID 11607
2019-05-21 05:29:18,368 [salt.minion      :1711][INFO    ][11607] Returning information for job: 20190521052918300018
2019-05-21 05:29:21,774 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (434.336377144s left)
2019-05-21 05:29:48,488 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521052948476847
2019-05-21 05:29:48,513 [salt.minion      :1432][INFO    ][11658] Starting a new job with PID 11658
2019-05-21 05:29:48,559 [salt.minion      :1711][INFO    ][11658] Returning information for job: 20190521052948476847
2019-05-21 05:29:54,664 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (401.446931124s left)
2019-05-21 05:30:18,631 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053018615607
2019-05-21 05:30:18,659 [salt.minion      :1432][INFO    ][11691] Starting a new job with PID 11691
2019-05-21 05:30:18,680 [salt.minion      :1711][INFO    ][11691] Returning information for job: 20190521053018615607
2019-05-21 05:30:27,352 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (368.758965969s left)
2019-05-21 05:30:48,810 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053048802451
2019-05-21 05:30:48,834 [salt.minion      :1432][INFO    ][11883] Starting a new job with PID 11883
2019-05-21 05:30:48,857 [salt.minion      :1711][INFO    ][11883] Returning information for job: 20190521053048802451
2019-05-21 05:31:00,282 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (335.828539133s left)
2019-05-21 05:31:18,961 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053118948788
2019-05-21 05:31:18,981 [salt.minion      :1432][INFO    ][11914] Starting a new job with PID 11914
2019-05-21 05:31:19,008 [salt.minion      :1711][INFO    ][11914] Returning information for job: 20190521053118948788
2019-05-21 05:31:33,031 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (303.079945087s left)
2019-05-21 05:31:49,187 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053149174943
2019-05-21 05:31:49,216 [salt.minion      :1432][INFO    ][11970] Starting a new job with PID 11970
2019-05-21 05:31:49,237 [salt.minion      :1711][INFO    ][11970] Returning information for job: 20190521053149174943
2019-05-21 05:32:05,819 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (270.292015076s left)
2019-05-21 05:32:19,229 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053219214570
2019-05-21 05:32:19,256 [salt.minion      :1432][INFO    ][11993] Starting a new job with PID 11993
2019-05-21 05:32:19,278 [salt.minion      :1711][INFO    ][11993] Returning information for job: 20190521053219214570
2019-05-21 05:32:38,784 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (237.326452971s left)
2019-05-21 05:32:49,434 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053249421091
2019-05-21 05:32:49,460 [salt.minion      :1432][INFO    ][12048] Starting a new job with PID 12048
2019-05-21 05:32:49,483 [salt.minion      :1711][INFO    ][12048] Returning information for job: 20190521053249421091
2019-05-21 05:33:11,514 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (204.596985102s left)
2019-05-21 05:33:19,532 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053319523617
2019-05-21 05:33:19,559 [salt.minion      :1432][INFO    ][12074] Starting a new job with PID 12074
2019-05-21 05:33:19,582 [salt.minion      :1711][INFO    ][12074] Returning information for job: 20190521053319523617
2019-05-21 05:33:44,205 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (171.906213999s left)
2019-05-21 05:33:49,592 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053349579323
2019-05-21 05:33:49,620 [salt.minion      :1432][INFO    ][12125] Starting a new job with PID 12125
2019-05-21 05:33:49,641 [salt.minion      :1711][INFO    ][12125] Returning information for job: 20190521053349579323
2019-05-21 05:34:16,941 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (139.170118093s left)
2019-05-21 05:34:19,703 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053419689482
2019-05-21 05:34:19,731 [salt.minion      :1432][INFO    ][12151] Starting a new job with PID 12151
2019-05-21 05:34:19,758 [salt.minion      :1711][INFO    ][12151] Returning information for job: 20190521053419689482
2019-05-21 05:34:49,798 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053449787315
2019-05-21 05:34:49,822 [salt.minion      :1432][INFO    ][12201] Starting a new job with PID 12201
2019-05-21 05:34:49,846 [salt.minion      :1711][INFO    ][12201] Returning information for job: 20190521053449787315
2019-05-21 05:34:49,956 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (106.154622078s left)
2019-05-21 05:35:19,952 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053519945029
2019-05-21 05:35:19,978 [salt.minion      :1432][INFO    ][12222] Starting a new job with PID 12222
2019-05-21 05:35:20,002 [salt.minion      :1711][INFO    ][12222] Returning information for job: 20190521053519945029
2019-05-21 05:35:22,796 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (73.3147881031s left)
2019-05-21 05:35:50,039 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053550025870
2019-05-21 05:35:50,063 [salt.minion      :1432][INFO    ][12275] Starting a new job with PID 12275
2019-05-21 05:35:50,087 [salt.minion      :1711][INFO    ][12275] Returning information for job: 20190521053550025870
2019-05-21 05:35:55,580 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (40.5302979946s left)
2019-05-21 05:36:20,240 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053620229293
2019-05-21 05:36:20,266 [salt.minion      :1432][INFO    ][12297] Starting a new job with PID 12297
2019-05-21 05:36:20,288 [salt.minion      :1711][INFO    ][12297] Returning information for job: 20190521053620229293
2019-05-21 05:36:28,368 [salt.loaded.ext.module.maas:1023][INFO    ][6728] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (7.74303007126s left)
2019-05-21 05:36:50,440 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053650424283
2019-05-21 05:36:50,467 [salt.minion      :1432][INFO    ][12352] Starting a new job with PID 12352
2019-05-21 05:36:50,490 [salt.minion      :1711][INFO    ][12352] Returning information for job: 20190521053650424283
2019-05-21 05:37:01,235 [salt.state       :302 ][ERROR   ][6728] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-21 05:37:01,236 [salt.state       :1951][INFO    ][6728] Completed state [maas.wait_for_machine_status] at time 05:37:01.236586 duration_in_ms=2275134.411
2019-05-21 05:37:01,247 [salt.minion      :1711][INFO    ][6728] Returning information for job: 20190521045855995630
2019-05-21 05:37:12,041 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command pillar.get with jid 20190521053712031339
2019-05-21 05:37:12,069 [salt.minion      :1432][INFO    ][12369] Starting a new job with PID 12369
2019-05-21 05:37:12,078 [salt.minion      :1711][INFO    ][12369] Returning information for job: 20190521053712031339
2019-05-21 05:37:12,640 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command service.status with jid 20190521053712624698
2019-05-21 05:37:12,665 [salt.minion      :1432][INFO    ][12374] Starting a new job with PID 12374
2019-05-21 05:37:13,186 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12374] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-21 05:37:13,230 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12374] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-21 05:37:13,253 [salt.minion      :1711][INFO    ][12374] Returning information for job: 20190521053712624698
2019-05-21 05:37:13,833 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command state.apply with jid 20190521053713815626
2019-05-21 05:37:13,860 [salt.minion      :1432][INFO    ][12385] Starting a new job with PID 12385
2019-05-21 05:37:21,954 [salt.state       :915 ][INFO    ][12385] Loading fresh modules for state activity
2019-05-21 05:37:22,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command 'salt-minion --version' in directory '/root'
2019-05-21 05:37:22,852 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command 'salt-minion --version' in directory '/root'
2019-05-21 05:37:23,874 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command 'salt-minion --version' in directory '/root'
2019-05-21 05:37:24,162 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command 'salt-minion --version' in directory '/root'
2019-05-21 05:37:26,021 [salt.state       :1780][INFO    ][12385] Running state [salt-minion] at time 05:37:26.021148
2019-05-21 05:37:26,021 [salt.state       :1813][INFO    ][12385] Executing state pkg.installed for [salt-minion]
2019-05-21 05:37:26,022 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-21 05:37:26,143 [salt.state       :300 ][INFO    ][12385] All specified packages are already installed
2019-05-21 05:37:26,143 [salt.state       :1951][INFO    ][12385] Completed state [salt-minion] at time 05:37:26.143862 duration_in_ms=122.715
2019-05-21 05:37:26,144 [salt.state       :1780][INFO    ][12385] Running state [salt_minion_dependency_packages] at time 05:37:26.144194
2019-05-21 05:37:26,144 [salt.state       :1813][INFO    ][12385] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-21 05:37:26,155 [salt.state       :300 ][INFO    ][12385] All specified packages are already installed
2019-05-21 05:37:26,155 [salt.state       :1951][INFO    ][12385] Completed state [salt_minion_dependency_packages] at time 05:37:26.155369 duration_in_ms=11.175
2019-05-21 05:37:26,158 [salt.state       :1780][INFO    ][12385] Running state [/etc/salt/minion.d/minion.conf] at time 05:37:26.158404
2019-05-21 05:37:26,158 [salt.state       :1813][INFO    ][12385] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-21 05:37:26,451 [salt.state       :300 ][INFO    ][12385] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-21 05:37:26,451 [salt.state       :1951][INFO    ][12385] Completed state [/etc/salt/minion.d/minion.conf] at time 05:37:26.451736 duration_in_ms=293.331
2019-05-21 05:37:26,452 [salt.state       :1780][INFO    ][12385] Running state [python-netaddr] at time 05:37:26.451995
2019-05-21 05:37:26,452 [salt.state       :1813][INFO    ][12385] Executing state pkg.installed for [python-netaddr]
2019-05-21 05:37:26,461 [salt.state       :300 ][INFO    ][12385] All specified packages are already installed
2019-05-21 05:37:26,461 [salt.state       :1951][INFO    ][12385] Completed state [python-netaddr] at time 05:37:26.461617 duration_in_ms=9.622
2019-05-21 05:37:26,465 [salt.state       :1780][INFO    ][12385] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:37:26.465312
2019-05-21 05:37:26,465 [salt.state       :1813][INFO    ][12385] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-21 05:37:26,483 [salt.state       :300 ][INFO    ][12385] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-21 05:37:26,483 [salt.state       :1951][INFO    ][12385] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:37:26.483826 duration_in_ms=18.512
2019-05-21 05:37:26,488 [salt.state       :1780][INFO    ][12385] Running state [salt-minion] at time 05:37:26.487939
2019-05-21 05:37:26,488 [salt.state       :1813][INFO    ][12385] Executing state service.running for [salt-minion]
2019-05-21 05:37:26,489 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-21 05:37:26,533 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-21 05:37:26,555 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-21 05:37:26,578 [salt.state       :300 ][INFO    ][12385] The service salt-minion is already running
2019-05-21 05:37:26,578 [salt.state       :1951][INFO    ][12385] Completed state [salt-minion] at time 05:37:26.578454 duration_in_ms=90.514
2019-05-21 05:37:26,581 [salt.state       :1780][INFO    ][12385] Running state [/etc/salt/grains.d] at time 05:37:26.580976
2019-05-21 05:37:26,581 [salt.state       :1813][INFO    ][12385] Executing state file.directory for [/etc/salt/grains.d]
2019-05-21 05:37:26,584 [salt.state       :300 ][INFO    ][12385] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-21 05:37:26,584 [salt.state       :1951][INFO    ][12385] Completed state [/etc/salt/grains.d] at time 05:37:26.584645 duration_in_ms=3.669
2019-05-21 05:37:26,585 [salt.state       :1780][INFO    ][12385] Running state [/etc/salt/grains] at time 05:37:26.585566
2019-05-21 05:37:26,585 [salt.state       :1813][INFO    ][12385] Executing state file.managed for [/etc/salt/grains]
2019-05-21 05:37:26,586 [salt.state       :300 ][INFO    ][12385] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-21 05:37:26,586 [salt.state       :1951][INFO    ][12385] Completed state [/etc/salt/grains] at time 05:37:26.586912 duration_in_ms=1.346
2019-05-21 05:37:26,587 [salt.state       :1780][INFO    ][12385] Running state [/etc/salt/grains.d/placeholder] at time 05:37:26.587565
2019-05-21 05:37:26,587 [salt.state       :1813][INFO    ][12385] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-21 05:37:26,588 [salt.state       :300 ][INFO    ][12385] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-21 05:37:26,588 [salt.state       :1951][INFO    ][12385] Completed state [/etc/salt/grains.d/placeholder] at time 05:37:26.588866 duration_in_ms=1.301
2019-05-21 05:37:26,589 [salt.state       :1780][INFO    ][12385] Running state [/etc/salt/grains.d/sphinx] at time 05:37:26.589515
2019-05-21 05:37:26,589 [salt.state       :1813][INFO    ][12385] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-21 05:37:26,591 [salt.state       :300 ][INFO    ][12385] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-21 05:37:26,591 [salt.state       :1951][INFO    ][12385] Completed state [/etc/salt/grains.d/sphinx] at time 05:37:26.591627 duration_in_ms=2.112
2019-05-21 05:37:26,596 [salt.state       :1780][INFO    ][12385] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:37:26.596125
2019-05-21 05:37:26,596 [salt.state       :1813][INFO    ][12385] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-21 05:37:26,596 [salt.state       :300 ][INFO    ][12385] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-21 05:37:26,596 [salt.state       :1951][INFO    ][12385] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:37:26.596912 duration_in_ms=0.787
2019-05-21 05:37:26,597 [salt.state       :1780][INFO    ][12385] Running state [/etc/salt/grains.d/dns_records] at time 05:37:26.597385
2019-05-21 05:37:26,597 [salt.state       :1813][INFO    ][12385] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-21 05:37:26,598 [salt.state       :300 ][INFO    ][12385] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-21 05:37:26,598 [salt.state       :1951][INFO    ][12385] Completed state [/etc/salt/grains.d/dns_records] at time 05:37:26.598716 duration_in_ms=1.33
2019-05-21 05:37:26,599 [salt.state       :1780][INFO    ][12385] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:37:26.599623
2019-05-21 05:37:26,599 [salt.state       :1813][INFO    ][12385] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-21 05:37:26,600 [salt.state       :300 ][INFO    ][12385] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-21 05:37:26,600 [salt.state       :1951][INFO    ][12385] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:37:26.600359 duration_in_ms=0.736
2019-05-21 05:37:26,600 [salt.state       :1780][INFO    ][12385] Running state [/etc/salt/grains.d/salt] at time 05:37:26.600827
2019-05-21 05:37:26,601 [salt.state       :1813][INFO    ][12385] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-21 05:37:26,601 [salt.state       :300 ][INFO    ][12385] File /etc/salt/grains.d/salt is in the correct state
2019-05-21 05:37:26,602 [salt.state       :1951][INFO    ][12385] Completed state [/etc/salt/grains.d/salt] at time 05:37:26.602168 duration_in_ms=1.341
2019-05-21 05:37:26,603 [salt.state       :1780][INFO    ][12385] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:37:26.603031
2019-05-21 05:37:26,603 [salt.state       :1813][INFO    ][12385] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-21 05:37:26,603 [salt.state       :300 ][INFO    ][12385] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-21 05:37:26,603 [salt.state       :1951][INFO    ][12385] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:37:26.603771 duration_in_ms=0.74
2019-05-21 05:37:26,607 [salt.state       :1780][INFO    ][12385] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:37:26.605683
2019-05-21 05:37:26,607 [salt.state       :1813][INFO    ][12385] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-21 05:37:26,608 [salt.state       :300 ][INFO    ][12385] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-21 05:37:26,608 [salt.state       :1951][INFO    ][12385] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:37:26.608386 duration_in_ms=2.703
2019-05-21 05:37:26,609 [salt.state       :1780][INFO    ][12385] Running state [mine.update] at time 05:37:26.609321
2019-05-21 05:37:26,609 [salt.state       :1813][INFO    ][12385] Executing state module.wait for [mine.update]
2019-05-21 05:37:26,610 [salt.state       :300 ][INFO    ][12385] No changes made for mine.update
2019-05-21 05:37:26,610 [salt.state       :1951][INFO    ][12385] Completed state [mine.update] at time 05:37:26.610566 duration_in_ms=1.246
2019-05-21 05:37:26,610 [salt.state       :1780][INFO    ][12385] Running state [ca-certificates] at time 05:37:26.610893
2019-05-21 05:37:26,611 [salt.state       :1813][INFO    ][12385] Executing state pkg.installed for [ca-certificates]
2019-05-21 05:37:26,623 [salt.state       :300 ][INFO    ][12385] All specified packages are already installed
2019-05-21 05:37:26,623 [salt.state       :1951][INFO    ][12385] Completed state [ca-certificates] at time 05:37:26.623597 duration_in_ms=12.704
2019-05-21 05:37:26,624 [salt.state       :1780][INFO    ][12385] Running state [update-ca-certificates] at time 05:37:26.624277
2019-05-21 05:37:26,624 [salt.state       :1813][INFO    ][12385] Executing state cmd.wait for [update-ca-certificates]
2019-05-21 05:37:26,624 [salt.state       :300 ][INFO    ][12385] No changes made for update-ca-certificates
2019-05-21 05:37:26,625 [salt.state       :1951][INFO    ][12385] Completed state [update-ca-certificates] at time 05:37:26.624982 duration_in_ms=0.705
2019-05-21 05:37:26,625 [salt.state       :1780][INFO    ][12385] Running state [iptables] at time 05:37:26.625217
2019-05-21 05:37:26,625 [salt.state       :1813][INFO    ][12385] Executing state pkg.installed for [iptables]
2019-05-21 05:37:26,635 [salt.state       :300 ][INFO    ][12385] All specified packages are already installed
2019-05-21 05:37:26,635 [salt.state       :1951][INFO    ][12385] Completed state [iptables] at time 05:37:26.635863 duration_in_ms=10.645
2019-05-21 05:37:26,636 [salt.state       :1780][INFO    ][12385] Running state [iptables-persistent] at time 05:37:26.636098
2019-05-21 05:37:26,636 [salt.state       :1813][INFO    ][12385] Executing state pkg.installed for [iptables-persistent]
2019-05-21 05:37:26,645 [salt.state       :300 ][INFO    ][12385] All specified packages are already installed
2019-05-21 05:37:26,646 [salt.state       :1951][INFO    ][12385] Completed state [iptables-persistent] at time 05:37:26.645950 duration_in_ms=9.852
2019-05-21 05:37:26,646 [salt.state       :1780][INFO    ][12385] Running state [iptables_modules_v4_load] at time 05:37:26.646917
2019-05-21 05:37:26,647 [salt.state       :1813][INFO    ][12385] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-21 05:37:26,647 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command 'lsmod' in directory '/root'
2019-05-21 05:37:26,670 [salt.state       :300 ][INFO    ][12385] Kernel modules iptable_filter, ip_tables are already present
2019-05-21 05:37:26,670 [salt.state       :1951][INFO    ][12385] Completed state [iptables_modules_v4_load] at time 05:37:26.670806 duration_in_ms=23.888
2019-05-21 05:37:26,671 [salt.state       :1780][INFO    ][12385] Running state [/etc/iptables/rules.v4] at time 05:37:26.671775
2019-05-21 05:37:26,672 [salt.state       :1813][INFO    ][12385] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-21 05:37:26,776 [salt.state       :300 ][INFO    ][12385] File /etc/iptables/rules.v4 is in the correct state
2019-05-21 05:37:26,777 [salt.state       :1951][INFO    ][12385] Completed state [/etc/iptables/rules.v4] at time 05:37:26.776775 duration_in_ms=105.001
2019-05-21 05:37:26,779 [salt.state       :1780][INFO    ][12385] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:37:26.779660
2019-05-21 05:37:26,780 [salt.state       :1813][INFO    ][12385] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-21 05:37:26,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-21 05:37:26,802 [salt.state       :300 ][INFO    ][12385] onlyif execution failed
2019-05-21 05:37:26,803 [salt.state       :1951][INFO    ][12385] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:37:26.802991 duration_in_ms=23.331
2019-05-21 05:37:26,805 [salt.state       :1780][INFO    ][12385] Running state [netfilter-persistent] at time 05:37:26.804922
2019-05-21 05:37:26,805 [salt.state       :1813][INFO    ][12385] Executing state service.running for [netfilter-persistent]
2019-05-21 05:37:26,806 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-21 05:37:26,831 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-21 05:37:26,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-21 05:37:26,884 [salt.state       :300 ][INFO    ][12385] The service netfilter-persistent is already running
2019-05-21 05:37:26,885 [salt.state       :1951][INFO    ][12385] Completed state [netfilter-persistent] at time 05:37:26.884980 duration_in_ms=80.058
2019-05-21 05:37:26,886 [salt.state       :1780][INFO    ][12385] Running state [iptables_extra.remove_stale_tables] at time 05:37:26.886520
2019-05-21 05:37:26,887 [salt.state       :1813][INFO    ][12385] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-21 05:37:26,887 [salt.state       :300 ][INFO    ][12385] No changes made for iptables_extra.remove_stale_tables
2019-05-21 05:37:26,888 [salt.state       :1951][INFO    ][12385] Completed state [iptables_extra.remove_stale_tables] at time 05:37:26.887918 duration_in_ms=1.397
2019-05-21 05:37:26,888 [salt.state       :1780][INFO    ][12385] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:37:26.888334
2019-05-21 05:37:26,888 [salt.state       :1813][INFO    ][12385] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-21 05:37:26,889 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12385] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-21 05:37:26,910 [salt.state       :300 ][INFO    ][12385] onlyif execution failed
2019-05-21 05:37:26,911 [salt.state       :1951][INFO    ][12385] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:37:26.910888 duration_in_ms=22.553
2019-05-21 05:37:26,912 [salt.state       :1780][INFO    ][12385] Running state [/etc/iptables/rules.v6] at time 05:37:26.912732
2019-05-21 05:37:26,913 [salt.state       :1813][INFO    ][12385] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-21 05:37:26,914 [salt.state       :300 ][INFO    ][12385] File /etc/iptables/rules.v6 is not present
2019-05-21 05:37:26,914 [salt.state       :1951][INFO    ][12385] Completed state [/etc/iptables/rules.v6] at time 05:37:26.914500 duration_in_ms=1.768
2019-05-21 05:37:26,915 [salt.state       :1780][INFO    ][12385] Running state [iptables_extra.flush_all] at time 05:37:26.915883
2019-05-21 05:37:26,916 [salt.state       :1813][INFO    ][12385] Executing state module.wait for [iptables_extra.flush_all]
2019-05-21 05:37:26,917 [salt.state       :300 ][INFO    ][12385] No changes made for iptables_extra.flush_all
2019-05-21 05:37:26,917 [salt.state       :1951][INFO    ][12385] Completed state [iptables_extra.flush_all] at time 05:37:26.917344 duration_in_ms=1.46
2019-05-21 05:37:26,923 [salt.minion      :1711][INFO    ][12385] Returning information for job: 20190521053713815626
2019-05-21 05:37:27,497 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command state.apply with jid 20190521053727484006
2019-05-21 05:37:27,524 [salt.minion      :1432][INFO    ][12466] Starting a new job with PID 12466
2019-05-21 05:37:28,647 [salt.state       :915 ][INFO    ][12466] Loading fresh modules for state activity
2019-05-21 05:37:29,560 [salt.state       :1780][INFO    ][12466] Running state [maas-rack-controller] at time 05:37:29.560148
2019-05-21 05:37:29,560 [salt.state       :1813][INFO    ][12466] Executing state pkg.installed for [maas-rack-controller]
2019-05-21 05:37:29,562 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12466] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-21 05:37:29,680 [salt.state       :300 ][INFO    ][12466] All specified packages are already installed
2019-05-21 05:37:29,680 [salt.state       :1951][INFO    ][12466] Completed state [maas-rack-controller] at time 05:37:29.680318 duration_in_ms=120.172
2019-05-21 05:37:29,680 [salt.state       :1780][INFO    ][12466] Running state [ipmitool] at time 05:37:29.680691
2019-05-21 05:37:29,681 [salt.state       :1813][INFO    ][12466] Executing state pkg.installed for [ipmitool]
2019-05-21 05:37:29,690 [salt.state       :300 ][INFO    ][12466] All specified packages are already installed
2019-05-21 05:37:29,691 [salt.state       :1951][INFO    ][12466] Completed state [ipmitool] at time 05:37:29.691105 duration_in_ms=10.414
2019-05-21 05:37:29,695 [salt.state       :1780][INFO    ][12466] Running state [/etc/maas/rackd.conf] at time 05:37:29.695136
2019-05-21 05:37:29,695 [salt.state       :1813][INFO    ][12466] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-21 05:37:29,696 [salt.state       :300 ][INFO    ][12466] No changes needed to be made
2019-05-21 05:37:29,696 [salt.state       :1951][INFO    ][12466] Completed state [/etc/maas/rackd.conf] at time 05:37:29.696546 duration_in_ms=1.41
2019-05-21 05:37:29,696 [salt.state       :1780][INFO    ][12466] Running state [/etc/maas/rackd.conf] at time 05:37:29.696784
2019-05-21 05:37:29,697 [salt.state       :1813][INFO    ][12466] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-21 05:37:29,697 [salt.loaded.int.states.file:2298][WARNING ][12466] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-21 05:37:29,698 [salt.state       :300 ][INFO    ][12466] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-21 05:37:29,698 [salt.state       :1951][INFO    ][12466] Completed state [/etc/maas/rackd.conf] at time 05:37:29.698309 duration_in_ms=1.525
2019-05-21 05:37:29,699 [salt.state       :1780][INFO    ][12466] Running state [maas-rackd] at time 05:37:29.699314
2019-05-21 05:37:29,699 [salt.state       :1813][INFO    ][12466] Executing state service.running for [maas-rackd]
2019-05-21 05:37:29,700 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12466] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-21 05:37:29,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12466] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-21 05:37:29,758 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12466] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-21 05:37:29,780 [salt.state       :300 ][INFO    ][12466] The service maas-rackd is already running
2019-05-21 05:37:29,781 [salt.state       :1951][INFO    ][12466] Completed state [maas-rackd] at time 05:37:29.781054 duration_in_ms=81.74
2019-05-21 05:37:29,783 [salt.minion      :1711][INFO    ][12466] Returning information for job: 20190521053727484006
2019-05-21 05:37:30,394 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command state.apply with jid 20190521053730382290
2019-05-21 05:37:30,420 [salt.minion      :1432][INFO    ][12502] Starting a new job with PID 12502
2019-05-21 05:37:31,564 [salt.state       :915 ][INFO    ][12502] Loading fresh modules for state activity
2019-05-21 05:37:32,633 [salt.state       :1780][INFO    ][12502] Running state [maas-region-controller] at time 05:37:32.632929
2019-05-21 05:37:32,633 [salt.state       :1813][INFO    ][12502] Executing state pkg.installed for [maas-region-controller]
2019-05-21 05:37:32,634 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12502] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-21 05:37:32,747 [salt.state       :300 ][INFO    ][12502] All specified packages are already installed
2019-05-21 05:37:32,748 [salt.state       :1951][INFO    ][12502] Completed state [maas-region-controller] at time 05:37:32.748135 duration_in_ms=115.206
2019-05-21 05:37:32,748 [salt.state       :1780][INFO    ][12502] Running state [python-oauth] at time 05:37:32.748470
2019-05-21 05:37:32,748 [salt.state       :1813][INFO    ][12502] Executing state pkg.installed for [python-oauth]
2019-05-21 05:37:32,758 [salt.state       :300 ][INFO    ][12502] All specified packages are already installed
2019-05-21 05:37:32,758 [salt.state       :1951][INFO    ][12502] Completed state [python-oauth] at time 05:37:32.758260 duration_in_ms=9.791
2019-05-21 05:37:32,761 [salt.state       :1780][INFO    ][12502] Running state [/etc/maas/regiond.conf] at time 05:37:32.761294
2019-05-21 05:37:32,761 [salt.state       :1813][INFO    ][12502] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-21 05:37:32,767 [salt.state       :300 ][INFO    ][12502] No changes needed to be made
2019-05-21 05:37:32,767 [salt.state       :1951][INFO    ][12502] Completed state [/etc/maas/regiond.conf] at time 05:37:32.767361 duration_in_ms=6.067
2019-05-21 05:37:32,767 [salt.state       :1780][INFO    ][12502] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:37:32.767828
2019-05-21 05:37:32,768 [salt.state       :1813][INFO    ][12502] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-21 05:37:32,828 [salt.state       :300 ][INFO    ][12502] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-21 05:37:32,828 [salt.state       :1951][INFO    ][12502] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:37:32.828694 duration_in_ms=60.866
2019-05-21 05:37:32,829 [salt.state       :1780][INFO    ][12502] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:37:32.829257
2019-05-21 05:37:32,829 [salt.state       :1813][INFO    ][12502] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-21 05:37:32,834 [salt.state       :300 ][INFO    ][12502] No changes needed to be made
2019-05-21 05:37:32,834 [salt.state       :1951][INFO    ][12502] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:37:32.834938 duration_in_ms=5.681
2019-05-21 05:37:32,835 [salt.state       :1780][INFO    ][12502] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:37:32.835409
2019-05-21 05:37:32,835 [salt.state       :1813][INFO    ][12502] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-21 05:37:32,839 [salt.state       :300 ][INFO    ][12502] No changes needed to be made
2019-05-21 05:37:32,839 [salt.state       :1951][INFO    ][12502] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:37:32.839208 duration_in_ms=3.799
2019-05-21 05:37:32,839 [salt.state       :1780][INFO    ][12502] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:37:32.839685
2019-05-21 05:37:32,839 [salt.state       :1813][INFO    ][12502] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-21 05:37:32,864 [salt.state       :300 ][INFO    ][12502] No changes needed to be made
2019-05-21 05:37:32,864 [salt.state       :1951][INFO    ][12502] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:37:32.864378 duration_in_ms=24.693
2019-05-21 05:37:32,864 [salt.state       :1780][INFO    ][12502] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:37:32.864850
2019-05-21 05:37:32,865 [salt.state       :1813][INFO    ][12502] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-21 05:37:32,879 [salt.state       :300 ][INFO    ][12502] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-21 05:37:32,879 [salt.state       :1951][INFO    ][12502] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:37:32.879793 duration_in_ms=14.942
2019-05-21 05:37:32,881 [salt.state       :1780][INFO    ][12502] Running state [a2enmod headers] at time 05:37:32.881007
2019-05-21 05:37:32,881 [salt.state       :1813][INFO    ][12502] Executing state cmd.run for [a2enmod headers]
2019-05-21 05:37:32,883 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12502] Executing command 'a2enmod headers' in directory '/root'
2019-05-21 05:37:32,947 [salt.state       :300 ][INFO    ][12502] {'pid': 12521, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-21 05:37:32,948 [salt.state       :1951][INFO    ][12502] Completed state [a2enmod headers] at time 05:37:32.947931 duration_in_ms=66.922
2019-05-21 05:37:32,948 [salt.state       :1780][INFO    ][12502] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:37:32.948639
2019-05-21 05:37:32,949 [salt.state       :1813][INFO    ][12502] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-21 05:37:32,975 [salt.state       :300 ][INFO    ][12502] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-21 05:37:32,976 [salt.state       :1951][INFO    ][12502] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:37:32.976236 duration_in_ms=27.597
2019-05-21 05:37:32,977 [salt.state       :1780][INFO    ][12502] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:37:32.977320
2019-05-21 05:37:32,980 [salt.state       :1813][INFO    ][12502] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-21 05:37:33,034 [salt.state       :300 ][INFO    ][12502] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-21 05:37:33,035 [salt.state       :1951][INFO    ][12502] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:37:33.035045 duration_in_ms=57.725
2019-05-21 05:37:33,035 [salt.state       :1780][INFO    ][12502] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:37:33.035614
2019-05-21 05:37:33,035 [salt.state       :1813][INFO    ][12502] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-21 05:37:33,093 [salt.state       :300 ][INFO    ][12502] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-21 05:37:33,093 [salt.state       :1951][INFO    ][12502] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:37:33.093348 duration_in_ms=57.734
2019-05-21 05:37:33,093 [salt.state       :1780][INFO    ][12502] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:37:33.093855
2019-05-21 05:37:33,094 [salt.state       :1813][INFO    ][12502] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-21 05:37:33,165 [salt.state       :300 ][INFO    ][12502] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-21 05:37:33,165 [salt.state       :1951][INFO    ][12502] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:37:33.165274 duration_in_ms=71.419
2019-05-21 05:37:33,165 [salt.state       :1780][INFO    ][12502] Running state [/root/.pgpass] at time 05:37:33.165594
2019-05-21 05:37:33,166 [salt.state       :1813][INFO    ][12502] Executing state file.managed for [/root/.pgpass]
2019-05-21 05:37:33,219 [salt.state       :300 ][INFO    ][12502] File /root/.pgpass is in the correct state
2019-05-21 05:37:33,220 [salt.state       :1951][INFO    ][12502] Completed state [/root/.pgpass] at time 05:37:33.220079 duration_in_ms=54.485
2019-05-21 05:37:33,226 [salt.state       :1780][INFO    ][12502] Running state [maas-region syncdb --noinput] at time 05:37:33.226888
2019-05-21 05:37:33,227 [salt.state       :1813][INFO    ][12502] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-21 05:37:33,227 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12502] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-21 05:37:35,694 [salt.state       :300 ][INFO    ][12502] {'pid': 12534, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, contenttypes, piston3, maasserver, sessions, auth, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-21 05:37:35,695 [salt.state       :1951][INFO    ][12502] Completed state [maas-region syncdb --noinput] at time 05:37:35.695478 duration_in_ms=2468.588
2019-05-21 05:37:35,696 [salt.state       :2022][WARNING ][12502] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-21 05:37:35,699 [salt.state       :1780][INFO    ][12502] Running state [maas-regiond] at time 05:37:35.699807
2019-05-21 05:37:35,700 [salt.state       :1813][INFO    ][12502] Executing state service.running for [maas-regiond]
2019-05-21 05:37:35,702 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12502] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-21 05:37:35,752 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12502] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-21 05:37:35,777 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12502] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-21 05:37:35,800 [salt.state       :300 ][INFO    ][12502] The service maas-regiond is already running
2019-05-21 05:37:35,800 [salt.state       :1951][INFO    ][12502] Completed state [maas-regiond] at time 05:37:35.800764 duration_in_ms=100.958
2019-05-21 05:37:35,804 [salt.state       :1780][INFO    ][12502] Running state [bind9] at time 05:37:35.804071
2019-05-21 05:37:35,804 [salt.state       :1813][INFO    ][12502] Executing state service.running for [bind9]
2019-05-21 05:37:35,805 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12502] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-21 05:37:35,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12502] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-21 05:37:35,845 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12502] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-21 05:37:35,869 [salt.state       :300 ][INFO    ][12502] The service bind9 is already running
2019-05-21 05:37:35,872 [salt.state       :1951][INFO    ][12502] Completed state [bind9] at time 05:37:35.872037 duration_in_ms=67.967
2019-05-21 05:37:35,874 [salt.state       :1780][INFO    ][12502] Running state [apache2] at time 05:37:35.874579
2019-05-21 05:37:35,875 [salt.state       :1813][INFO    ][12502] Executing state service.running for [apache2]
2019-05-21 05:37:35,875 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12502] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-21 05:37:35,899 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12502] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-21 05:37:35,922 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12502] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-21 05:37:35,948 [salt.state       :300 ][INFO    ][12502] The service apache2 is already running
2019-05-21 05:37:35,948 [salt.state       :1951][INFO    ][12502] Completed state [apache2] at time 05:37:35.948860 duration_in_ms=74.28
2019-05-21 05:37:35,951 [salt.state       :1780][INFO    ][12502] Running state [maasng.wait_for_http_code] at time 05:37:35.951184
2019-05-21 05:37:35,951 [salt.state       :1813][INFO    ][12502] Executing state module.run for [maasng.wait_for_http_code]
2019-05-21 05:37:35,952 [salt.utils.decorators:613 ][WARNING ][12502] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 05:37:35,963 [salt.state       :300 ][INFO    ][12502] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-21 05:37:35,963 [salt.state       :1951][INFO    ][12502] Completed state [maasng.wait_for_http_code] at time 05:37:35.963512 duration_in_ms=12.328
2019-05-21 05:37:35,965 [salt.state       :1780][INFO    ][12502] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:37:35.965037
2019-05-21 05:37:35,965 [salt.state       :1813][INFO    ][12502] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-21 05:37:35,969 [salt.state       :300 ][INFO    ][12502] /var/lib/maas/.setup_admin exists
2019-05-21 05:37:35,970 [salt.state       :1951][INFO    ][12502] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:37:35.970159 duration_in_ms=5.123
2019-05-21 05:37:35,971 [salt.state       :1780][INFO    ][12502] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:37:35.971402
2019-05-21 05:37:35,971 [salt.state       :1813][INFO    ][12502] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 05:37:35,972 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12502] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 05:37:37,694 [salt.state       :300 ][INFO    ][12502] {'pid': 12554, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 05:37:37,695 [salt.state       :1951][INFO    ][12502] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:37:37.695578 duration_in_ms=1724.175
2019-05-21 05:37:37,707 [salt.state       :1780][INFO    ][12502] Running state [maas_region_boot_source_resources_mirror] at time 05:37:37.707367
2019-05-21 05:37:37,708 [salt.state       :1813][INFO    ][12502] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-21 05:37:37,803 [salt.state       :300 ][INFO    ][12502] {'changes': {}}
2019-05-21 05:37:37,803 [salt.state       :1951][INFO    ][12502] Completed state [maas_region_boot_source_resources_mirror] at time 05:37:37.803736 duration_in_ms=96.369
2019-05-21 05:37:37,804 [salt.state       :1780][INFO    ][12502] Running state [maasng.boot_resources_import] at time 05:37:37.804851
2019-05-21 05:37:37,805 [salt.state       :1813][INFO    ][12502] Executing state module.run for [maasng.boot_resources_import]
2019-05-21 05:37:37,806 [salt.utils.decorators:613 ][WARNING ][12502] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 05:37:37,905 [salt.loaded.ext.module.maasng:1600][INFO    ][12502] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-21 05:37:42,971 [salt.loaded.ext.module.maasng:1600][INFO    ][12502] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-21 05:37:45,505 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053745490528
2019-05-21 05:37:45,536 [salt.minion      :1432][INFO    ][12589] Starting a new job with PID 12589
2019-05-21 05:37:45,561 [salt.minion      :1711][INFO    ][12589] Returning information for job: 20190521053745490528
2019-05-21 05:37:48,067 [salt.state       :300 ][INFO    ][12502] {'ret': True}
2019-05-21 05:37:48,068 [salt.state       :1951][INFO    ][12502] Completed state [maasng.boot_resources_import] at time 05:37:48.068459 duration_in_ms=10263.607
2019-05-21 05:37:48,071 [salt.state       :1780][INFO    ][12502] Running state [maas_region_boot_sources_selection_xenial] at time 05:37:48.071137
2019-05-21 05:37:48,071 [salt.state       :1813][INFO    ][12502] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-21 05:37:48,271 [salt.state       :300 ][INFO    ][12502] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-21 05:37:48,272 [salt.state       :1951][INFO    ][12502] Completed state [maas_region_boot_sources_selection_xenial] at time 05:37:48.272042 duration_in_ms=200.904
2019-05-21 05:37:48,273 [salt.state       :1780][INFO    ][12502] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:37:48.273109
2019-05-21 05:37:48,273 [salt.state       :1813][INFO    ][12502] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-21 05:37:48,273 [salt.utils.decorators:613 ][WARNING ][12502] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 05:37:48,274 [salt.loaded.ext.module.maasng:1771][INFO    ][12502] boot-sources sync initiated for ALL Rack's
2019-05-21 05:37:49,458 [salt.state       :300 ][INFO    ][12502] {'ret': True}
2019-05-21 05:37:49,459 [salt.state       :1951][INFO    ][12502] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:37:49.459231 duration_in_ms=1186.122
2019-05-21 05:37:49,461 [salt.state       :1780][INFO    ][12502] Running state [maas.process_maas_config] at time 05:37:49.461042
2019-05-21 05:37:49,461 [salt.state       :1813][INFO    ][12502] Executing state module.run for [maas.process_maas_config]
2019-05-21 05:37:49,462 [salt.utils.decorators:613 ][WARNING ][12502] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 05:37:49,463 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=enable_http_proxy value=True
2019-05-21 05:37:49,507 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=upstream_dns value=8.8.8.8
2019-05-21 05:37:49,558 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=commissioning_distro_series value=xenial
2019-05-21 05:37:49,610 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=default_osystem value=ubuntu
2019-05-21 05:37:50,996 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=active_discovery_interval value=600
2019-05-21 05:37:51,037 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=dnssec_validation value=no
2019-05-21 05:37:51,083 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=maas_name value=mas01
2019-05-21 05:37:51,120 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=network_discovery value=enabled
2019-05-21 05:37:51,219 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=enable_third_party_drivers value=True
2019-05-21 05:37:51,333 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=default_storage_layout value=lvm
2019-05-21 05:37:51,384 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=ntp_external_only value=True
2019-05-21 05:37:51,439 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-21 05:37:51,496 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=default_distro_series value=xenial
2019-05-21 05:37:51,551 [salt.loaded.ext.module.maas:92  ][INFO    ][12502] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-05-21 05:37:51,676 [salt.state       :300 ][INFO    ][12502] {'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-05-21 05:37:51,676 [salt.state       :1951][INFO    ][12502] Completed state [maas.process_maas_config] at time 05:37:51.676479 duration_in_ms=2215.436
2019-05-21 05:37:51,677 [salt.state       :1780][INFO    ][12502] Running state [pxe_admin] at time 05:37:51.677196
2019-05-21 05:37:51,677 [salt.state       :1813][INFO    ][12502] Executing state maasng.fabric_present for [pxe_admin]
2019-05-21 05:37:51,735 [salt.loaded.ext.module.maasng:945 ][INFO    ][12502] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], 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'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', 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'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmqkfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-21 05:37:51,809 [salt.loaded.ext.module.maasng:1008][WARNING ][12502] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-21 05:37:51,810 [salt.loaded.ext.module.maasng:1011][WARNING ][12502] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-21 05:37:51,863 [salt.state       :300 ][INFO    ][12502] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-21 05:37:51,864 [salt.state       :1951][INFO    ][12502] Completed state [pxe_admin] at time 05:37:51.864205 duration_in_ms=187.008
2019-05-21 05:37:51,864 [salt.state       :1780][INFO    ][12502] Running state [vlan 0] at time 05:37:51.864625
2019-05-21 05:37:51,865 [salt.state       :1813][INFO    ][12502] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-21 05:37:51,924 [salt.loaded.ext.module.maasng:945 ][INFO    ][12502] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], 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'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', 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'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmqkfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-21 05:37:52,023 [salt.loaded.ext.module.maasng:945 ][INFO    ][12502] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qmqkfd', 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-05-21 05:37:52,268 [salt.loaded.ext.module.maasng:945 ][INFO    ][12502] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qmqkfd', 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-05-21 05:37:52,342 [salt.state       :300 ][INFO    ][12502] {'new': 'Vlan untagged was updated'}
2019-05-21 05:37:52,342 [salt.state       :1951][INFO    ][12502] Completed state [vlan 0] at time 05:37:52.342849 duration_in_ms=478.224
2019-05-21 05:37:52,343 [salt.state       :1780][INFO    ][12502] Running state [192.168.11.0/24] at time 05:37:52.343770
2019-05-21 05:37:52,344 [salt.state       :1813][INFO    ][12502] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-21 05:37:52,513 [salt.loaded.ext.module.maasng:945 ][INFO    ][12502] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'qmqkfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-21 05:37:52,517 [salt.loaded.ext.module.maasng:1235][WARNING ][12502] Ignoring parameter vlan:0
2019-05-21 05:37:52,585 [salt.state       :300 ][INFO    ][12502] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-21 05:37:52,587 [salt.state       :1951][INFO    ][12502] Completed state [192.168.11.0/24] at time 05:37:52.587413 duration_in_ms=243.642
2019-05-21 05:37:52,588 [salt.state       :1780][INFO    ][12502] Running state [maas_create_iprange_1] at time 05:37:52.588126
2019-05-21 05:37:52,588 [salt.state       :1813][INFO    ][12502] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-21 05:37:52,643 [salt.state       :300 ][INFO    ][12502] Iprange maas_create_iprange_1 already exist.
2019-05-21 05:37:52,643 [salt.state       :1951][INFO    ][12502] Completed state [maas_create_iprange_1] at time 05:37:52.643550 duration_in_ms=55.423
2019-05-21 05:37:52,643 [salt.state       :1780][INFO    ][12502] Running state [vlan 0] at time 05:37:52.643806
2019-05-21 05:37:52,644 [salt.state       :1813][INFO    ][12502] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-21 05:37:52,696 [salt.loaded.ext.module.maasng:945 ][INFO    ][12502] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qmqkfd', 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-05-21 05:37:52,779 [salt.loaded.ext.module.maasng:945 ][INFO    ][12502] [{u'class_type': None, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/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'dhcp_on': False, u'fabric_id': 2, 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'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qmqkfd', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-05-21 05:37:52,977 [salt.loaded.ext.module.maasng:945 ][INFO    ][12502] [{u'class_type': None, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/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'dhcp_on': False, u'fabric_id': 2, 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'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qmqkfd', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-05-21 05:37:53,062 [salt.state       :300 ][INFO    ][12502] {'new': 'Vlan untagged was updated'}
2019-05-21 05:37:53,062 [salt.state       :1951][INFO    ][12502] Completed state [vlan 0] at time 05:37:53.062852 duration_in_ms=419.046
2019-05-21 05:37:53,063 [salt.state       :1780][INFO    ][12502] Running state [opnfv] at time 05:37:53.063711
2019-05-21 05:37:53,063 [salt.state       :1813][INFO    ][12502] Executing state maasng.sshkey_present for [opnfv]
2019-05-21 05:37:53,124 [salt.loaded.ext.module.maasng:1903][INFO    ][12502] [{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-05-21 05:37:53,125 [salt.state       :300 ][INFO    ][12502] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-21 05:37:53,125 [salt.state       :1951][INFO    ][12502] Completed state [opnfv] at time 05:37:53.125303 duration_in_ms=61.592
2019-05-21 05:37:53,131 [salt.minion      :1711][INFO    ][12502] Returning information for job: 20190521053730382290
2019-05-21 05:37:53,743 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command state.apply with jid 20190521053753735213
2019-05-21 05:37:53,759 [salt.minion      :1432][INFO    ][12966] Starting a new job with PID 12966
2019-05-21 05:38:02,044 [salt.state       :915 ][INFO    ][12966] Loading fresh modules for state activity
2019-05-21 05:38:02,164 [salt.state       :1780][INFO    ][12966] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:38:02.164531
2019-05-21 05:38:02,164 [salt.state       :1813][INFO    ][12966] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 05:38:02,166 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12966] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 05:38:03,871 [salt.state       :300 ][INFO    ][12966] {'pid': 12998, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 05:38:03,872 [salt.state       :1951][INFO    ][12966] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:38:03.872041 duration_in_ms=1707.509
2019-05-21 05:38:03,875 [salt.state       :1780][INFO    ][12966] Running state [maas.process_machines] at time 05:38:03.875402
2019-05-21 05:38:03,876 [salt.state       :1813][INFO    ][12966] Executing state module.run for [maas.process_machines]
2019-05-21 05:38:03,876 [salt.utils.decorators:613 ][WARNING ][12966] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 05:38:04,433 [salt.loaded.ext.module.maas:412 ][WARNING ][12966] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 05:38:04,434 [salt.loaded.ext.module.maas:92  ][INFO    ][12966] 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=n4mq4a architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 05:38:05,619 [salt.loaded.ext.module.maas:412 ][WARNING ][12966] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 05:38:05,620 [salt.loaded.ext.module.maas:92  ][INFO    ][12966] machine hostname=cmp001 power_type=ipmi mac_addresses=['9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=xabcwd architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 05:38:06,884 [salt.loaded.ext.module.maas:412 ][WARNING ][12966] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 05:38:06,887 [salt.loaded.ext.module.maas:92  ][INFO    ][12966] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=6pmqbr architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 05:38:08,122 [salt.loaded.ext.module.maas:412 ][WARNING ][12966] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 05:38:08,123 [salt.loaded.ext.module.maas:92  ][INFO    ][12966] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=dypc66 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 05:38:08,804 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053808794611
2019-05-21 05:38:08,826 [salt.minion      :1432][INFO    ][13159] Starting a new job with PID 13159
2019-05-21 05:38:08,851 [salt.minion      :1711][INFO    ][13159] Returning information for job: 20190521053808794611
2019-05-21 05:38:09,497 [salt.loaded.ext.module.maas:412 ][WARNING ][12966] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 05:38:09,498 [salt.loaded.ext.module.maas:92  ][INFO    ][12966] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=7btd7h architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 05:38:10,711 [salt.state       :300 ][INFO    ][12966] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-21 05:38:10,712 [salt.state       :1951][INFO    ][12966] Completed state [maas.process_machines] at time 05:38:10.711999 duration_in_ms=6836.596
2019-05-21 05:38:10,716 [salt.minion      :1711][INFO    ][12966] Returning information for job: 20190521053753735213
2019-05-21 05:38:43,724 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command state.apply with jid 20190521053843713092
2019-05-21 05:38:43,752 [salt.minion      :1432][INFO    ][13239] Starting a new job with PID 13239
2019-05-21 05:38:51,964 [salt.state       :915 ][INFO    ][13239] Loading fresh modules for state activity
2019-05-21 05:38:52,075 [salt.state       :1780][INFO    ][13239] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:38:52.075221
2019-05-21 05:38:52,075 [salt.state       :1813][INFO    ][13239] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 05:38:52,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13239] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 05:38:53,771 [salt.state       :300 ][INFO    ][13239] {'pid': 13281, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 05:38:53,772 [salt.state       :1951][INFO    ][13239] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:38:53.772435 duration_in_ms=1697.213
2019-05-21 05:38:53,776 [salt.state       :1780][INFO    ][13239] Running state [maas.wait_for_machine_status] at time 05:38:53.776527
2019-05-21 05:38:53,777 [salt.state       :1813][INFO    ][13239] Executing state module.run for [maas.wait_for_machine_status]
2019-05-21 05:38:53,777 [salt.utils.decorators:613 ][WARNING ][13239] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 05:38:56,775 [salt.state       :300 ][INFO    ][13239] {'ret': True}
2019-05-21 05:38:56,775 [salt.state       :1951][INFO    ][13239] Completed state [maas.wait_for_machine_status] at time 05:38:56.775610 duration_in_ms=2999.084
2019-05-21 05:38:56,778 [salt.minion      :1711][INFO    ][13239] Returning information for job: 20190521053843713092
2019-05-21 05:38:57,322 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command state.apply with jid 20190521053857310524
2019-05-21 05:38:57,345 [salt.minion      :1432][INFO    ][13295] Starting a new job with PID 13295
2019-05-21 05:38:58,513 [salt.state       :915 ][INFO    ][13295] Loading fresh modules for state activity
2019-05-21 05:38:58,662 [salt.state       :1780][INFO    ][13295] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:38:58.662838
2019-05-21 05:38:58,663 [salt.state       :1813][INFO    ][13295] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 05:38:58,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13295] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 05:39:00,400 [salt.state       :300 ][INFO    ][13295] {'pid': 13302, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 05:39:00,401 [salt.state       :1951][INFO    ][13295] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:00.401458 duration_in_ms=1738.619
2019-05-21 05:39:00,406 [salt.state       :1780][INFO    ][13295] Running state [maas_machines_storage_cmp002_lvm] at time 05:39:00.406115
2019-05-21 05:39:00,406 [salt.state       :1813][INFO    ][13295] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-21 05:39:00,909 [salt.state       :300 ][INFO    ][13295] Machine cmp002 is not in Ready state.
2019-05-21 05:39:00,910 [salt.state       :1951][INFO    ][13295] Completed state [maas_machines_storage_cmp002_lvm] at time 05:39:00.910272 duration_in_ms=504.156
2019-05-21 05:39:00,910 [salt.state       :1780][INFO    ][13295] Running state [maas_machines_storage_cmp001_lvm] at time 05:39:00.910623
2019-05-21 05:39:00,910 [salt.state       :1813][INFO    ][13295] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-21 05:39:01,448 [salt.state       :300 ][INFO    ][13295] Machine cmp001 is not in Ready state.
2019-05-21 05:39:01,448 [salt.state       :1951][INFO    ][13295] Completed state [maas_machines_storage_cmp001_lvm] at time 05:39:01.448912 duration_in_ms=538.289
2019-05-21 05:39:01,452 [salt.minion      :1711][INFO    ][13295] Returning information for job: 20190521053857310524
2019-05-21 05:39:02,048 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command state.apply with jid 20190521053901986649
2019-05-21 05:39:02,074 [salt.minion      :1432][INFO    ][13318] Starting a new job with PID 13318
2019-05-21 05:39:03,257 [salt.state       :915 ][INFO    ][13318] Loading fresh modules for state activity
2019-05-21 05:39:03,364 [salt.state       :1780][INFO    ][13318] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:03.364224
2019-05-21 05:39:03,364 [salt.state       :1813][INFO    ][13318] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 05:39:03,366 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13318] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 05:39:05,067 [salt.state       :300 ][INFO    ][13318] {'pid': 13325, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 05:39:05,068 [salt.state       :1951][INFO    ][13318] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:05.068354 duration_in_ms=1704.13
2019-05-21 05:39:05,071 [salt.state       :1780][INFO    ][13318] Running state [maas.deploy_machines] at time 05:39:05.071522
2019-05-21 05:39:05,071 [salt.state       :1813][INFO    ][13318] Executing state module.run for [maas.deploy_machines]
2019-05-21 05:39:05,072 [salt.utils.decorators:613 ][WARNING ][13318] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 05:39:05,623 [salt.loaded.ext.module.maas:684 ][INFO    ][13318] deploymachines hwe_kernel=ga-16.04 system_id=7btd7h distro_series=xenial
2019-05-21 05:39:08,058 [salt.state       :300 ][INFO    ][13318] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-21 05:39:08,059 [salt.state       :1951][INFO    ][13318] Completed state [maas.deploy_machines] at time 05:39:08.059150 duration_in_ms=2987.627
2019-05-21 05:39:08,063 [salt.minion      :1711][INFO    ][13318] Returning information for job: 20190521053901986649
2019-05-21 05:39:08,632 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command state.apply with jid 20190521053908621157
2019-05-21 05:39:08,656 [salt.minion      :1432][INFO    ][13385] Starting a new job with PID 13385
2019-05-21 05:39:16,632 [salt.state       :915 ][INFO    ][13385] Loading fresh modules for state activity
2019-05-21 05:39:16,739 [salt.state       :1780][INFO    ][13385] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:16.739332
2019-05-21 05:39:16,739 [salt.state       :1813][INFO    ][13385] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 05:39:16,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13385] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 05:39:18,430 [salt.state       :300 ][INFO    ][13385] {'pid': 13401, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 05:39:18,431 [salt.state       :1951][INFO    ][13385] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:18.431214 duration_in_ms=1691.881
2019-05-21 05:39:18,434 [salt.state       :1780][INFO    ][13385] Running state [maas.wait_for_machine_status] at time 05:39:18.434636
2019-05-21 05:39:18,435 [salt.state       :1813][INFO    ][13385] Executing state module.run for [maas.wait_for_machine_status]
2019-05-21 05:39:18,436 [salt.utils.decorators:613 ][WARNING ][13385] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 05:39:21,145 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.30905008s left)
2019-05-21 05:39:23,711 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053923700611
2019-05-21 05:39:23,739 [salt.minion      :1432][INFO    ][13413] Starting a new job with PID 13413
2019-05-21 05:39:23,763 [salt.minion      :1711][INFO    ][13413] Returning information for job: 20190521053923700611
2019-05-21 05:39:53,789 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521053953772541
2019-05-21 05:39:53,814 [salt.minion      :1432][INFO    ][13469] Starting a new job with PID 13469
2019-05-21 05:39:53,837 [salt.minion      :1711][INFO    ][13469] Returning information for job: 20190521053953772541
2019-05-21 05:39:53,865 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.58866405s left)
2019-05-21 05:40:23,899 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054023883046
2019-05-21 05:40:23,934 [salt.minion      :1432][INFO    ][13488] Starting a new job with PID 13488
2019-05-21 05:40:23,956 [salt.minion      :1711][INFO    ][13488] Returning information for job: 20190521054023883046
2019-05-21 05:40:26,740 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.71413708s left)
2019-05-21 05:40:53,983 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054053971793
2019-05-21 05:40:54,011 [salt.minion      :1432][INFO    ][13561] Starting a new job with PID 13561
2019-05-21 05:40:54,032 [salt.minion      :1711][INFO    ][13561] Returning information for job: 20190521054053971793
2019-05-21 05:40:59,561 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.89240909s left)
2019-05-21 05:41:24,065 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054124047012
2019-05-21 05:41:24,092 [salt.minion      :1432][INFO    ][13587] Starting a new job with PID 13587
2019-05-21 05:41:24,116 [salt.minion      :1711][INFO    ][13587] Returning information for job: 20190521054124047012
2019-05-21 05:41:32,455 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.99890399s left)
2019-05-21 05:41:54,147 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054154134276
2019-05-21 05:41:54,178 [salt.minion      :1432][INFO    ][13645] Starting a new job with PID 13645
2019-05-21 05:41:54,200 [salt.minion      :1711][INFO    ][13645] Returning information for job: 20190521054154134276
2019-05-21 05:42:05,382 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.07168007s left)
2019-05-21 05:42:24,237 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054224218514
2019-05-21 05:42:24,263 [salt.minion      :1432][INFO    ][13670] Starting a new job with PID 13670
2019-05-21 05:42:24,288 [salt.minion      :1711][INFO    ][13670] Returning information for job: 20190521054224218514
2019-05-21 05:42:38,217 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.23726201s left)
2019-05-21 05:42:54,340 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054254327015
2019-05-21 05:42:54,370 [salt.minion      :1432][INFO    ][13728] Starting a new job with PID 13728
2019-05-21 05:42:54,399 [salt.minion      :1711][INFO    ][13728] Returning information for job: 20190521054254327015
2019-05-21 05:43:10,951 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.50283289s left)
2019-05-21 05:43:24,442 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054324428809
2019-05-21 05:43:24,464 [salt.minion      :1432][INFO    ][13774] Starting a new job with PID 13774
2019-05-21 05:43:24,500 [salt.minion      :1711][INFO    ][13774] Returning information for job: 20190521054324428809
2019-05-21 05:43:43,740 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.71419191s left)
2019-05-21 05:43:54,547 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054354531981
2019-05-21 05:43:54,575 [salt.minion      :1432][INFO    ][13853] Starting a new job with PID 13853
2019-05-21 05:43:54,601 [salt.minion      :1711][INFO    ][13853] Returning information for job: 20190521054354531981
2019-05-21 05:44:16,643 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.81087995s left)
2019-05-21 05:44:24,672 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054424658654
2019-05-21 05:44:24,697 [salt.minion      :1432][INFO    ][13879] Starting a new job with PID 13879
2019-05-21 05:44:24,716 [salt.minion      :1711][INFO    ][13879] Returning information for job: 20190521054424658654
2019-05-21 05:44:50,429 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.02481699s left)
2019-05-21 05:44:54,785 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054454770831
2019-05-21 05:44:54,815 [salt.minion      :1432][INFO    ][14053] Starting a new job with PID 14053
2019-05-21 05:44:54,838 [salt.minion      :1711][INFO    ][14053] Returning information for job: 20190521054454770831
2019-05-21 05:45:23,232 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.22185707s left)
2019-05-21 05:45:24,897 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054524888338
2019-05-21 05:45:24,925 [salt.minion      :1432][INFO    ][14076] Starting a new job with PID 14076
2019-05-21 05:45:24,951 [salt.minion      :1711][INFO    ][14076] Returning information for job: 20190521054524888338
2019-05-21 05:45:55,016 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054555007921
2019-05-21 05:45:55,032 [salt.minion      :1432][INFO    ][14194] Starting a new job with PID 14194
2019-05-21 05:45:55,063 [salt.minion      :1711][INFO    ][14194] Returning information for job: 20190521054555007921
2019-05-21 05:45:56,187 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.267138s left)
2019-05-21 05:46:25,147 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054625130790
2019-05-21 05:46:25,182 [salt.minion      :1432][INFO    ][14211] Starting a new job with PID 14211
2019-05-21 05:46:25,206 [salt.minion      :1711][INFO    ][14211] Returning information for job: 20190521054625130790
2019-05-21 05:46:29,382 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.07213092s left)
2019-05-21 05:46:55,317 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054655301938
2019-05-21 05:46:55,349 [salt.minion      :1432][INFO    ][14346] Starting a new job with PID 14346
2019-05-21 05:46:55,374 [salt.minion      :1711][INFO    ][14346] Returning information for job: 20190521054655301938
2019-05-21 05:47:02,256 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.19831705s left)
2019-05-21 05:47:25,474 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054725458359
2019-05-21 05:47:25,507 [salt.minion      :1432][INFO    ][14366] Starting a new job with PID 14366
2019-05-21 05:47:25,529 [salt.minion      :1711][INFO    ][14366] Returning information for job: 20190521054725458359
2019-05-21 05:47:35,083 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.37062311s left)
2019-05-21 05:47:55,637 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054755619980
2019-05-21 05:47:55,664 [salt.minion      :1432][INFO    ][14465] Starting a new job with PID 14465
2019-05-21 05:47:55,691 [salt.minion      :1711][INFO    ][14465] Returning information for job: 20190521054755619980
2019-05-21 05:48:08,024 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.42986298s left)
2019-05-21 05:48:25,801 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054825791054
2019-05-21 05:48:25,834 [salt.minion      :1432][INFO    ][14485] Starting a new job with PID 14485
2019-05-21 05:48:25,857 [salt.minion      :1711][INFO    ][14485] Returning information for job: 20190521054825791054
2019-05-21 05:48:41,105 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.34907007s left)
2019-05-21 05:48:55,961 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054855945489
2019-05-21 05:48:55,988 [salt.minion      :1432][INFO    ][14542] Starting a new job with PID 14542
2019-05-21 05:48:56,010 [salt.minion      :1711][INFO    ][14542] Returning information for job: 20190521054855945489
2019-05-21 05:49:13,878 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.5758481s left)
2019-05-21 05:49:26,128 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054926118456
2019-05-21 05:49:26,155 [salt.minion      :1432][INFO    ][14563] Starting a new job with PID 14563
2019-05-21 05:49:26,181 [salt.minion      :1711][INFO    ][14563] Returning information for job: 20190521054926118456
2019-05-21 05:49:46,712 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.74151397s left)
2019-05-21 05:49:56,296 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521054956287050
2019-05-21 05:49:56,316 [salt.minion      :1432][INFO    ][14620] Starting a new job with PID 14620
2019-05-21 05:49:56,340 [salt.minion      :1711][INFO    ][14620] Returning information for job: 20190521054956287050
2019-05-21 05:50:19,714 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.73974204s left)
2019-05-21 05:50:26,473 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521055026456811
2019-05-21 05:50:26,502 [salt.minion      :1432][INFO    ][14642] Starting a new job with PID 14642
2019-05-21 05:50:26,528 [salt.minion      :1711][INFO    ][14642] Returning information for job: 20190521055026456811
2019-05-21 05:50:37,985 [salt.utils.schedule:1377][INFO    ][2820] Running scheduled job: __mine_interval
2019-05-21 05:50:52,810 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.64397502s left)
2019-05-21 05:50:56,668 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521055056655563
2019-05-21 05:50:56,688 [salt.minion      :1432][INFO    ][14746] Starting a new job with PID 14746
2019-05-21 05:50:56,717 [salt.minion      :1711][INFO    ][14746] Returning information for job: 20190521055056655563
2019-05-21 05:51:25,986 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.4677949s left)
2019-05-21 05:51:26,836 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521055126825686
2019-05-21 05:51:26,857 [salt.minion      :1432][INFO    ][14919] Starting a new job with PID 14919
2019-05-21 05:51:26,880 [salt.minion      :1711][INFO    ][14919] Returning information for job: 20190521055126825686
2019-05-21 05:51:56,923 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521055156910668
2019-05-21 05:51:56,947 [salt.minion      :1432][INFO    ][15114] Starting a new job with PID 15114
2019-05-21 05:51:56,971 [salt.minion      :1711][INFO    ][15114] Returning information for job: 20190521055156910668
2019-05-21 05:51:59,119 [salt.state       :300 ][INFO    ][13385] {'ret': True}
2019-05-21 05:51:59,120 [salt.state       :1951][INFO    ][13385] Completed state [maas.wait_for_machine_status] at time 05:51:59.120152 duration_in_ms=760685.494
2019-05-21 05:51:59,130 [salt.minion      :1711][INFO    ][13385] Returning information for job: 20190521053908621157
2019-05-21 06:50:37,984 [salt.utils.schedule:1377][INFO    ][2820] Running scheduled job: __mine_interval
2019-05-21 07:25:46,726 [salt.minion      :1308][INFO    ][2820] User sudo_ubuntu Executing command cp.push_dir with jid 20190521072546713637
2019-05-21 07:25:46,749 [salt.minion      :1432][INFO    ][21714] Starting a new job with PID 21714
