2019-04-16 08:39:39,243 [salt.minion      :870 ][ERROR   ][383] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-16 08:40:29,288 [salt.minion      :870 ][ERROR   ][383] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-16 08:41:19,324 [salt.minion      :870 ][ERROR   ][383] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-16 08:42:09,369 [salt.minion      :870 ][ERROR   ][383] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-16 08:42:59,417 [salt.minion      :870 ][ERROR   ][383] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-16 08:43:49,460 [salt.minion      :870 ][ERROR   ][383] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-16 08:45:17,705 [salt.utils.decorators:613 ][WARNING ][2930] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 08:45:18,780 [salt.utils.decorators:613 ][WARNING ][2930] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 08:45:29,652 [salt.loaded.int.states.file:2298][WARNING ][3064] 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-04-16 08:45:33,684 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3278] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-16 08:45:33,708 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3278] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-16 08:45:33,752 [salt.utils.parsers:1051][WARNING ][383] Minion received a SIGTERM. Exiting.
2019-04-16 08:45:34,922 [salt.cli.daemons :293 ][INFO    ][3440] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-04-16 08:45:35,096 [salt.cli.daemons :82  ][INFO    ][3440] Starting up the Salt Minion
2019-04-16 08:45:35,096 [salt.utils.event :1017][INFO    ][3440] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-04-16 08:45:36,404 [salt.minion      :976 ][INFO    ][3440] Creating minion process manager
2019-04-16 08:45:37,526 [salt.state       :2022][WARNING ][3283] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-16 08:45:39,054 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3440] Executing command ['date', '+%z'] in directory '/root'
2019-04-16 08:45:39,078 [salt.utils.schedule:568 ][INFO    ][3440] Updating job settings for scheduled job: __mine_interval
2019-04-16 08:45:39,080 [salt.minion      :1108][INFO    ][3440] Added mine.update to scheduler
2019-04-16 08:45:39,085 [salt.minion      :1975][INFO    ][3440] Minion is starting as user 'root'
2019-04-16 08:45:39,104 [salt.minion      :2336][INFO    ][3440] Minion is ready to receive requests!
2019-04-16 08:45:40,787 [salt.utils.decorators:613 ][WARNING ][3283] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 08:45:45,900 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416084545887975
2019-04-16 08:45:45,935 [salt.minion      :1432][INFO    ][3974] Starting a new job with PID 3974
2019-04-16 08:45:45,986 [salt.minion      :1711][INFO    ][3974] Returning information for job: 20190416084545887975
2019-04-16 08:45:52,021 [salt.utils.decorators:613 ][WARNING ][3283] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 08:46:16,033 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416084616021969
2019-04-16 08:46:16,056 [salt.minion      :1432][INFO    ][4702] Starting a new job with PID 4702
2019-04-16 08:46:16,103 [salt.minion      :1711][INFO    ][4702] Returning information for job: 20190416084616021969
2019-04-16 08:46:17,744 [salt.utils.decorators:613 ][WARNING ][3283] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 08:46:18,644 [salt.utils.decorators:613 ][WARNING ][3283] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 08:46:20,119 [salt.loaded.ext.module.maasng:1008][WARNING ][3283] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-16 08:46:20,119 [salt.loaded.ext.module.maasng:1011][WARNING ][3283] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-16 08:46:20,932 [salt.loaded.ext.module.maasng:1235][WARNING ][3283] Ignoring parameter vlan:0
2019-04-16 08:46:22,608 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command state.apply with jid 20190416084622596716
2019-04-16 08:46:22,634 [salt.minion      :1432][INFO    ][4919] Starting a new job with PID 4919
2019-04-16 08:46:30,888 [salt.state       :915 ][INFO    ][4919] Loading fresh modules for state activity
2019-04-16 08:46:30,953 [salt.fileclient  :1219][INFO    ][4919] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-04-16 08:46:31,002 [salt.state       :1780][INFO    ][4919] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:46:31.002456
2019-04-16 08:46:31,002 [salt.state       :1813][INFO    ][4919] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 08:46:31,004 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4919] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 08:46:33,016 [salt.state       :300 ][INFO    ][4919] {'pid': 4945, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 08:46:33,017 [salt.state       :1951][INFO    ][4919] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:46:33.016927 duration_in_ms=2014.47
2019-04-16 08:46:33,020 [salt.state       :1780][INFO    ][4919] Running state [maas.process_machines] at time 08:46:33.020006
2019-04-16 08:46:33,020 [salt.state       :1813][INFO    ][4919] Executing state module.run for [maas.process_machines]
2019-04-16 08:46:33,021 [salt.utils.decorators:613 ][WARNING ][4919] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 08:46:33,083 [salt.loaded.ext.module.maas:412 ][WARNING ][4919] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 08:46:33,084 [salt.loaded.ext.module.maas:92  ][INFO    ][4919] 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-04-16 08:46:34,512 [salt.loaded.ext.module.maas:412 ][WARNING ][4919] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 08:46:34,512 [salt.loaded.ext.module.maas:92  ][INFO    ][4919] 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-04-16 08:46:35,819 [salt.loaded.ext.module.maas:412 ][WARNING ][4919] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 08:46:35,820 [salt.loaded.ext.module.maas:92  ][INFO    ][4919] 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-04-16 08:46:37,100 [salt.loaded.ext.module.maas:412 ][WARNING ][4919] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 08:46:37,100 [salt.loaded.ext.module.maas:92  ][INFO    ][4919] 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-04-16 08:46:37,637 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416084637625634
2019-04-16 08:46:37,656 [salt.minion      :1432][INFO    ][5170] Starting a new job with PID 5170
2019-04-16 08:46:37,701 [salt.minion      :1711][INFO    ][5170] Returning information for job: 20190416084637625634
2019-04-16 08:46:38,457 [salt.loaded.ext.module.maas:412 ][WARNING ][4919] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 08:46:38,457 [salt.loaded.ext.module.maas:92  ][INFO    ][4919] 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-04-16 08:46:39,598 [salt.state       :300 ][INFO    ][4919] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-16 08:46:39,599 [salt.state       :1951][INFO    ][4919] Completed state [maas.process_machines] at time 08:46:39.599451 duration_in_ms=6579.444
2019-04-16 08:46:39,602 [salt.minion      :1711][INFO    ][4919] Returning information for job: 20190416084622596716
2019-04-16 08:47:22,890 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command state.apply with jid 20190416084722876636
2019-04-16 08:47:22,905 [salt.minion      :1432][INFO    ][5314] Starting a new job with PID 5314
2019-04-16 08:47:30,909 [salt.state       :915 ][INFO    ][5314] Loading fresh modules for state activity
2019-04-16 08:47:30,961 [salt.fileclient  :1219][INFO    ][5314] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-16 08:47:31,010 [salt.state       :1780][INFO    ][5314] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:47:31.010474
2019-04-16 08:47:31,011 [salt.state       :1813][INFO    ][5314] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 08:47:31,014 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5314] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 08:47:33,041 [salt.state       :300 ][INFO    ][5314] {'pid': 5322, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 08:47:33,042 [salt.state       :1951][INFO    ][5314] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:47:33.042126 duration_in_ms=2031.652
2019-04-16 08:47:33,046 [salt.state       :1780][INFO    ][5314] Running state [maas.wait_for_machine_status] at time 08:47:33.046190
2019-04-16 08:47:33,047 [salt.state       :1813][INFO    ][5314] Executing state module.run for [maas.wait_for_machine_status]
2019-04-16 08:47:33,047 [salt.utils.decorators:613 ][WARNING ][5314] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 08:47:33,840 [salt.loaded.ext.module.maas:1023][INFO    ][5314] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.21984696s left)
2019-04-16 08:47:37,977 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416084737964806
2019-04-16 08:47:37,997 [salt.minion      :1432][INFO    ][5361] Starting a new job with PID 5361
2019-04-16 08:47:38,023 [salt.minion      :1711][INFO    ][5361] Returning information for job: 20190416084737964806
2019-04-16 08:48:04,609 [salt.loaded.ext.module.maas:1023][INFO    ][5314] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.45113206s left)
2019-04-16 08:48:08,238 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416084808184168
2019-04-16 08:48:08,252 [salt.minion      :1432][INFO    ][5411] Starting a new job with PID 5411
2019-04-16 08:48:08,281 [salt.minion      :1711][INFO    ][5411] Returning information for job: 20190416084808184168
2019-04-16 08:48:35,374 [salt.loaded.ext.module.maas:1023][INFO    ][5314] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.68596792s left)
2019-04-16 08:48:38,325 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416084838315462
2019-04-16 08:48:38,349 [salt.minion      :1432][INFO    ][5465] Starting a new job with PID 5465
2019-04-16 08:48:38,374 [salt.minion      :1711][INFO    ][5465] Returning information for job: 20190416084838315462
2019-04-16 08:49:06,341 [salt.loaded.ext.module.maas:1023][INFO    ][5314] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.71943593s left)
2019-04-16 08:49:08,386 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416084908372500
2019-04-16 08:49:08,408 [salt.minion      :1432][INFO    ][5542] Starting a new job with PID 5542
2019-04-16 08:49:08,433 [salt.minion      :1711][INFO    ][5542] Returning information for job: 20190416084908372500
2019-04-16 08:49:37,415 [salt.loaded.ext.module.maas:1023][INFO    ][5314] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.64537406s left)
2019-04-16 08:49:38,466 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416084938450131
2019-04-16 08:49:38,483 [salt.minion      :1432][INFO    ][5655] Starting a new job with PID 5655
2019-04-16 08:49:38,510 [salt.minion      :1711][INFO    ][5655] Returning information for job: 20190416084938450131
2019-04-16 08:50:08,469 [salt.loaded.ext.module.maas:1023][INFO    ][5314] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.59046006s left)
2019-04-16 08:50:08,541 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085008530487
2019-04-16 08:50:08,555 [salt.minion      :1432][INFO    ][5872] Starting a new job with PID 5872
2019-04-16 08:50:08,581 [salt.minion      :1711][INFO    ][5872] Returning information for job: 20190416085008530487
2019-04-16 08:50:38,616 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085038605732
2019-04-16 08:50:38,630 [salt.minion      :1432][INFO    ][5959] Starting a new job with PID 5959
2019-04-16 08:50:38,658 [salt.minion      :1711][INFO    ][5959] Returning information for job: 20190416085038605732
2019-04-16 08:50:39,769 [salt.loaded.ext.module.maas:1023][INFO    ][5314] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.29067898s left)
2019-04-16 08:51:08,709 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085108697493
2019-04-16 08:51:08,732 [salt.minion      :1432][INFO    ][6250] Starting a new job with PID 6250
2019-04-16 08:51:08,756 [salt.minion      :1711][INFO    ][6250] Returning information for job: 20190416085108697493
2019-04-16 08:51:11,741 [salt.loaded.ext.module.maas:1023][INFO    ][5314] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.31866288s left)
2019-04-16 08:51:38,821 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085138802248
2019-04-16 08:51:38,842 [salt.minion      :1432][INFO    ][6468] Starting a new job with PID 6468
2019-04-16 08:51:38,868 [salt.minion      :1711][INFO    ][6468] Returning information for job: 20190416085138802248
2019-04-16 08:51:44,608 [salt.loaded.ext.module.maas:1023][INFO    ][5314] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1248.45198107s left)
2019-04-16 08:52:08,974 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085208962923
2019-04-16 08:52:08,995 [salt.minion      :1432][INFO    ][6913] Starting a new job with PID 6913
2019-04-16 08:52:09,018 [salt.minion      :1711][INFO    ][6913] Returning information for job: 20190416085208962923
2019-04-16 08:52:16,804 [salt.loaded.ext.module.maas:1023][INFO    ][5314] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.25547695s left)
2019-04-16 08:52:39,068 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085239054589
2019-04-16 08:52:39,091 [salt.minion      :1432][INFO    ][7044] Starting a new job with PID 7044
2019-04-16 08:52:39,113 [salt.minion      :1711][INFO    ][7044] Returning information for job: 20190416085239054589
2019-04-16 08:52:49,833 [salt.state       :300 ][INFO    ][5314] {'ret': True}
2019-04-16 08:52:49,834 [salt.state       :1951][INFO    ][5314] Completed state [maas.wait_for_machine_status] at time 08:52:49.834238 duration_in_ms=316788.048
2019-04-16 08:52:49,838 [salt.minion      :1711][INFO    ][5314] Returning information for job: 20190416084722876636
2019-04-16 08:52:50,389 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command state.apply with jid 20190416085250381089
2019-04-16 08:52:50,410 [salt.minion      :1432][INFO    ][7152] Starting a new job with PID 7152
2019-04-16 08:52:58,466 [salt.state       :915 ][INFO    ][7152] Loading fresh modules for state activity
2019-04-16 08:52:58,519 [salt.fileclient  :1219][INFO    ][7152] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-16 08:52:58,614 [salt.state       :1780][INFO    ][7152] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:52:58.614347
2019-04-16 08:52:58,614 [salt.state       :1813][INFO    ][7152] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 08:52:58,616 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7152] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 08:53:00,380 [salt.state       :300 ][INFO    ][7152] {'pid': 7166, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 08:53:00,381 [salt.state       :1951][INFO    ][7152] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:53:00.381273 duration_in_ms=1766.925
2019-04-16 08:53:00,386 [salt.state       :1780][INFO    ][7152] Running state [maas_machines_storage_cmp002_lvm] at time 08:53:00.385946
2019-04-16 08:53:00,386 [salt.state       :1813][INFO    ][7152] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-16 08:53:01,499 [salt.loaded.ext.module.maasng:610 ][INFO    ][7152] hyfth4
2019-04-16 08:53:01,500 [salt.loaded.ext.module.maasng:626 ][INFO    ][7152] sda
2019-04-16 08:53:02,093 [salt.loaded.ext.module.maasng:361 ][INFO    ][7152] hyfth4
2019-04-16 08:53:02,180 [salt.loaded.ext.module.maasng:367 ][INFO    ][7152] [{u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'system_id': u'hyfth4', u'used_size': 800106479616, u'partitions': [{u'uuid': u'd6749463-0696-485e-a7ed-eebdbfc62861', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hyfth4', u'device_id': 1, u'filesystem': {u'label': None, u'uuid': u'8a7106eb-ba1e-419e-a635-60d88ead3c2e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, 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/hyfth4/blockdevices/1/partition/1'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id': 1, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/blockdevices/3/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'hyfth4', u'used_size': 800097042432, u'partitions': [], u'uuid': u'a4798065-9b35-4dba-a9e0-8c92e46cda51', u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 3, u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'7a7198ca-3dd6-4e18-93ef-bfb521a5931a', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2019-04-16 08:53:02,180 [salt.loaded.ext.module.maasng:632 ][INFO    ][7152] vgroot
2019-04-16 08:53:02,181 [salt.loaded.ext.module.maasng:635 ][INFO    ][7152] lvroot
2019-04-16 08:53:02,181 [salt.loaded.ext.module.maasng:639 ][INFO    ][7152] 107374182400
2019-04-16 08:53:02,792 [salt.loaded.ext.module.maasng:645 ][INFO    ][7152] {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'status_action': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 40, 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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tx6trw', 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'id': 17, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tx6trw', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tx6trw', 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'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'hyfth4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/interfaces/4/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hyfth4', u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'a8fc0911-aa91-4977-9329-a517a13e88db', u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hyfth4', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'5a5dec68-e932-4f27-81fb-04529818f32a', 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'device_id': 1}], u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'name': u'sda', u'system_id': u'hyfth4', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'a8fc0911-aa91-4977-9329-a517a13e88db', u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hyfth4', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'5a5dec68-e932-4f27-81fb-04529818f32a', 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'device_id': 1}]}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'fa9aa234-ad74-4c82-ac30-6b9245e7bf06', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'name': u'vgroot-lvroot', u'system_id': u'hyfth4', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'55f5907d-f82c-47fb-8d18-7a38eb8913ac', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/hyfth4/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'hyfth4', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'partition_table_type': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'fa9aa234-ad74-4c82-ac30-6b9245e7bf06', u'tags': [], u'type': u'virtual', u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hyfth4', u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/blockdevices/11/', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'55f5907d-f82c-47fb-8d18-7a38eb8913ac', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'serial': None, u'name': u'vgroot-lvroot'}], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hyfth4', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, 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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tx6trw', 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'id': 17, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tx6trw', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tx6trw', 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'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'hyfth4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 14, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'hyfth4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/interfaces/14/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 15, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'hyfth4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/interfaces/15/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tx6trw', 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': 18, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tx6trw', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'id': 11, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tx6trw', 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'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'hyfth4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/interfaces/11/'}, {u'name': u'ens2f1', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'id': 12, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'hyfth4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/interfaces/12/'}, {u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'id': 13, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'hyfth4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/interfaces/13/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hyfth4', u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'a8fc0911-aa91-4977-9329-a517a13e88db', u'resource_uri': u'/MAAS/api/2.0/nodes/hyfth4/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hyfth4', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'5a5dec68-e932-4f27-81fb-04529818f32a', 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'device_id': 1}], u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'architecture': u'amd64/generic', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-04-16 08:53:02,795 [salt.state       :300 ][INFO    ][7152] {'new': {'storage_layout': 'lvm'}}
2019-04-16 08:53:02,795 [salt.state       :1951][INFO    ][7152] Completed state [maas_machines_storage_cmp002_lvm] at time 08:53:02.795604 duration_in_ms=2409.656
2019-04-16 08:53:02,796 [salt.state       :1780][INFO    ][7152] Running state [maas_machines_storage_cmp001_lvm] at time 08:53:02.796184
2019-04-16 08:53:02,796 [salt.state       :1813][INFO    ][7152] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-16 08:53:03,917 [salt.loaded.ext.module.maasng:610 ][INFO    ][7152] 64cna7
2019-04-16 08:53:03,917 [salt.loaded.ext.module.maasng:626 ][INFO    ][7152] sda
2019-04-16 08:53:04,481 [salt.loaded.ext.module.maasng:361 ][INFO    ][7152] 64cna7
2019-04-16 08:53:04,565 [salt.loaded.ext.module.maasng:367 ][INFO    ][7152] [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'64cna7', 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'partitions': [{u'uuid': u'40bed84d-8736-45e3-8e21-e05ff8c7b558', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64cna7', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'60a46da1-c7ce-4852-acbc-608f37c277c5', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/blockdevices/2/partition/2'}], u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/blockdevices/2/'}, {u'size': 800097042432, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 4, u'uuid': u'eb0ba2a7-2bb2-4abf-ae56-83355503022f', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'64cna7', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'333bd5a1-1534-40ec-9fc8-4ab9aeefe4c8', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'used_size': 800097042432, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/blockdevices/4/'}]
2019-04-16 08:53:04,565 [salt.loaded.ext.module.maasng:632 ][INFO    ][7152] vgroot
2019-04-16 08:53:04,565 [salt.loaded.ext.module.maasng:635 ][INFO    ][7152] lvroot
2019-04-16 08:53:04,565 [salt.loaded.ext.module.maasng:639 ][INFO    ][7152] 107374182400
2019-04-16 08:53:05,153 [salt.loaded.ext.module.maasng:645 ][INFO    ][7152] {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.39', u'192.168.11.41'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {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'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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'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'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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'enabled': True, u'id': 5, u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'64cna7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'available_size': 0, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/blockdevices/2/', u'system_id': u'64cna7', u'used_size': 800106479616, u'partitions': [{u'uuid': u'48095b5a-f6ec-4245-bd92-c79058af740e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64cna7', u'device_id': 2, u'filesystem': {u'label': None, u'uuid': u'4baf93ea-4236-453a-bcee-c211186232ef', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/blockdevices/2/partition/7'}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'id': 2, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'system_id': u'64cna7', u'used_size': 800106479616, u'partitions': [{u'uuid': u'48095b5a-f6ec-4245-bd92-c79058af740e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64cna7', u'device_id': 2, u'filesystem': {u'label': None, u'uuid': u'4baf93ea-4236-453a-bcee-c211186232ef', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id': 2, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/blockdevices/12/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'64cna7', u'used_size': 107374182400, u'partitions': [], u'uuid': u'634b01e6-7137-46fe-a7c9-c1d42821630f', u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 12, u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'625c4081-655b-454d-9cdd-e32f18dbd95c', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'64cna7', 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'bcaches': [], u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'available_size': 0, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/blockdevices/2/', u'system_id': u'64cna7', u'used_size': 800106479616, u'partitions': [{u'uuid': u'48095b5a-f6ec-4245-bd92-c79058af740e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64cna7', u'device_id': 2, u'filesystem': {u'label': None, u'uuid': u'4baf93ea-4236-453a-bcee-c211186232ef', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/blockdevices/2/partition/7'}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'id': 2, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'64cna7', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'disable_ipv4': False, u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'available_size': 0, u'uuid': u'634b01e6-7137-46fe-a7c9-c1d42821630f', u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/blockdevices/12/', u'system_id': u'64cna7', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 12, u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'625c4081-655b-454d-9cdd-e32f18dbd95c', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{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'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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'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'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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'enabled': True, u'id': 5, u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'64cna7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/interfaces/5/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 19, u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'system_id': u'64cna7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/interfaces/19/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 20, u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'system_id': u'64cna7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/interfaces/20/'}, {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'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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'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'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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'enabled': True, u'id': 16, u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'system_id': u'64cna7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/interfaces/16/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'primary_rack': None, u'fabric': u'fabric-0', 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'enabled': True, u'id': 17, u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'system_id': u'64cna7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/interfaces/17/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'primary_rack': None, u'fabric': u'fabric-0', 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'enabled': True, u'id': 18, u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'system_id': u'64cna7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64cna7/interfaces/18/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/64cna7/'}
2019-04-16 08:53:05,155 [salt.state       :300 ][INFO    ][7152] {'new': {'storage_layout': 'lvm'}}
2019-04-16 08:53:05,156 [salt.state       :1951][INFO    ][7152] Completed state [maas_machines_storage_cmp001_lvm] at time 08:53:05.156271 duration_in_ms=2360.087
2019-04-16 08:53:05,159 [salt.minion      :1711][INFO    ][7152] Returning information for job: 20190416085250381089
2019-04-16 08:53:05,705 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command state.apply with jid 20190416085305693186
2019-04-16 08:53:05,723 [salt.minion      :1432][INFO    ][7206] Starting a new job with PID 7206
2019-04-16 08:53:06,900 [salt.state       :915 ][INFO    ][7206] Loading fresh modules for state activity
2019-04-16 08:53:06,958 [salt.fileclient  :1219][INFO    ][7206] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-16 08:53:07,006 [salt.state       :1780][INFO    ][7206] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:53:07.006028
2019-04-16 08:53:07,006 [salt.state       :1813][INFO    ][7206] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 08:53:07,008 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7206] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 08:53:08,791 [salt.state       :300 ][INFO    ][7206] {'pid': 7213, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 08:53:08,792 [salt.state       :1951][INFO    ][7206] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:53:08.792430 duration_in_ms=1786.402
2019-04-16 08:53:08,793 [salt.state       :1780][INFO    ][7206] Running state [maas.deploy_machines] at time 08:53:08.793949
2019-04-16 08:53:08,794 [salt.state       :1813][INFO    ][7206] Executing state module.run for [maas.deploy_machines]
2019-04-16 08:53:08,795 [salt.utils.decorators:613 ][WARNING ][7206] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 08:53:09,353 [salt.loaded.ext.module.maas:684 ][INFO    ][7206] deploymachines hwe_kernel=ga-16.04 system_id=hyfth4 distro_series=xenial
2019-04-16 08:53:11,673 [salt.loaded.ext.module.maas:684 ][INFO    ][7206] deploymachines hwe_kernel=ga-16.04 system_id=64cna7 distro_series=xenial
2019-04-16 08:53:14,033 [salt.loaded.ext.module.maas:684 ][INFO    ][7206] deploymachines hwe_kernel=ga-16.04 system_id=tfdnxc distro_series=xenial
2019-04-16 08:53:16,378 [salt.loaded.ext.module.maas:684 ][INFO    ][7206] deploymachines hwe_kernel=ga-16.04 system_id=be8xqk distro_series=xenial
2019-04-16 08:53:18,812 [salt.loaded.ext.module.maas:684 ][INFO    ][7206] deploymachines hwe_kernel=ga-16.04 system_id=x4h4k4 distro_series=xenial
2019-04-16 08:53:20,740 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085320730413
2019-04-16 08:53:20,759 [salt.minion      :1432][INFO    ][7484] Starting a new job with PID 7484
2019-04-16 08:53:20,783 [salt.minion      :1711][INFO    ][7484] Returning information for job: 20190416085320730413
2019-04-16 08:53:21,200 [salt.state       :300 ][INFO    ][7206] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-16 08:53:21,200 [salt.state       :1951][INFO    ][7206] Completed state [maas.deploy_machines] at time 08:53:21.200577 duration_in_ms=12406.628
2019-04-16 08:53:21,202 [salt.minion      :1711][INFO    ][7206] Returning information for job: 20190416085305693186
2019-04-16 08:53:21,762 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command state.apply with jid 20190416085321751341
2019-04-16 08:53:21,778 [salt.minion      :1432][INFO    ][7501] Starting a new job with PID 7501
2019-04-16 08:53:30,007 [salt.state       :915 ][INFO    ][7501] Loading fresh modules for state activity
2019-04-16 08:53:30,058 [salt.fileclient  :1219][INFO    ][7501] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-16 08:53:30,101 [salt.state       :1780][INFO    ][7501] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:53:30.101564
2019-04-16 08:53:30,101 [salt.state       :1813][INFO    ][7501] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 08:53:30,103 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7501] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 08:53:31,964 [salt.state       :300 ][INFO    ][7501] {'pid': 7518, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 08:53:31,965 [salt.state       :1951][INFO    ][7501] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:53:31.965244 duration_in_ms=1863.68
2019-04-16 08:53:31,968 [salt.state       :1780][INFO    ][7501] Running state [maas.wait_for_machine_status] at time 08:53:31.967976
2019-04-16 08:53:31,968 [salt.state       :1813][INFO    ][7501] Executing state module.run for [maas.wait_for_machine_status]
2019-04-16 08:53:31,968 [salt.utils.decorators:613 ][WARNING ][7501] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 08:53:34,934 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.043854s left)
2019-04-16 08:53:36,865 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085336855289
2019-04-16 08:53:36,885 [salt.minion      :1432][INFO    ][7556] Starting a new job with PID 7556
2019-04-16 08:53:36,908 [salt.minion      :1711][INFO    ][7556] Returning information for job: 20190416085336855289
2019-04-16 08:54:06,921 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085406912341
2019-04-16 08:54:06,936 [salt.minion      :1432][INFO    ][7605] Starting a new job with PID 7605
2019-04-16 08:54:06,959 [salt.minion      :1711][INFO    ][7605] Returning information for job: 20190416085406912341
2019-04-16 08:54:07,838 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.13987708s left)
2019-04-16 08:54:36,993 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085436978460
2019-04-16 08:54:37,015 [salt.minion      :1432][INFO    ][7654] Starting a new job with PID 7654
2019-04-16 08:54:37,038 [salt.minion      :1711][INFO    ][7654] Returning information for job: 20190416085436978460
2019-04-16 08:54:40,663 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.31514502s left)
2019-04-16 08:55:07,060 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085507047733
2019-04-16 08:55:07,081 [salt.minion      :1432][INFO    ][7710] Starting a new job with PID 7710
2019-04-16 08:55:07,105 [salt.minion      :1711][INFO    ][7710] Returning information for job: 20190416085507047733
2019-04-16 08:55:13,380 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.59749198s left)
2019-04-16 08:55:37,112 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085537105389
2019-04-16 08:55:37,126 [salt.minion      :1432][INFO    ][7768] Starting a new job with PID 7768
2019-04-16 08:55:37,147 [salt.minion      :1711][INFO    ][7768] Returning information for job: 20190416085537105389
2019-04-16 08:55:46,316 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.66196704s left)
2019-04-16 08:56:07,309 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085607293307
2019-04-16 08:56:07,329 [salt.minion      :1432][INFO    ][7846] Starting a new job with PID 7846
2019-04-16 08:56:07,351 [salt.minion      :1711][INFO    ][7846] Returning information for job: 20190416085607293307
2019-04-16 08:56:19,154 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.82335806s left)
2019-04-16 08:56:37,520 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085637508646
2019-04-16 08:56:37,536 [salt.minion      :1432][INFO    ][7964] Starting a new job with PID 7964
2019-04-16 08:56:37,556 [salt.minion      :1711][INFO    ][7964] Returning information for job: 20190416085637508646
2019-04-16 08:56:52,200 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.7777431s left)
2019-04-16 08:57:07,624 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085707608562
2019-04-16 08:57:07,646 [salt.minion      :1432][INFO    ][8229] Starting a new job with PID 8229
2019-04-16 08:57:07,676 [salt.minion      :1711][INFO    ][8229] Returning information for job: 20190416085707608562
2019-04-16 08:57:24,955 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.02236104s left)
2019-04-16 08:57:37,702 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085737688610
2019-04-16 08:57:37,723 [salt.minion      :1432][INFO    ][8318] Starting a new job with PID 8318
2019-04-16 08:57:37,744 [salt.minion      :1711][INFO    ][8318] Returning information for job: 20190416085737688610
2019-04-16 08:57:57,904 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.0731101s left)
2019-04-16 08:58:07,797 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085807785960
2019-04-16 08:58:07,819 [salt.minion      :1432][INFO    ][8555] Starting a new job with PID 8555
2019-04-16 08:58:07,847 [salt.minion      :1711][INFO    ][8555] Returning information for job: 20190416085807785960
2019-04-16 08:58:30,860 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.11763501s left)
2019-04-16 08:58:37,919 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085837906357
2019-04-16 08:58:37,940 [salt.minion      :1432][INFO    ][8616] Starting a new job with PID 8616
2019-04-16 08:58:37,964 [salt.minion      :1711][INFO    ][8616] Returning information for job: 20190416085837906357
2019-04-16 08:59:03,998 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.97950912s left)
2019-04-16 08:59:08,028 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085908020888
2019-04-16 08:59:08,041 [salt.minion      :1432][INFO    ][9143] Starting a new job with PID 9143
2019-04-16 08:59:08,064 [salt.minion      :1711][INFO    ][9143] Returning information for job: 20190416085908020888
2019-04-16 08:59:36,835 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.14281297s left)
2019-04-16 08:59:38,132 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416085938119960
2019-04-16 08:59:38,150 [salt.minion      :1432][INFO    ][9224] Starting a new job with PID 9224
2019-04-16 08:59:38,177 [salt.minion      :1711][INFO    ][9224] Returning information for job: 20190416085938119960
2019-04-16 09:00:08,281 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090008275058
2019-04-16 09:00:08,300 [salt.minion      :1432][INFO    ][9636] Starting a new job with PID 9636
2019-04-16 09:00:08,322 [salt.minion      :1711][INFO    ][9636] Returning information for job: 20190416090008275058
2019-04-16 09:00:09,690 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1852.28775311s left)
2019-04-16 09:00:38,400 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090038385349
2019-04-16 09:00:38,420 [salt.minion      :1432][INFO    ][9693] Starting a new job with PID 9693
2019-04-16 09:00:38,444 [salt.minion      :1711][INFO    ][9693] Returning information for job: 20190416090038385349
2019-04-16 09:00:43,630 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.34750915s left)
2019-04-16 09:01:08,560 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090108541093
2019-04-16 09:01:08,579 [salt.minion      :1432][INFO    ][9966] Starting a new job with PID 9966
2019-04-16 09:01:08,601 [salt.minion      :1711][INFO    ][9966] Returning information for job: 20190416090108541093
2019-04-16 09:01:16,500 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.477633s left)
2019-04-16 09:01:38,689 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090138676376
2019-04-16 09:01:38,710 [salt.minion      :1432][INFO    ][10026] Starting a new job with PID 10026
2019-04-16 09:01:38,735 [salt.minion      :1711][INFO    ][10026] Returning information for job: 20190416090138676376
2019-04-16 09:01:49,376 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1752.60180712s left)
2019-04-16 09:02:08,837 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090208829510
2019-04-16 09:02:08,851 [salt.minion      :1432][INFO    ][10238] Starting a new job with PID 10238
2019-04-16 09:02:08,870 [salt.minion      :1711][INFO    ][10238] Returning information for job: 20190416090208829510
2019-04-16 09:02:22,207 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1719.77069902s left)
2019-04-16 09:02:38,989 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090238977047
2019-04-16 09:02:39,011 [salt.minion      :1432][INFO    ][10321] Starting a new job with PID 10321
2019-04-16 09:02:39,034 [salt.minion      :1711][INFO    ][10321] Returning information for job: 20190416090238977047
2019-04-16 09:02:54,918 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1687.05948806s left)
2019-04-16 09:03:09,046 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090309031929
2019-04-16 09:03:09,068 [salt.minion      :1432][INFO    ][10531] Starting a new job with PID 10531
2019-04-16 09:03:09,091 [salt.minion      :1711][INFO    ][10531] Returning information for job: 20190416090309031929
2019-04-16 09:03:27,874 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1654.10324597s left)
2019-04-16 09:03:39,176 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090339166631
2019-04-16 09:03:39,199 [salt.minion      :1432][INFO    ][10586] Starting a new job with PID 10586
2019-04-16 09:03:39,220 [salt.minion      :1711][INFO    ][10586] Returning information for job: 20190416090339166631
2019-04-16 09:04:00,738 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1621.23988509s left)
2019-04-16 09:04:09,354 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090409340214
2019-04-16 09:04:09,377 [salt.minion      :1432][INFO    ][10745] Starting a new job with PID 10745
2019-04-16 09:04:09,398 [salt.minion      :1711][INFO    ][10745] Returning information for job: 20190416090409340214
2019-04-16 09:04:33,514 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1588.46392512s left)
2019-04-16 09:04:39,497 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090439483836
2019-04-16 09:04:39,515 [salt.minion      :1432][INFO    ][10799] Starting a new job with PID 10799
2019-04-16 09:04:39,544 [salt.minion      :1711][INFO    ][10799] Returning information for job: 20190416090439483836
2019-04-16 09:05:06,422 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1555.55554199s left)
2019-04-16 09:05:09,674 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090509659358
2019-04-16 09:05:09,697 [salt.minion      :1432][INFO    ][10868] Starting a new job with PID 10868
2019-04-16 09:05:09,719 [salt.minion      :1711][INFO    ][10868] Returning information for job: 20190416090509659358
2019-04-16 09:05:39,223 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.75432014s left)
2019-04-16 09:05:39,751 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090539736409
2019-04-16 09:05:39,773 [salt.minion      :1432][INFO    ][11106] Starting a new job with PID 11106
2019-04-16 09:05:39,796 [salt.minion      :1711][INFO    ][11106] Returning information for job: 20190416090539736409
2019-04-16 09:06:09,961 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090609952887
2019-04-16 09:06:09,978 [salt.minion      :1432][INFO    ][11357] Starting a new job with PID 11357
2019-04-16 09:06:09,998 [salt.minion      :1711][INFO    ][11357] Returning information for job: 20190416090609952887
2019-04-16 09:06:11,933 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1490.04485607s left)
2019-04-16 09:06:40,147 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090640134816
2019-04-16 09:06:40,170 [salt.minion      :1432][INFO    ][11408] Starting a new job with PID 11408
2019-04-16 09:06:40,193 [salt.minion      :1711][INFO    ][11408] Returning information for job: 20190416090640134816
2019-04-16 09:06:44,680 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1457.29786801s left)
2019-04-16 09:07:10,169 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090710159925
2019-04-16 09:07:10,190 [salt.minion      :1432][INFO    ][11539] Starting a new job with PID 11539
2019-04-16 09:07:10,216 [salt.minion      :1711][INFO    ][11539] Returning information for job: 20190416090710159925
2019-04-16 09:07:17,626 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1424.35183215s left)
2019-04-16 09:07:40,354 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090740347798
2019-04-16 09:07:40,368 [salt.minion      :1432][INFO    ][11595] Starting a new job with PID 11595
2019-04-16 09:07:40,389 [salt.minion      :1711][INFO    ][11595] Returning information for job: 20190416090740347798
2019-04-16 09:07:50,477 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1391.50046206s left)
2019-04-16 09:08:10,378 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090810361406
2019-04-16 09:08:10,400 [salt.minion      :1432][INFO    ][11643] Starting a new job with PID 11643
2019-04-16 09:08:10,423 [salt.minion      :1711][INFO    ][11643] Returning information for job: 20190416090810361406
2019-04-16 09:08:23,137 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1358.84068298s left)
2019-04-16 09:08:40,412 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090840397947
2019-04-16 09:08:40,433 [salt.minion      :1432][INFO    ][11695] Starting a new job with PID 11695
2019-04-16 09:08:40,457 [salt.minion      :1711][INFO    ][11695] Returning information for job: 20190416090840397947
2019-04-16 09:08:56,010 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1325.96706414s left)
2019-04-16 09:09:10,447 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090910428434
2019-04-16 09:09:10,469 [salt.minion      :1432][INFO    ][11742] Starting a new job with PID 11742
2019-04-16 09:09:10,490 [salt.minion      :1711][INFO    ][11742] Returning information for job: 20190416090910428434
2019-04-16 09:09:28,831 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1293.14599514s left)
2019-04-16 09:09:40,502 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416090940491514
2019-04-16 09:09:40,531 [salt.minion      :1432][INFO    ][11804] Starting a new job with PID 11804
2019-04-16 09:09:40,553 [salt.minion      :1711][INFO    ][11804] Returning information for job: 20190416090940491514
2019-04-16 09:10:01,556 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1260.42119503s left)
2019-04-16 09:10:10,545 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091010531694
2019-04-16 09:10:10,571 [salt.minion      :1432][INFO    ][11861] Starting a new job with PID 11861
2019-04-16 09:10:10,597 [salt.minion      :1711][INFO    ][11861] Returning information for job: 20190416091010531694
2019-04-16 09:10:34,193 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1227.78466511s left)
2019-04-16 09:10:40,639 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091040624694
2019-04-16 09:10:40,661 [salt.minion      :1432][INFO    ][11912] Starting a new job with PID 11912
2019-04-16 09:10:40,684 [salt.minion      :1711][INFO    ][11912] Returning information for job: 20190416091040624694
2019-04-16 09:11:06,929 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1195.04815197s left)
2019-04-16 09:11:10,700 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091110692102
2019-04-16 09:11:10,713 [salt.minion      :1432][INFO    ][11963] Starting a new job with PID 11963
2019-04-16 09:11:10,734 [salt.minion      :1711][INFO    ][11963] Returning information for job: 20190416091110692102
2019-04-16 09:11:39,729 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1162.24803805s left)
2019-04-16 09:11:40,796 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091140785143
2019-04-16 09:11:40,814 [salt.minion      :1432][INFO    ][12021] Starting a new job with PID 12021
2019-04-16 09:11:40,847 [salt.minion      :1711][INFO    ][12021] Returning information for job: 20190416091140785143
2019-04-16 09:12:10,889 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091210879624
2019-04-16 09:12:10,903 [salt.minion      :1432][INFO    ][12070] Starting a new job with PID 12070
2019-04-16 09:12:10,927 [salt.minion      :1711][INFO    ][12070] Returning information for job: 20190416091210879624
2019-04-16 09:12:12,329 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1129.64862013s left)
2019-04-16 09:12:41,037 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091241023243
2019-04-16 09:12:41,059 [salt.minion      :1432][INFO    ][12136] Starting a new job with PID 12136
2019-04-16 09:12:41,085 [salt.minion      :1711][INFO    ][12136] Returning information for job: 20190416091241023243
2019-04-16 09:12:45,139 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1096.83862805s left)
2019-04-16 09:13:11,149 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091311142135
2019-04-16 09:13:11,165 [salt.minion      :1432][INFO    ][12171] Starting a new job with PID 12171
2019-04-16 09:13:11,186 [salt.minion      :1711][INFO    ][12171] Returning information for job: 20190416091311142135
2019-04-16 09:13:18,066 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1063.91157413s left)
2019-04-16 09:13:41,296 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091341284350
2019-04-16 09:13:41,316 [salt.minion      :1432][INFO    ][12238] Starting a new job with PID 12238
2019-04-16 09:13:41,339 [salt.minion      :1711][INFO    ][12238] Returning information for job: 20190416091341284350
2019-04-16 09:13:50,803 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1031.17505908s left)
2019-04-16 09:14:11,445 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091411436902
2019-04-16 09:14:11,462 [salt.minion      :1432][INFO    ][12281] Starting a new job with PID 12281
2019-04-16 09:14:11,488 [salt.minion      :1711][INFO    ][12281] Returning information for job: 20190416091411436902
2019-04-16 09:14:23,468 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (998.509869099s left)
2019-04-16 09:14:41,564 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091441553293
2019-04-16 09:14:41,582 [salt.minion      :1432][INFO    ][12344] Starting a new job with PID 12344
2019-04-16 09:14:41,606 [salt.minion      :1711][INFO    ][12344] Returning information for job: 20190416091441553293
2019-04-16 09:14:56,201 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (965.776615143s left)
2019-04-16 09:15:11,741 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091511724095
2019-04-16 09:15:11,760 [salt.minion      :1432][INFO    ][12385] Starting a new job with PID 12385
2019-04-16 09:15:11,785 [salt.minion      :1711][INFO    ][12385] Returning information for job: 20190416091511724095
2019-04-16 09:15:28,877 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (933.10059309s left)
2019-04-16 09:15:41,905 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091541892139
2019-04-16 09:15:41,926 [salt.minion      :1432][INFO    ][12460] Starting a new job with PID 12460
2019-04-16 09:15:41,953 [salt.minion      :1711][INFO    ][12460] Returning information for job: 20190416091541892139
2019-04-16 09:16:01,675 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (900.302828074s left)
2019-04-16 09:16:11,917 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091611909027
2019-04-16 09:16:11,937 [salt.minion      :1432][INFO    ][12511] Starting a new job with PID 12511
2019-04-16 09:16:11,965 [salt.minion      :1711][INFO    ][12511] Returning information for job: 20190416091611909027
2019-04-16 09:16:34,380 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (867.597613096s left)
2019-04-16 09:16:42,127 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091642116149
2019-04-16 09:16:42,150 [salt.minion      :1432][INFO    ][12573] Starting a new job with PID 12573
2019-04-16 09:16:42,174 [salt.minion      :1711][INFO    ][12573] Returning information for job: 20190416091642116149
2019-04-16 09:17:07,145 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (834.832076073s left)
2019-04-16 09:17:12,193 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091712179779
2019-04-16 09:17:12,213 [salt.minion      :1432][INFO    ][12617] Starting a new job with PID 12617
2019-04-16 09:17:12,237 [salt.minion      :1711][INFO    ][12617] Returning information for job: 20190416091712179779
2019-04-16 09:17:39,785 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (802.192263126s left)
2019-04-16 09:17:42,416 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091742404462
2019-04-16 09:17:42,432 [salt.minion      :1432][INFO    ][12681] Starting a new job with PID 12681
2019-04-16 09:17:42,457 [salt.minion      :1711][INFO    ][12681] Returning information for job: 20190416091742404462
2019-04-16 09:18:12,476 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (769.502028942s left)
2019-04-16 09:18:12,494 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091812485608
2019-04-16 09:18:12,513 [salt.minion      :1432][INFO    ][12721] Starting a new job with PID 12721
2019-04-16 09:18:12,536 [salt.minion      :1711][INFO    ][12721] Returning information for job: 20190416091812485608
2019-04-16 09:18:42,560 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091842551503
2019-04-16 09:18:42,575 [salt.minion      :1432][INFO    ][12786] Starting a new job with PID 12786
2019-04-16 09:18:42,599 [salt.minion      :1711][INFO    ][12786] Returning information for job: 20190416091842551503
2019-04-16 09:18:45,146 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (736.831869125s left)
2019-04-16 09:19:12,703 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091912692186
2019-04-16 09:19:12,721 [salt.minion      :1432][INFO    ][12822] Starting a new job with PID 12822
2019-04-16 09:19:12,746 [salt.minion      :1711][INFO    ][12822] Returning information for job: 20190416091912692186
2019-04-16 09:19:17,904 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (704.073832035s left)
2019-04-16 09:19:42,774 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416091942761724
2019-04-16 09:19:42,789 [salt.minion      :1432][INFO    ][12887] Starting a new job with PID 12887
2019-04-16 09:19:42,813 [salt.minion      :1711][INFO    ][12887] Returning information for job: 20190416091942761724
2019-04-16 09:19:50,672 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (671.305135965s left)
2019-04-16 09:20:12,929 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092012916245
2019-04-16 09:20:12,950 [salt.minion      :1432][INFO    ][12926] Starting a new job with PID 12926
2019-04-16 09:20:12,976 [salt.minion      :1711][INFO    ][12926] Returning information for job: 20190416092012916245
2019-04-16 09:20:23,421 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (638.556921959s left)
2019-04-16 09:20:43,066 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092043059735
2019-04-16 09:20:43,081 [salt.minion      :1432][INFO    ][12994] Starting a new job with PID 12994
2019-04-16 09:20:43,105 [salt.minion      :1711][INFO    ][12994] Returning information for job: 20190416092043059735
2019-04-16 09:20:56,226 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (605.751902103s left)
2019-04-16 09:21:13,212 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092113202611
2019-04-16 09:21:13,232 [salt.minion      :1432][INFO    ][13031] Starting a new job with PID 13031
2019-04-16 09:21:13,255 [salt.minion      :1711][INFO    ][13031] Returning information for job: 20190416092113202611
2019-04-16 09:21:28,866 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (573.111974955s left)
2019-04-16 09:21:43,410 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092143402117
2019-04-16 09:21:43,427 [salt.minion      :1432][INFO    ][13097] Starting a new job with PID 13097
2019-04-16 09:21:43,454 [salt.minion      :1711][INFO    ][13097] Returning information for job: 20190416092143402117
2019-04-16 09:22:01,288 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (540.689105034s left)
2019-04-16 09:22:13,573 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092213565184
2019-04-16 09:22:13,590 [salt.minion      :1432][INFO    ][13136] Starting a new job with PID 13136
2019-04-16 09:22:13,615 [salt.minion      :1711][INFO    ][13136] Returning information for job: 20190416092213565184
2019-04-16 09:22:33,752 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (508.225157022s left)
2019-04-16 09:22:43,737 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092243730370
2019-04-16 09:22:43,753 [salt.minion      :1432][INFO    ][13203] Starting a new job with PID 13203
2019-04-16 09:22:43,778 [salt.minion      :1711][INFO    ][13203] Returning information for job: 20190416092243730370
2019-04-16 09:23:06,078 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (475.899536133s left)
2019-04-16 09:23:13,894 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092313886346
2019-04-16 09:23:13,909 [salt.minion      :1432][INFO    ][13240] Starting a new job with PID 13240
2019-04-16 09:23:13,938 [salt.minion      :1711][INFO    ][13240] Returning information for job: 20190416092313886346
2019-04-16 09:23:38,438 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (443.539436102s left)
2019-04-16 09:23:44,040 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092344033275
2019-04-16 09:23:44,054 [salt.minion      :1432][INFO    ][13304] Starting a new job with PID 13304
2019-04-16 09:23:44,081 [salt.minion      :1711][INFO    ][13304] Returning information for job: 20190416092344033275
2019-04-16 09:24:10,771 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (411.206733942s left)
2019-04-16 09:24:14,180 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092414172282
2019-04-16 09:24:14,196 [salt.minion      :1432][INFO    ][13341] Starting a new job with PID 13341
2019-04-16 09:24:14,221 [salt.minion      :1711][INFO    ][13341] Returning information for job: 20190416092414172282
2019-04-16 09:24:43,361 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (378.61690712s left)
2019-04-16 09:24:44,394 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092444384383
2019-04-16 09:24:44,409 [salt.minion      :1432][INFO    ][13411] Starting a new job with PID 13411
2019-04-16 09:24:44,432 [salt.minion      :1711][INFO    ][13411] Returning information for job: 20190416092444384383
2019-04-16 09:25:14,581 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092514573465
2019-04-16 09:25:14,599 [salt.minion      :1432][INFO    ][13446] Starting a new job with PID 13446
2019-04-16 09:25:14,622 [salt.minion      :1711][INFO    ][13446] Returning information for job: 20190416092514573465
2019-04-16 09:25:15,730 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (346.247961998s left)
2019-04-16 09:25:44,794 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092544786790
2019-04-16 09:25:44,810 [salt.minion      :1432][INFO    ][13647] Starting a new job with PID 13647
2019-04-16 09:25:44,832 [salt.minion      :1711][INFO    ][13647] Returning information for job: 20190416092544786790
2019-04-16 09:25:48,197 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (313.780857086s left)
2019-04-16 09:26:14,820 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092614812834
2019-04-16 09:26:14,834 [salt.minion      :1432][INFO    ][13692] Starting a new job with PID 13692
2019-04-16 09:26:14,856 [salt.minion      :1711][INFO    ][13692] Returning information for job: 20190416092614812834
2019-04-16 09:26:20,563 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (281.41495204s left)
2019-04-16 09:26:44,961 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092644952547
2019-04-16 09:26:44,976 [salt.minion      :1432][INFO    ][13759] Starting a new job with PID 13759
2019-04-16 09:26:44,999 [salt.minion      :1711][INFO    ][13759] Returning information for job: 20190416092644952547
2019-04-16 09:26:53,232 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (248.745196104s left)
2019-04-16 09:27:15,071 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092715064318
2019-04-16 09:27:15,085 [salt.minion      :1432][INFO    ][13797] Starting a new job with PID 13797
2019-04-16 09:27:15,108 [salt.minion      :1711][INFO    ][13797] Returning information for job: 20190416092715064318
2019-04-16 09:27:25,843 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (216.134917974s left)
2019-04-16 09:27:45,189 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092745175366
2019-04-16 09:27:45,212 [salt.minion      :1432][INFO    ][13864] Starting a new job with PID 13864
2019-04-16 09:27:45,236 [salt.minion      :1711][INFO    ][13864] Returning information for job: 20190416092745175366
2019-04-16 09:27:58,477 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (183.500717163s left)
2019-04-16 09:28:15,253 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092815245722
2019-04-16 09:28:15,272 [salt.minion      :1432][INFO    ][13900] Starting a new job with PID 13900
2019-04-16 09:28:15,295 [salt.minion      :1711][INFO    ][13900] Returning information for job: 20190416092815245722
2019-04-16 09:28:31,224 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (150.753509998s left)
2019-04-16 09:28:45,426 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092845413035
2019-04-16 09:28:45,449 [salt.minion      :1432][INFO    ][13969] Starting a new job with PID 13969
2019-04-16 09:28:45,469 [salt.minion      :1711][INFO    ][13969] Returning information for job: 20190416092845413035
2019-04-16 09:29:03,901 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (118.076745033s left)
2019-04-16 09:29:15,468 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092915454125
2019-04-16 09:29:15,489 [salt.minion      :1432][INFO    ][14005] Starting a new job with PID 14005
2019-04-16 09:29:15,513 [salt.minion      :1711][INFO    ][14005] Returning information for job: 20190416092915454125
2019-04-16 09:29:36,510 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (85.4670519829s left)
2019-04-16 09:29:45,510 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416092945494036
2019-04-16 09:29:45,528 [salt.minion      :1432][INFO    ][14074] Starting a new job with PID 14074
2019-04-16 09:29:45,552 [salt.minion      :1711][INFO    ][14074] Returning information for job: 20190416092945494036
2019-04-16 09:30:09,373 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (52.6042170525s left)
2019-04-16 09:30:15,621 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093015611419
2019-04-16 09:30:15,643 [salt.minion      :1432][INFO    ][14110] Starting a new job with PID 14110
2019-04-16 09:30:15,666 [salt.minion      :1711][INFO    ][14110] Returning information for job: 20190416093015611419
2019-04-16 09:30:42,244 [salt.loaded.ext.module.maas:1023][INFO    ][7501] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (19.7331080437s left)
2019-04-16 09:30:45,682 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093045674425
2019-04-16 09:30:45,704 [salt.minion      :1432][INFO    ][14178] Starting a new job with PID 14178
2019-04-16 09:30:45,727 [salt.minion      :1711][INFO    ][14178] Returning information for job: 20190416093045674425
2019-04-16 09:31:15,013 [salt.state       :302 ][ERROR   ][7501] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-16 09:31:15,014 [salt.state       :1951][INFO    ][7501] Completed state [maas.wait_for_machine_status] at time 09:31:15.014121 duration_in_ms=2263046.141
2019-04-16 09:31:15,020 [salt.minion      :1711][INFO    ][7501] Returning information for job: 20190416085321751341
2019-04-16 09:31:25,864 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command pillar.get with jid 20190416093125856312
2019-04-16 09:31:25,884 [salt.minion      :1432][INFO    ][14222] Starting a new job with PID 14222
2019-04-16 09:31:25,893 [salt.minion      :1711][INFO    ][14222] Returning information for job: 20190416093125856312
2019-04-16 09:31:26,425 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command service.status with jid 20190416093126414136
2019-04-16 09:31:26,445 [salt.minion      :1432][INFO    ][14227] Starting a new job with PID 14227
2019-04-16 09:31:27,057 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14227] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-16 09:31:27,098 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14227] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-16 09:31:27,118 [salt.minion      :1711][INFO    ][14227] Returning information for job: 20190416093126414136
2019-04-16 09:31:27,657 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command state.apply with jid 20190416093127645519
2019-04-16 09:31:27,680 [salt.minion      :1432][INFO    ][14238] Starting a new job with PID 14238
2019-04-16 09:31:36,281 [salt.state       :915 ][INFO    ][14238] Loading fresh modules for state activity
2019-04-16 09:31:36,814 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command 'salt-minion --version' in directory '/root'
2019-04-16 09:31:37,082 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command 'salt-minion --version' in directory '/root'
2019-04-16 09:31:37,961 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command 'salt-minion --version' in directory '/root'
2019-04-16 09:31:38,219 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command 'salt-minion --version' in directory '/root'
2019-04-16 09:31:39,868 [salt.state       :1780][INFO    ][14238] Running state [salt-minion] at time 09:31:39.868410
2019-04-16 09:31:39,868 [salt.state       :1813][INFO    ][14238] Executing state pkg.installed for [salt-minion]
2019-04-16 09:31:39,869 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-16 09:31:39,947 [salt.state       :300 ][INFO    ][14238] All specified packages are already installed
2019-04-16 09:31:39,947 [salt.state       :1951][INFO    ][14238] Completed state [salt-minion] at time 09:31:39.947790 duration_in_ms=79.381
2019-04-16 09:31:39,948 [salt.state       :1780][INFO    ][14238] Running state [salt_minion_dependency_packages] at time 09:31:39.948132
2019-04-16 09:31:39,948 [salt.state       :1813][INFO    ][14238] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-16 09:31:39,957 [salt.state       :300 ][INFO    ][14238] All specified packages are already installed
2019-04-16 09:31:39,958 [salt.state       :1951][INFO    ][14238] Completed state [salt_minion_dependency_packages] at time 09:31:39.958072 duration_in_ms=9.941
2019-04-16 09:31:39,960 [salt.state       :1780][INFO    ][14238] Running state [/etc/salt/minion.d/minion.conf] at time 09:31:39.960949
2019-04-16 09:31:39,961 [salt.state       :1813][INFO    ][14238] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-16 09:31:40,220 [salt.state       :300 ][INFO    ][14238] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-16 09:31:40,220 [salt.state       :1951][INFO    ][14238] Completed state [/etc/salt/minion.d/minion.conf] at time 09:31:40.220432 duration_in_ms=259.483
2019-04-16 09:31:40,220 [salt.state       :1780][INFO    ][14238] Running state [python-netaddr] at time 09:31:40.220689
2019-04-16 09:31:40,220 [salt.state       :1813][INFO    ][14238] Executing state pkg.installed for [python-netaddr]
2019-04-16 09:31:40,230 [salt.state       :300 ][INFO    ][14238] All specified packages are already installed
2019-04-16 09:31:40,231 [salt.state       :1951][INFO    ][14238] Completed state [python-netaddr] at time 09:31:40.230962 duration_in_ms=10.272
2019-04-16 09:31:40,234 [salt.state       :1780][INFO    ][14238] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:31:40.233980
2019-04-16 09:31:40,234 [salt.state       :1813][INFO    ][14238] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-16 09:31:40,245 [salt.state       :300 ][INFO    ][14238] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-16 09:31:40,245 [salt.state       :1951][INFO    ][14238] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:31:40.245690 duration_in_ms=11.71
2019-04-16 09:31:40,246 [salt.state       :1780][INFO    ][14238] Running state [salt-minion] at time 09:31:40.246762
2019-04-16 09:31:40,247 [salt.state       :1813][INFO    ][14238] Executing state service.running for [salt-minion]
2019-04-16 09:31:40,247 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-16 09:31:40,272 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-16 09:31:40,284 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-16 09:31:40,295 [salt.state       :300 ][INFO    ][14238] The service salt-minion is already running
2019-04-16 09:31:40,296 [salt.state       :1951][INFO    ][14238] Completed state [salt-minion] at time 09:31:40.296238 duration_in_ms=49.475
2019-04-16 09:31:40,297 [salt.state       :1780][INFO    ][14238] Running state [/etc/salt/grains.d] at time 09:31:40.297776
2019-04-16 09:31:40,298 [salt.state       :1813][INFO    ][14238] Executing state file.directory for [/etc/salt/grains.d]
2019-04-16 09:31:40,301 [salt.state       :300 ][INFO    ][14238] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-16 09:31:40,301 [salt.state       :1951][INFO    ][14238] Completed state [/etc/salt/grains.d] at time 09:31:40.301391 duration_in_ms=3.616
2019-04-16 09:31:40,302 [salt.state       :1780][INFO    ][14238] Running state [/etc/salt/grains] at time 09:31:40.302052
2019-04-16 09:31:40,302 [salt.state       :1813][INFO    ][14238] Executing state file.managed for [/etc/salt/grains]
2019-04-16 09:31:40,302 [salt.state       :300 ][INFO    ][14238] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-16 09:31:40,303 [salt.state       :1951][INFO    ][14238] Completed state [/etc/salt/grains] at time 09:31:40.303164 duration_in_ms=1.111
2019-04-16 09:31:40,303 [salt.state       :1780][INFO    ][14238] Running state [/etc/salt/grains.d/placeholder] at time 09:31:40.303627
2019-04-16 09:31:40,303 [salt.state       :1813][INFO    ][14238] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-16 09:31:40,304 [salt.state       :300 ][INFO    ][14238] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-16 09:31:40,304 [salt.state       :1951][INFO    ][14238] Completed state [/etc/salt/grains.d/placeholder] at time 09:31:40.304577 duration_in_ms=0.95
2019-04-16 09:31:40,305 [salt.state       :1780][INFO    ][14238] Running state [/etc/salt/grains.d/sphinx] at time 09:31:40.305054
2019-04-16 09:31:40,305 [salt.state       :1813][INFO    ][14238] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-16 09:31:40,306 [salt.state       :300 ][INFO    ][14238] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-16 09:31:40,306 [salt.state       :1951][INFO    ][14238] Completed state [/etc/salt/grains.d/sphinx] at time 09:31:40.306811 duration_in_ms=1.757
2019-04-16 09:31:40,308 [salt.state       :1780][INFO    ][14238] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:31:40.308804
2019-04-16 09:31:40,309 [salt.state       :1813][INFO    ][14238] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-16 09:31:40,309 [salt.state       :300 ][INFO    ][14238] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-16 09:31:40,309 [salt.state       :1951][INFO    ][14238] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:31:40.309580 duration_in_ms=0.776
2019-04-16 09:31:40,310 [salt.state       :1780][INFO    ][14238] Running state [/etc/salt/grains.d/dns_records] at time 09:31:40.310054
2019-04-16 09:31:40,310 [salt.state       :1813][INFO    ][14238] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-16 09:31:40,313 [salt.state       :300 ][INFO    ][14238] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-16 09:31:40,313 [salt.state       :1951][INFO    ][14238] Completed state [/etc/salt/grains.d/dns_records] at time 09:31:40.313793 duration_in_ms=3.738
2019-04-16 09:31:40,314 [salt.state       :1780][INFO    ][14238] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:31:40.314716
2019-04-16 09:31:40,315 [salt.state       :1813][INFO    ][14238] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-16 09:31:40,315 [salt.state       :300 ][INFO    ][14238] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-16 09:31:40,315 [salt.state       :1951][INFO    ][14238] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:31:40.315487 duration_in_ms=0.771
2019-04-16 09:31:40,316 [salt.state       :1780][INFO    ][14238] Running state [/etc/salt/grains.d/salt] at time 09:31:40.315963
2019-04-16 09:31:40,316 [salt.state       :1813][INFO    ][14238] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-16 09:31:40,317 [salt.state       :300 ][INFO    ][14238] File /etc/salt/grains.d/salt is in the correct state
2019-04-16 09:31:40,317 [salt.state       :1951][INFO    ][14238] Completed state [/etc/salt/grains.d/salt] at time 09:31:40.317536 duration_in_ms=1.573
2019-04-16 09:31:40,318 [salt.state       :1780][INFO    ][14238] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:31:40.318419
2019-04-16 09:31:40,318 [salt.state       :1813][INFO    ][14238] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-16 09:31:40,319 [salt.state       :300 ][INFO    ][14238] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-16 09:31:40,319 [salt.state       :1951][INFO    ][14238] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:31:40.319195 duration_in_ms=0.776
2019-04-16 09:31:40,321 [salt.state       :1780][INFO    ][14238] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:31:40.321098
2019-04-16 09:31:40,321 [salt.state       :1813][INFO    ][14238] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-16 09:31:40,321 [salt.state       :300 ][INFO    ][14238] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-16 09:31:40,321 [salt.state       :1951][INFO    ][14238] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:31:40.321861 duration_in_ms=0.762
2019-04-16 09:31:40,322 [salt.state       :1780][INFO    ][14238] Running state [mine.update] at time 09:31:40.322523
2019-04-16 09:31:40,322 [salt.state       :1813][INFO    ][14238] Executing state module.wait for [mine.update]
2019-04-16 09:31:40,325 [salt.state       :300 ][INFO    ][14238] No changes made for mine.update
2019-04-16 09:31:40,325 [salt.state       :1951][INFO    ][14238] Completed state [mine.update] at time 09:31:40.325450 duration_in_ms=2.927
2019-04-16 09:31:40,325 [salt.state       :1780][INFO    ][14238] Running state [ca-certificates] at time 09:31:40.325686
2019-04-16 09:31:40,325 [salt.state       :1813][INFO    ][14238] Executing state pkg.installed for [ca-certificates]
2019-04-16 09:31:40,337 [salt.state       :300 ][INFO    ][14238] All specified packages are already installed
2019-04-16 09:31:40,337 [salt.state       :1951][INFO    ][14238] Completed state [ca-certificates] at time 09:31:40.337303 duration_in_ms=11.617
2019-04-16 09:31:40,338 [salt.state       :1780][INFO    ][14238] Running state [update-ca-certificates] at time 09:31:40.337994
2019-04-16 09:31:40,338 [salt.state       :1813][INFO    ][14238] Executing state cmd.wait for [update-ca-certificates]
2019-04-16 09:31:40,338 [salt.state       :300 ][INFO    ][14238] No changes made for update-ca-certificates
2019-04-16 09:31:40,338 [salt.state       :1951][INFO    ][14238] Completed state [update-ca-certificates] at time 09:31:40.338724 duration_in_ms=0.73
2019-04-16 09:31:40,339 [salt.state       :1780][INFO    ][14238] Running state [iptables] at time 09:31:40.338977
2019-04-16 09:31:40,339 [salt.state       :1813][INFO    ][14238] Executing state pkg.installed for [iptables]
2019-04-16 09:31:40,349 [salt.state       :300 ][INFO    ][14238] All specified packages are already installed
2019-04-16 09:31:40,349 [salt.state       :1951][INFO    ][14238] Completed state [iptables] at time 09:31:40.349196 duration_in_ms=10.218
2019-04-16 09:31:40,349 [salt.state       :1780][INFO    ][14238] Running state [iptables-persistent] at time 09:31:40.349437
2019-04-16 09:31:40,349 [salt.state       :1813][INFO    ][14238] Executing state pkg.installed for [iptables-persistent]
2019-04-16 09:31:40,358 [salt.state       :300 ][INFO    ][14238] All specified packages are already installed
2019-04-16 09:31:40,358 [salt.state       :1951][INFO    ][14238] Completed state [iptables-persistent] at time 09:31:40.358337 duration_in_ms=8.9
2019-04-16 09:31:40,359 [salt.state       :1780][INFO    ][14238] Running state [iptables_modules_v4_load] at time 09:31:40.359879
2019-04-16 09:31:40,360 [salt.state       :1813][INFO    ][14238] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-16 09:31:40,360 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command 'lsmod' in directory '/root'
2019-04-16 09:31:40,374 [salt.state       :300 ][INFO    ][14238] Kernel modules iptable_filter, ip_tables are already present
2019-04-16 09:31:40,375 [salt.state       :1951][INFO    ][14238] Completed state [iptables_modules_v4_load] at time 09:31:40.375250 duration_in_ms=15.371
2019-04-16 09:31:40,375 [salt.state       :1780][INFO    ][14238] Running state [/etc/iptables/rules.v4] at time 09:31:40.375889
2019-04-16 09:31:40,376 [salt.state       :1813][INFO    ][14238] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-16 09:31:40,473 [salt.state       :300 ][INFO    ][14238] File /etc/iptables/rules.v4 is in the correct state
2019-04-16 09:31:40,474 [salt.state       :1951][INFO    ][14238] Completed state [/etc/iptables/rules.v4] at time 09:31:40.473965 duration_in_ms=98.076
2019-04-16 09:31:40,475 [salt.state       :1780][INFO    ][14238] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:31:40.475122
2019-04-16 09:31:40,475 [salt.state       :1813][INFO    ][14238] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-16 09:31:40,475 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-16 09:31:40,488 [salt.state       :300 ][INFO    ][14238] onlyif execution failed
2019-04-16 09:31:40,488 [salt.state       :1951][INFO    ][14238] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:31:40.488876 duration_in_ms=13.754
2019-04-16 09:31:40,489 [salt.state       :1780][INFO    ][14238] Running state [netfilter-persistent] at time 09:31:40.489928
2019-04-16 09:31:40,490 [salt.state       :1813][INFO    ][14238] Executing state service.running for [netfilter-persistent]
2019-04-16 09:31:40,493 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-16 09:31:40,506 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-16 09:31:40,517 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-16 09:31:40,529 [salt.state       :300 ][INFO    ][14238] The service netfilter-persistent is already running
2019-04-16 09:31:40,530 [salt.state       :1951][INFO    ][14238] Completed state [netfilter-persistent] at time 09:31:40.529941 duration_in_ms=40.013
2019-04-16 09:31:40,530 [salt.state       :1780][INFO    ][14238] Running state [iptables_extra.remove_stale_tables] at time 09:31:40.530788
2019-04-16 09:31:40,531 [salt.state       :1813][INFO    ][14238] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-16 09:31:40,531 [salt.state       :300 ][INFO    ][14238] No changes made for iptables_extra.remove_stale_tables
2019-04-16 09:31:40,531 [salt.state       :1951][INFO    ][14238] Completed state [iptables_extra.remove_stale_tables] at time 09:31:40.531632 duration_in_ms=0.843
2019-04-16 09:31:40,531 [salt.state       :1780][INFO    ][14238] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:31:40.531876
2019-04-16 09:31:40,532 [salt.state       :1813][INFO    ][14238] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-16 09:31:40,532 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-16 09:31:40,542 [salt.state       :300 ][INFO    ][14238] onlyif execution failed
2019-04-16 09:31:40,543 [salt.state       :1951][INFO    ][14238] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:31:40.543190 duration_in_ms=11.313
2019-04-16 09:31:40,544 [salt.state       :1780][INFO    ][14238] Running state [/etc/iptables/rules.v6] at time 09:31:40.544103
2019-04-16 09:31:40,544 [salt.state       :1813][INFO    ][14238] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-16 09:31:40,544 [salt.state       :300 ][INFO    ][14238] File /etc/iptables/rules.v6 is not present
2019-04-16 09:31:40,545 [salt.state       :1951][INFO    ][14238] Completed state [/etc/iptables/rules.v6] at time 09:31:40.544997 duration_in_ms=0.894
2019-04-16 09:31:40,545 [salt.state       :1780][INFO    ][14238] Running state [iptables_extra.flush_all] at time 09:31:40.545686
2019-04-16 09:31:40,545 [salt.state       :1813][INFO    ][14238] Executing state module.wait for [iptables_extra.flush_all]
2019-04-16 09:31:40,546 [salt.state       :300 ][INFO    ][14238] No changes made for iptables_extra.flush_all
2019-04-16 09:31:40,546 [salt.state       :1951][INFO    ][14238] Completed state [iptables_extra.flush_all] at time 09:31:40.546401 duration_in_ms=0.714
2019-04-16 09:31:40,549 [salt.minion      :1711][INFO    ][14238] Returning information for job: 20190416093127645519
2019-04-16 09:31:41,122 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command state.apply with jid 20190416093141112849
2019-04-16 09:31:41,138 [salt.minion      :1432][INFO    ][14361] Starting a new job with PID 14361
2019-04-16 09:31:42,246 [salt.state       :915 ][INFO    ][14361] Loading fresh modules for state activity
2019-04-16 09:31:43,103 [salt.state       :1780][INFO    ][14361] Running state [maas-rack-controller] at time 09:31:43.103539
2019-04-16 09:31:43,103 [salt.state       :1813][INFO    ][14361] Executing state pkg.installed for [maas-rack-controller]
2019-04-16 09:31:43,104 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14361] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-16 09:31:43,204 [salt.state       :300 ][INFO    ][14361] All specified packages are already installed
2019-04-16 09:31:43,204 [salt.state       :1951][INFO    ][14361] Completed state [maas-rack-controller] at time 09:31:43.204631 duration_in_ms=101.092
2019-04-16 09:31:43,205 [salt.state       :1780][INFO    ][14361] Running state [ipmitool] at time 09:31:43.205018
2019-04-16 09:31:43,205 [salt.state       :1813][INFO    ][14361] Executing state pkg.installed for [ipmitool]
2019-04-16 09:31:43,213 [salt.state       :300 ][INFO    ][14361] All specified packages are already installed
2019-04-16 09:31:43,214 [salt.state       :1951][INFO    ][14361] Completed state [ipmitool] at time 09:31:43.214075 duration_in_ms=9.057
2019-04-16 09:31:43,219 [salt.state       :1780][INFO    ][14361] Running state [/etc/maas/rackd.conf] at time 09:31:43.219343
2019-04-16 09:31:43,219 [salt.state       :1813][INFO    ][14361] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-16 09:31:43,220 [salt.state       :300 ][INFO    ][14361] No changes needed to be made
2019-04-16 09:31:43,220 [salt.state       :1951][INFO    ][14361] Completed state [/etc/maas/rackd.conf] at time 09:31:43.220728 duration_in_ms=1.385
2019-04-16 09:31:43,221 [salt.state       :1780][INFO    ][14361] Running state [/etc/maas/rackd.conf] at time 09:31:43.220964
2019-04-16 09:31:43,221 [salt.state       :1813][INFO    ][14361] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-16 09:31:43,221 [salt.loaded.int.states.file:2298][WARNING ][14361] 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-04-16 09:31:43,222 [salt.state       :300 ][INFO    ][14361] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-16 09:31:43,222 [salt.state       :1951][INFO    ][14361] Completed state [/etc/maas/rackd.conf] at time 09:31:43.222423 duration_in_ms=1.459
2019-04-16 09:31:43,223 [salt.state       :1780][INFO    ][14361] Running state [maas-rackd] at time 09:31:43.223420
2019-04-16 09:31:43,223 [salt.state       :1813][INFO    ][14361] Executing state service.running for [maas-rackd]
2019-04-16 09:31:43,224 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14361] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-16 09:31:43,257 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14361] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-16 09:31:43,270 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14361] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-16 09:31:43,290 [salt.state       :300 ][INFO    ][14361] The service maas-rackd is already running
2019-04-16 09:31:43,291 [salt.state       :1951][INFO    ][14361] Completed state [maas-rackd] at time 09:31:43.291239 duration_in_ms=67.819
2019-04-16 09:31:43,293 [salt.minion      :1711][INFO    ][14361] Returning information for job: 20190416093141112849
2019-04-16 09:31:43,832 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command state.apply with jid 20190416093143820696
2019-04-16 09:31:43,855 [salt.minion      :1432][INFO    ][14388] Starting a new job with PID 14388
2019-04-16 09:31:44,992 [salt.state       :915 ][INFO    ][14388] Loading fresh modules for state activity
2019-04-16 09:31:45,985 [salt.state       :1780][INFO    ][14388] Running state [maas-region-controller] at time 09:31:45.985285
2019-04-16 09:31:45,985 [salt.state       :1813][INFO    ][14388] Executing state pkg.installed for [maas-region-controller]
2019-04-16 09:31:45,986 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14388] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-16 09:31:46,096 [salt.state       :300 ][INFO    ][14388] All specified packages are already installed
2019-04-16 09:31:46,097 [salt.state       :1951][INFO    ][14388] Completed state [maas-region-controller] at time 09:31:46.096989 duration_in_ms=111.705
2019-04-16 09:31:46,097 [salt.state       :1780][INFO    ][14388] Running state [python-oauth] at time 09:31:46.097305
2019-04-16 09:31:46,097 [salt.state       :1813][INFO    ][14388] Executing state pkg.installed for [python-oauth]
2019-04-16 09:31:46,106 [salt.state       :300 ][INFO    ][14388] All specified packages are already installed
2019-04-16 09:31:46,106 [salt.state       :1951][INFO    ][14388] Completed state [python-oauth] at time 09:31:46.106496 duration_in_ms=9.191
2019-04-16 09:31:46,111 [salt.state       :1780][INFO    ][14388] Running state [/etc/maas/regiond.conf] at time 09:31:46.111464
2019-04-16 09:31:46,111 [salt.state       :1813][INFO    ][14388] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-16 09:31:46,115 [salt.state       :300 ][INFO    ][14388] No changes needed to be made
2019-04-16 09:31:46,116 [salt.state       :1951][INFO    ][14388] Completed state [/etc/maas/regiond.conf] at time 09:31:46.116137 duration_in_ms=4.673
2019-04-16 09:31:46,116 [salt.state       :1780][INFO    ][14388] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:31:46.116627
2019-04-16 09:31:46,116 [salt.state       :1813][INFO    ][14388] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-16 09:31:46,175 [salt.state       :300 ][INFO    ][14388] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-16 09:31:46,176 [salt.state       :1951][INFO    ][14388] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:31:46.175938 duration_in_ms=59.31
2019-04-16 09:31:46,177 [salt.state       :1780][INFO    ][14388] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:31:46.176934
2019-04-16 09:31:46,177 [salt.state       :1813][INFO    ][14388] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-16 09:31:46,186 [salt.state       :300 ][INFO    ][14388] No changes needed to be made
2019-04-16 09:31:46,186 [salt.state       :1951][INFO    ][14388] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:31:46.186411 duration_in_ms=9.477
2019-04-16 09:31:46,187 [salt.state       :1780][INFO    ][14388] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:31:46.187676
2019-04-16 09:31:46,188 [salt.state       :1813][INFO    ][14388] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-16 09:31:46,194 [salt.state       :300 ][INFO    ][14388] No changes needed to be made
2019-04-16 09:31:46,194 [salt.state       :1951][INFO    ][14388] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:31:46.194899 duration_in_ms=7.222
2019-04-16 09:31:46,195 [salt.state       :1780][INFO    ][14388] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:31:46.195375
2019-04-16 09:31:46,195 [salt.state       :1813][INFO    ][14388] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-16 09:31:46,225 [salt.state       :300 ][INFO    ][14388] No changes needed to be made
2019-04-16 09:31:46,225 [salt.state       :1951][INFO    ][14388] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:31:46.225299 duration_in_ms=29.924
2019-04-16 09:31:46,225 [salt.state       :1780][INFO    ][14388] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:31:46.225788
2019-04-16 09:31:46,226 [salt.state       :1813][INFO    ][14388] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-16 09:31:46,242 [salt.state       :300 ][INFO    ][14388] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-16 09:31:46,242 [salt.state       :1951][INFO    ][14388] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:31:46.242558 duration_in_ms=16.769
2019-04-16 09:31:46,244 [salt.state       :1780][INFO    ][14388] Running state [a2enmod headers] at time 09:31:46.244145
2019-04-16 09:31:46,244 [salt.state       :1813][INFO    ][14388] Executing state cmd.run for [a2enmod headers]
2019-04-16 09:31:46,245 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14388] Executing command 'a2enmod headers' in directory '/root'
2019-04-16 09:31:46,327 [salt.state       :300 ][INFO    ][14388] {'pid': 14408, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-16 09:31:46,328 [salt.state       :1951][INFO    ][14388] Completed state [a2enmod headers] at time 09:31:46.328171 duration_in_ms=84.026
2019-04-16 09:31:46,328 [salt.state       :1780][INFO    ][14388] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:31:46.328784
2019-04-16 09:31:46,329 [salt.state       :1813][INFO    ][14388] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-16 09:31:46,355 [salt.state       :300 ][INFO    ][14388] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-16 09:31:46,355 [salt.state       :1951][INFO    ][14388] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:31:46.355814 duration_in_ms=27.03
2019-04-16 09:31:46,356 [salt.state       :1780][INFO    ][14388] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:31:46.356865
2019-04-16 09:31:46,357 [salt.state       :1813][INFO    ][14388] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-16 09:31:46,416 [salt.state       :300 ][INFO    ][14388] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-16 09:31:46,417 [salt.state       :1951][INFO    ][14388] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:31:46.417136 duration_in_ms=60.27
2019-04-16 09:31:46,418 [salt.state       :1780][INFO    ][14388] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:31:46.418122
2019-04-16 09:31:46,418 [salt.state       :1813][INFO    ][14388] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-16 09:31:46,478 [salt.state       :300 ][INFO    ][14388] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-16 09:31:46,478 [salt.state       :1951][INFO    ][14388] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:31:46.478432 duration_in_ms=60.311
2019-04-16 09:31:46,480 [salt.state       :1780][INFO    ][14388] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:31:46.480158
2019-04-16 09:31:46,480 [salt.state       :1813][INFO    ][14388] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-16 09:31:46,555 [salt.state       :300 ][INFO    ][14388] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-16 09:31:46,556 [salt.state       :1951][INFO    ][14388] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:31:46.556395 duration_in_ms=76.236
2019-04-16 09:31:46,556 [salt.state       :1780][INFO    ][14388] Running state [/root/.pgpass] at time 09:31:46.556703
2019-04-16 09:31:46,556 [salt.state       :1813][INFO    ][14388] Executing state file.managed for [/root/.pgpass]
2019-04-16 09:31:46,612 [salt.state       :300 ][INFO    ][14388] File /root/.pgpass is in the correct state
2019-04-16 09:31:46,612 [salt.state       :1951][INFO    ][14388] Completed state [/root/.pgpass] at time 09:31:46.612692 duration_in_ms=55.989
2019-04-16 09:31:46,617 [salt.state       :1780][INFO    ][14388] Running state [maas-region syncdb --noinput] at time 09:31:46.617946
2019-04-16 09:31:46,618 [salt.state       :1813][INFO    ][14388] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-16 09:31:46,618 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14388] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-16 09:31:49,252 [salt.state       :300 ][INFO    ][14388] {'pid': 14422, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: auth, contenttypes, metadataserver, maasserver, sites, piston3, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-16 09:31:49,252 [salt.state       :1951][INFO    ][14388] Completed state [maas-region syncdb --noinput] at time 09:31:49.252737 duration_in_ms=2634.791
2019-04-16 09:31:49,253 [salt.state       :2022][WARNING ][14388] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-16 09:31:49,256 [salt.state       :1780][INFO    ][14388] Running state [maas-regiond] at time 09:31:49.256209
2019-04-16 09:31:49,256 [salt.state       :1813][INFO    ][14388] Executing state service.running for [maas-regiond]
2019-04-16 09:31:49,257 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14388] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-16 09:31:49,295 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14388] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-16 09:31:49,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14388] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-16 09:31:49,330 [salt.state       :300 ][INFO    ][14388] The service maas-regiond is already running
2019-04-16 09:31:49,331 [salt.state       :1951][INFO    ][14388] Completed state [maas-regiond] at time 09:31:49.331021 duration_in_ms=74.813
2019-04-16 09:31:49,333 [salt.state       :1780][INFO    ][14388] Running state [bind9] at time 09:31:49.333347
2019-04-16 09:31:49,333 [salt.state       :1813][INFO    ][14388] Executing state service.running for [bind9]
2019-04-16 09:31:49,334 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14388] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-16 09:31:49,354 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14388] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-16 09:31:49,370 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14388] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-16 09:31:49,387 [salt.state       :300 ][INFO    ][14388] The service bind9 is already running
2019-04-16 09:31:49,388 [salt.state       :1951][INFO    ][14388] Completed state [bind9] at time 09:31:49.388215 duration_in_ms=54.867
2019-04-16 09:31:49,390 [salt.state       :1780][INFO    ][14388] Running state [apache2] at time 09:31:49.390774
2019-04-16 09:31:49,391 [salt.state       :1813][INFO    ][14388] Executing state service.running for [apache2]
2019-04-16 09:31:49,392 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14388] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-16 09:31:49,412 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14388] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-16 09:31:49,427 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14388] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-16 09:31:49,451 [salt.state       :300 ][INFO    ][14388] The service apache2 is already running
2019-04-16 09:31:49,451 [salt.state       :1951][INFO    ][14388] Completed state [apache2] at time 09:31:49.451611 duration_in_ms=60.836
2019-04-16 09:31:49,453 [salt.state       :1780][INFO    ][14388] Running state [maasng.wait_for_http_code] at time 09:31:49.453562
2019-04-16 09:31:49,454 [salt.state       :1813][INFO    ][14388] Executing state module.run for [maasng.wait_for_http_code]
2019-04-16 09:31:49,456 [salt.utils.decorators:613 ][WARNING ][14388] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 09:31:49,555 [salt.state       :300 ][INFO    ][14388] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-16 09:31:49,555 [salt.state       :1951][INFO    ][14388] Completed state [maasng.wait_for_http_code] at time 09:31:49.555863 duration_in_ms=102.3
2019-04-16 09:31:49,556 [salt.state       :1780][INFO    ][14388] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:31:49.556898
2019-04-16 09:31:49,557 [salt.state       :1813][INFO    ][14388] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-16 09:31:49,557 [salt.state       :300 ][INFO    ][14388] /var/lib/maas/.setup_admin exists
2019-04-16 09:31:49,558 [salt.state       :1951][INFO    ][14388] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:31:49.558258 duration_in_ms=1.36
2019-04-16 09:31:49,559 [salt.state       :1780][INFO    ][14388] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:31:49.559178
2019-04-16 09:31:49,559 [salt.state       :1813][INFO    ][14388] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 09:31:49,560 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14388] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 09:31:51,336 [salt.state       :300 ][INFO    ][14388] {'pid': 14447, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 09:31:51,338 [salt.state       :1951][INFO    ][14388] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:31:51.337900 duration_in_ms=1778.721
2019-04-16 09:31:51,348 [salt.state       :1780][INFO    ][14388] Running state [maas_region_boot_source_resources_mirror] at time 09:31:51.348523
2019-04-16 09:31:51,348 [salt.state       :1813][INFO    ][14388] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-16 09:31:51,427 [salt.state       :300 ][INFO    ][14388] {'changes': {}}
2019-04-16 09:31:51,428 [salt.state       :1951][INFO    ][14388] Completed state [maas_region_boot_source_resources_mirror] at time 09:31:51.428153 duration_in_ms=79.629
2019-04-16 09:31:51,429 [salt.state       :1780][INFO    ][14388] Running state [maasng.boot_resources_import] at time 09:31:51.429426
2019-04-16 09:31:51,429 [salt.state       :1813][INFO    ][14388] Executing state module.run for [maasng.boot_resources_import]
2019-04-16 09:31:51,430 [salt.utils.decorators:613 ][WARNING ][14388] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 09:31:51,521 [salt.loaded.ext.module.maasng:1600][INFO    ][14388] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-16 09:31:56,576 [salt.loaded.ext.module.maasng:1600][INFO    ][14388] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-16 09:31:58,941 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093158927168
2019-04-16 09:31:58,964 [salt.minion      :1432][INFO    ][14602] Starting a new job with PID 14602
2019-04-16 09:31:58,988 [salt.minion      :1711][INFO    ][14602] Returning information for job: 20190416093158927168
2019-04-16 09:32:01,681 [salt.state       :300 ][INFO    ][14388] {'ret': True}
2019-04-16 09:32:01,682 [salt.state       :1951][INFO    ][14388] Completed state [maasng.boot_resources_import] at time 09:32:01.682377 duration_in_ms=10252.95
2019-04-16 09:32:01,685 [salt.state       :1780][INFO    ][14388] Running state [maas_region_boot_sources_selection_xenial] at time 09:32:01.685608
2019-04-16 09:32:01,686 [salt.state       :1813][INFO    ][14388] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-16 09:32:01,847 [salt.state       :300 ][INFO    ][14388] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-16 09:32:01,848 [salt.state       :1951][INFO    ][14388] Completed state [maas_region_boot_sources_selection_xenial] at time 09:32:01.848319 duration_in_ms=162.709
2019-04-16 09:32:01,850 [salt.state       :1780][INFO    ][14388] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 09:32:01.850105
2019-04-16 09:32:01,850 [salt.state       :1813][INFO    ][14388] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-16 09:32:01,853 [salt.utils.decorators:613 ][WARNING ][14388] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 09:32:01,853 [salt.loaded.ext.module.maasng:1771][INFO    ][14388] boot-sources sync initiated for ALL Rack's
2019-04-16 09:32:02,794 [salt.state       :300 ][INFO    ][14388] {'ret': True}
2019-04-16 09:32:02,794 [salt.state       :1951][INFO    ][14388] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 09:32:02.794738 duration_in_ms=944.631
2019-04-16 09:32:02,797 [salt.state       :1780][INFO    ][14388] Running state [maas.process_maas_config] at time 09:32:02.797244
2019-04-16 09:32:02,797 [salt.state       :1813][INFO    ][14388] Executing state module.run for [maas.process_maas_config]
2019-04-16 09:32:02,798 [salt.utils.decorators:613 ][WARNING ][14388] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 09:32:02,800 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=enable_http_proxy value=True
2019-04-16 09:32:02,849 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=upstream_dns value=8.8.8.8
2019-04-16 09:32:04,092 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=commissioning_distro_series value=xenial
2019-04-16 09:32:04,148 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=default_osystem value=ubuntu
2019-04-16 09:32:04,196 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=active_discovery_interval value=600
2019-04-16 09:32:04,238 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=dnssec_validation value=no
2019-04-16 09:32:04,284 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=maas_name value=mas01
2019-04-16 09:32:04,328 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=network_discovery value=enabled
2019-04-16 09:32:04,418 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=enable_third_party_drivers value=True
2019-04-16 09:32:04,461 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=default_storage_layout value=lvm
2019-04-16 09:32:04,507 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=ntp_external_only value=True
2019-04-16 09:32:04,547 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-16 09:32:04,595 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=default_distro_series value=xenial
2019-04-16 09:32:04,654 [salt.loaded.ext.module.maas:92  ][INFO    ][14388] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-04-16 09:32:04,812 [salt.state       :300 ][INFO    ][14388] {'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-04-16 09:32:04,813 [salt.state       :1951][INFO    ][14388] Completed state [maas.process_maas_config] at time 09:32:04.813266 duration_in_ms=2016.021
2019-04-16 09:32:04,814 [salt.state       :1780][INFO    ][14388] Running state [pxe_admin] at time 09:32:04.813984
2019-04-16 09:32:04,814 [salt.state       :1813][INFO    ][14388] Executing state maasng.fabric_present for [pxe_admin]
2019-04-16 09:32:04,867 [salt.loaded.ext.module.maasng:945 ][INFO    ][14388] [{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'primary_rack': None, u'fabric': u'fabric-0', 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'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'primary_rack': None, u'fabric': u'fabric-2', 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'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'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-16 09:32:04,917 [salt.loaded.ext.module.maasng:1008][WARNING ][14388] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-16 09:32:04,918 [salt.loaded.ext.module.maasng:1011][WARNING ][14388] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-16 09:32:04,985 [salt.state       :300 ][INFO    ][14388] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-16 09:32:04,985 [salt.state       :1951][INFO    ][14388] Completed state [pxe_admin] at time 09:32:04.985726 duration_in_ms=171.741
2019-04-16 09:32:04,986 [salt.state       :1780][INFO    ][14388] Running state [vlan 0] at time 09:32:04.986007
2019-04-16 09:32:04,986 [salt.state       :1813][INFO    ][14388] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-16 09:32:05,027 [salt.loaded.ext.module.maasng:945 ][INFO    ][14388] [{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'primary_rack': None, u'fabric': u'fabric-0', 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'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'primary_rack': None, u'fabric': u'fabric-2', 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'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'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-16 09:32:05,120 [salt.loaded.ext.module.maasng:945 ][INFO    ][14388] [{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'primary_rack': None, u'fabric': u'fabric-0', 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'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'primary_rack': None, u'fabric': u'fabric-2', 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'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'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-16 09:32:05,347 [salt.loaded.ext.module.maasng:945 ][INFO    ][14388] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'tx6trw', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-04-16 09:32:05,420 [salt.state       :300 ][INFO    ][14388] {'new': 'Vlan untagged was updated'}
2019-04-16 09:32:05,421 [salt.state       :1951][INFO    ][14388] Completed state [vlan 0] at time 09:32:05.421227 duration_in_ms=435.219
2019-04-16 09:32:05,422 [salt.state       :1780][INFO    ][14388] Running state [192.168.11.0/24] at time 09:32:05.422500
2019-04-16 09:32:05,423 [salt.state       :1813][INFO    ][14388] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-16 09:32:05,602 [salt.loaded.ext.module.maasng:945 ][INFO    ][14388] [{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'primary_rack': None, u'fabric': u'fabric-0', 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'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'primary_rack': None, u'fabric': u'fabric-2', 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'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'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-16 09:32:05,603 [salt.loaded.ext.module.maasng:1235][WARNING ][14388] Ignoring parameter vlan:0
2019-04-16 09:32:05,661 [salt.state       :300 ][INFO    ][14388] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-16 09:32:05,661 [salt.state       :1951][INFO    ][14388] Completed state [192.168.11.0/24] at time 09:32:05.661342 duration_in_ms=238.841
2019-04-16 09:32:05,662 [salt.state       :1780][INFO    ][14388] Running state [maas_create_iprange_1] at time 09:32:05.662143
2019-04-16 09:32:05,662 [salt.state       :1813][INFO    ][14388] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-16 09:32:05,704 [salt.state       :300 ][INFO    ][14388] Iprange maas_create_iprange_1 already exist.
2019-04-16 09:32:05,705 [salt.state       :1951][INFO    ][14388] Completed state [maas_create_iprange_1] at time 09:32:05.704983 duration_in_ms=42.84
2019-04-16 09:32:05,705 [salt.state       :1780][INFO    ][14388] Running state [vlan 0] at time 09:32:05.705257
2019-04-16 09:32:05,705 [salt.state       :1813][INFO    ][14388] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-16 09:32:05,742 [salt.loaded.ext.module.maasng:945 ][INFO    ][14388] [{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'primary_rack': None, u'fabric': u'fabric-0', 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'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'primary_rack': None, u'fabric': u'fabric-2', 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'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'primary_rack': u'tx6trw', u'fabric': u'pxe_admin', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-16 09:32:05,843 [salt.loaded.ext.module.maasng:945 ][INFO    ][14388] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'tx6trw', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-04-16 09:32:06,088 [salt.loaded.ext.module.maasng:945 ][INFO    ][14388] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tx6trw', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-04-16 09:32:06,168 [salt.state       :300 ][INFO    ][14388] {'new': 'Vlan untagged was updated'}
2019-04-16 09:32:06,168 [salt.state       :1951][INFO    ][14388] Completed state [vlan 0] at time 09:32:06.168416 duration_in_ms=463.158
2019-04-16 09:32:06,169 [salt.state       :1780][INFO    ][14388] Running state [opnfv] at time 09:32:06.169038
2019-04-16 09:32:06,169 [salt.state       :1813][INFO    ][14388] Executing state maasng.sshkey_present for [opnfv]
2019-04-16 09:32:06,223 [salt.loaded.ext.module.maasng:1903][INFO    ][14388] [{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-04-16 09:32:06,223 [salt.state       :300 ][INFO    ][14388] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-16 09:32:06,224 [salt.state       :1951][INFO    ][14388] Completed state [opnfv] at time 09:32:06.224136 duration_in_ms=55.097
2019-04-16 09:32:06,226 [salt.minion      :1711][INFO    ][14388] Returning information for job: 20190416093143820696
2019-04-16 09:32:06,838 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command state.apply with jid 20190416093206827923
2019-04-16 09:32:06,852 [salt.minion      :1432][INFO    ][14832] Starting a new job with PID 14832
2019-04-16 09:32:15,146 [salt.state       :915 ][INFO    ][14832] Loading fresh modules for state activity
2019-04-16 09:32:15,250 [salt.state       :1780][INFO    ][14832] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:32:15.250578
2019-04-16 09:32:15,250 [salt.state       :1813][INFO    ][14832] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 09:32:15,252 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 09:32:17,041 [salt.state       :300 ][INFO    ][14832] {'pid': 14870, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 09:32:17,042 [salt.state       :1951][INFO    ][14832] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:32:17.042599 duration_in_ms=1792.02
2019-04-16 09:32:17,046 [salt.state       :1780][INFO    ][14832] Running state [maas.process_machines] at time 09:32:17.046126
2019-04-16 09:32:17,046 [salt.state       :1813][INFO    ][14832] Executing state module.run for [maas.process_machines]
2019-04-16 09:32:17,047 [salt.utils.decorators:613 ][WARNING ][14832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 09:32:17,544 [salt.loaded.ext.module.maas:412 ][WARNING ][14832] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 09:32:17,545 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] 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=hyfth4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-16 09:32:18,641 [salt.loaded.ext.module.maas:412 ][WARNING ][14832] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 09:32:18,642 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] 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=64cna7 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-16 09:32:19,717 [salt.loaded.ext.module.maas:412 ][WARNING ][14832] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 09:32:19,718 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] 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=tfdnxc architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-16 09:32:20,820 [salt.loaded.ext.module.maas:412 ][WARNING ][14832] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 09:32:20,821 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] 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=be8xqk architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-16 09:32:21,895 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093221887265
2019-04-16 09:32:21,910 [salt.minion      :1432][INFO    ][15042] Starting a new job with PID 15042
2019-04-16 09:32:21,932 [salt.minion      :1711][INFO    ][15042] Returning information for job: 20190416093221887265
2019-04-16 09:32:21,941 [salt.loaded.ext.module.maas:412 ][WARNING ][14832] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 09:32:21,942 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] 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=x4h4k4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-16 09:32:22,938 [salt.state       :300 ][INFO    ][14832] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-16 09:32:22,940 [salt.state       :1951][INFO    ][14832] Completed state [maas.process_machines] at time 09:32:22.940247 duration_in_ms=5894.121
2019-04-16 09:32:22,943 [salt.minion      :1711][INFO    ][14832] Returning information for job: 20190416093206827923
2019-04-16 09:32:55,869 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command state.apply with jid 20190416093255860274
2019-04-16 09:32:55,883 [salt.minion      :1432][INFO    ][15147] Starting a new job with PID 15147
2019-04-16 09:33:04,111 [salt.state       :915 ][INFO    ][15147] Loading fresh modules for state activity
2019-04-16 09:33:04,197 [salt.state       :1780][INFO    ][15147] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:33:04.197625
2019-04-16 09:33:04,198 [salt.state       :1813][INFO    ][15147] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 09:33:04,200 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15147] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 09:33:06,000 [salt.state       :300 ][INFO    ][15147] {'pid': 15161, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 09:33:06,000 [salt.state       :1951][INFO    ][15147] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:33:06.000804 duration_in_ms=1803.179
2019-04-16 09:33:06,002 [salt.state       :1780][INFO    ][15147] Running state [maas.wait_for_machine_status] at time 09:33:06.002283
2019-04-16 09:33:06,002 [salt.state       :1813][INFO    ][15147] Executing state module.run for [maas.wait_for_machine_status]
2019-04-16 09:33:06,004 [salt.utils.decorators:613 ][WARNING ][15147] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 09:33:08,301 [salt.state       :300 ][INFO    ][15147] {'ret': True}
2019-04-16 09:33:08,302 [salt.state       :1951][INFO    ][15147] Completed state [maas.wait_for_machine_status] at time 09:33:08.302180 duration_in_ms=2299.896
2019-04-16 09:33:08,304 [salt.minion      :1711][INFO    ][15147] Returning information for job: 20190416093255860274
2019-04-16 09:33:08,821 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command state.apply with jid 20190416093308809391
2019-04-16 09:33:08,834 [salt.minion      :1432][INFO    ][15175] Starting a new job with PID 15175
2019-04-16 09:33:10,021 [salt.state       :915 ][INFO    ][15175] Loading fresh modules for state activity
2019-04-16 09:33:10,159 [salt.state       :1780][INFO    ][15175] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:33:10.159765
2019-04-16 09:33:10,160 [salt.state       :1813][INFO    ][15175] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 09:33:10,161 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15175] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 09:33:11,994 [salt.state       :300 ][INFO    ][15175] {'pid': 15182, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 09:33:11,994 [salt.state       :1951][INFO    ][15175] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:33:11.994733 duration_in_ms=1834.969
2019-04-16 09:33:11,996 [salt.state       :1780][INFO    ][15175] Running state [maas_machines_storage_cmp002_lvm] at time 09:33:11.996905
2019-04-16 09:33:11,997 [salt.state       :1813][INFO    ][15175] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-16 09:33:12,478 [salt.state       :300 ][INFO    ][15175] Machine cmp002 is not in Ready state.
2019-04-16 09:33:12,479 [salt.state       :1951][INFO    ][15175] Completed state [maas_machines_storage_cmp002_lvm] at time 09:33:12.479756 duration_in_ms=482.85
2019-04-16 09:33:12,480 [salt.state       :1780][INFO    ][15175] Running state [maas_machines_storage_cmp001_lvm] at time 09:33:12.480117
2019-04-16 09:33:12,480 [salt.state       :1813][INFO    ][15175] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-16 09:33:12,957 [salt.state       :300 ][INFO    ][15175] Machine cmp001 is not in Ready state.
2019-04-16 09:33:12,957 [salt.state       :1951][INFO    ][15175] Completed state [maas_machines_storage_cmp001_lvm] at time 09:33:12.957636 duration_in_ms=477.518
2019-04-16 09:33:12,960 [salt.minion      :1711][INFO    ][15175] Returning information for job: 20190416093308809391
2019-04-16 09:33:13,489 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command state.apply with jid 20190416093313482231
2019-04-16 09:33:13,506 [salt.minion      :1432][INFO    ][15205] Starting a new job with PID 15205
2019-04-16 09:33:14,608 [salt.state       :915 ][INFO    ][15205] Loading fresh modules for state activity
2019-04-16 09:33:14,689 [salt.state       :1780][INFO    ][15205] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:33:14.689530
2019-04-16 09:33:14,689 [salt.state       :1813][INFO    ][15205] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 09:33:14,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15205] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 09:33:16,474 [salt.state       :300 ][INFO    ][15205] {'pid': 15212, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 09:33:16,475 [salt.state       :1951][INFO    ][15205] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:33:16.475458 duration_in_ms=1785.928
2019-04-16 09:33:16,477 [salt.state       :1780][INFO    ][15205] Running state [maas.deploy_machines] at time 09:33:16.476985
2019-04-16 09:33:16,477 [salt.state       :1813][INFO    ][15205] Executing state module.run for [maas.deploy_machines]
2019-04-16 09:33:16,477 [salt.utils.decorators:613 ][WARNING ][15205] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 09:33:17,009 [salt.loaded.ext.module.maas:684 ][INFO    ][15205] deploymachines hwe_kernel=ga-16.04 system_id=x4h4k4 distro_series=xenial
2019-04-16 09:33:18,966 [salt.state       :300 ][INFO    ][15205] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-16 09:33:18,966 [salt.state       :1951][INFO    ][15205] Completed state [maas.deploy_machines] at time 09:33:18.966664 duration_in_ms=2489.678
2019-04-16 09:33:18,968 [salt.minion      :1711][INFO    ][15205] Returning information for job: 20190416093313482231
2019-04-16 09:33:19,497 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command state.apply with jid 20190416093319489255
2019-04-16 09:33:19,511 [salt.minion      :1432][INFO    ][15273] Starting a new job with PID 15273
2019-04-16 09:33:27,743 [salt.state       :915 ][INFO    ][15273] Loading fresh modules for state activity
2019-04-16 09:33:27,829 [salt.state       :1780][INFO    ][15273] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:33:27.829817
2019-04-16 09:33:27,830 [salt.state       :1813][INFO    ][15273] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 09:33:27,833 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15273] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 09:33:29,566 [salt.state       :300 ][INFO    ][15273] {'pid': 15287, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 09:33:29,566 [salt.state       :1951][INFO    ][15273] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:33:29.566643 duration_in_ms=1736.827
2019-04-16 09:33:29,568 [salt.state       :1780][INFO    ][15273] Running state [maas.wait_for_machine_status] at time 09:33:29.568784
2019-04-16 09:33:29,569 [salt.state       :1813][INFO    ][15273] Executing state module.run for [maas.wait_for_machine_status]
2019-04-16 09:33:29,569 [salt.utils.decorators:613 ][WARNING ][15273] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 09:33:32,080 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.50225616s left)
2019-04-16 09:33:34,593 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093334585002
2019-04-16 09:33:34,609 [salt.minion      :1432][INFO    ][15327] Starting a new job with PID 15327
2019-04-16 09:33:34,630 [salt.minion      :1711][INFO    ][15327] Returning information for job: 20190416093334585002
2019-04-16 09:34:04,520 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2215.06059408s left)
2019-04-16 09:34:04,739 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093404697561
2019-04-16 09:34:04,759 [salt.minion      :1432][INFO    ][15378] Starting a new job with PID 15378
2019-04-16 09:34:04,781 [salt.minion      :1711][INFO    ][15378] Returning information for job: 20190416093404697561
2019-04-16 09:34:34,745 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093434736474
2019-04-16 09:34:34,760 [salt.minion      :1432][INFO    ][15430] Starting a new job with PID 15430
2019-04-16 09:34:34,781 [salt.minion      :1711][INFO    ][15430] Returning information for job: 20190416093434736474
2019-04-16 09:34:36,822 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.75908804s left)
2019-04-16 09:35:04,933 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093504926098
2019-04-16 09:35:04,949 [salt.minion      :1432][INFO    ][15476] Starting a new job with PID 15476
2019-04-16 09:35:04,968 [salt.minion      :1711][INFO    ][15476] Returning information for job: 20190416093504926098
2019-04-16 09:35:09,220 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2150.360219s left)
2019-04-16 09:35:35,146 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093535138429
2019-04-16 09:35:35,159 [salt.minion      :1432][INFO    ][15536] Starting a new job with PID 15536
2019-04-16 09:35:35,181 [salt.minion      :1711][INFO    ][15536] Returning information for job: 20190416093535138429
2019-04-16 09:35:41,831 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2117.750283s left)
2019-04-16 09:36:05,357 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093605344131
2019-04-16 09:36:05,373 [salt.minion      :1432][INFO    ][15602] Starting a new job with PID 15602
2019-04-16 09:36:05,395 [salt.minion      :1711][INFO    ][15602] Returning information for job: 20190416093605344131
2019-04-16 09:36:14,229 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2085.35133195s left)
2019-04-16 09:36:35,377 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093635369274
2019-04-16 09:36:35,393 [salt.minion      :1432][INFO    ][15656] Starting a new job with PID 15656
2019-04-16 09:36:35,413 [salt.minion      :1711][INFO    ][15656] Returning information for job: 20190416093635369274
2019-04-16 09:36:46,552 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2053.02837396s left)
2019-04-16 09:37:05,593 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093705585609
2019-04-16 09:37:05,609 [salt.minion      :1432][INFO    ][15706] Starting a new job with PID 15706
2019-04-16 09:37:05,628 [salt.minion      :1711][INFO    ][15706] Returning information for job: 20190416093705585609
2019-04-16 09:37:18,913 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2020.66756701s left)
2019-04-16 09:37:35,611 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093735602754
2019-04-16 09:37:35,626 [salt.minion      :1432][INFO    ][15784] Starting a new job with PID 15784
2019-04-16 09:37:35,649 [salt.minion      :1711][INFO    ][15784] Returning information for job: 20190416093735602754
2019-04-16 09:37:51,510 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1988.070153s left)
2019-04-16 09:38:05,630 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093805623518
2019-04-16 09:38:05,645 [salt.minion      :1432][INFO    ][15861] Starting a new job with PID 15861
2019-04-16 09:38:05,664 [salt.minion      :1711][INFO    ][15861] Returning information for job: 20190416093805623518
2019-04-16 09:38:24,367 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1955.21352816s left)
2019-04-16 09:38:35,666 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093835658729
2019-04-16 09:38:35,686 [salt.minion      :1432][INFO    ][15920] Starting a new job with PID 15920
2019-04-16 09:38:35,710 [salt.minion      :1711][INFO    ][15920] Returning information for job: 20190416093835658729
2019-04-16 09:38:57,108 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1922.4728601s left)
2019-04-16 09:39:05,770 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093905761500
2019-04-16 09:39:05,790 [salt.minion      :1432][INFO    ][16123] Starting a new job with PID 16123
2019-04-16 09:39:05,813 [salt.minion      :1711][INFO    ][16123] Returning information for job: 20190416093905761500
2019-04-16 09:39:29,830 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1889.75057912s left)
2019-04-16 09:39:35,797 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416093935782441
2019-04-16 09:39:35,818 [salt.minion      :1432][INFO    ][16179] Starting a new job with PID 16179
2019-04-16 09:39:35,840 [salt.minion      :1711][INFO    ][16179] Returning information for job: 20190416093935782441
2019-04-16 09:40:02,710 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1856.87066102s left)
2019-04-16 09:40:05,909 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416094005896717
2019-04-16 09:40:05,928 [salt.minion      :1432][INFO    ][16275] Starting a new job with PID 16275
2019-04-16 09:40:05,951 [salt.minion      :1711][INFO    ][16275] Returning information for job: 20190416094005896717
2019-04-16 09:40:35,597 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1823.98316813s left)
2019-04-16 09:40:35,998 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416094035990767
2019-04-16 09:40:36,014 [salt.minion      :1432][INFO    ][16330] Starting a new job with PID 16330
2019-04-16 09:40:36,038 [salt.minion      :1711][INFO    ][16330] Returning information for job: 20190416094035990767
2019-04-16 09:41:06,133 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416094106126080
2019-04-16 09:41:06,149 [salt.minion      :1432][INFO    ][16463] Starting a new job with PID 16463
2019-04-16 09:41:06,169 [salt.minion      :1711][INFO    ][16463] Returning information for job: 20190416094106126080
2019-04-16 09:41:08,419 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1791.16177893s left)
2019-04-16 09:41:36,237 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416094136220218
2019-04-16 09:41:36,261 [salt.minion      :1432][INFO    ][16515] Starting a new job with PID 16515
2019-04-16 09:41:36,289 [salt.minion      :1711][INFO    ][16515] Returning information for job: 20190416094136220218
2019-04-16 09:41:41,457 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1758.12329507s left)
2019-04-16 09:42:06,394 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416094206379794
2019-04-16 09:42:06,418 [salt.minion      :1432][INFO    ][16629] Starting a new job with PID 16629
2019-04-16 09:42:06,450 [salt.minion      :1711][INFO    ][16629] Returning information for job: 20190416094206379794
2019-04-16 09:42:14,215 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1725.36549497s left)
2019-04-16 09:42:36,525 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416094236515559
2019-04-16 09:42:36,549 [salt.minion      :1432][INFO    ][16684] Starting a new job with PID 16684
2019-04-16 09:42:36,572 [salt.minion      :1711][INFO    ][16684] Returning information for job: 20190416094236515559
2019-04-16 09:42:47,124 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1692.45686603s left)
2019-04-16 09:43:06,683 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416094306671097
2019-04-16 09:43:06,704 [salt.minion      :1432][INFO    ][16736] Starting a new job with PID 16736
2019-04-16 09:43:06,727 [salt.minion      :1711][INFO    ][16736] Returning information for job: 20190416094306671097
2019-04-16 09:43:19,969 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1659.6116941s left)
2019-04-16 09:43:36,804 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416094336797376
2019-04-16 09:43:36,822 [salt.minion      :1432][INFO    ][16789] Starting a new job with PID 16789
2019-04-16 09:43:36,842 [salt.minion      :1711][INFO    ][16789] Returning information for job: 20190416094336797376
2019-04-16 09:43:52,579 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1627.00153804s left)
2019-04-16 09:44:06,851 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416094406844332
2019-04-16 09:44:06,870 [salt.minion      :1432][INFO    ][16837] Starting a new job with PID 16837
2019-04-16 09:44:06,893 [salt.minion      :1711][INFO    ][16837] Returning information for job: 20190416094406844332
2019-04-16 09:44:24,967 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1594.61353397s left)
2019-04-16 09:44:36,904 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416094436894467
2019-04-16 09:44:36,921 [salt.minion      :1432][INFO    ][16892] Starting a new job with PID 16892
2019-04-16 09:44:36,943 [salt.minion      :1711][INFO    ][16892] Returning information for job: 20190416094436894467
2019-04-16 09:44:57,705 [salt.loaded.ext.module.maas:1023][INFO    ][15273] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1561.87520313s left)
2019-04-16 09:45:07,002 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416094506993653
2019-04-16 09:45:07,018 [salt.minion      :1432][INFO    ][16942] Starting a new job with PID 16942
2019-04-16 09:45:07,042 [salt.minion      :1711][INFO    ][16942] Returning information for job: 20190416094506993653
2019-04-16 09:45:30,348 [salt.state       :300 ][INFO    ][15273] {'ret': True}
2019-04-16 09:45:30,348 [salt.state       :1951][INFO    ][15273] Completed state [maas.wait_for_machine_status] at time 09:45:30.348376 duration_in_ms=720779.589
2019-04-16 09:45:30,353 [salt.minion      :1711][INFO    ][15273] Returning information for job: 20190416093319489255
2019-04-16 09:45:40,107 [salt.utils.schedule:1377][INFO    ][3440] Running scheduled job: __mine_interval
2019-04-16 10:45:40,107 [salt.utils.schedule:1377][INFO    ][3440] Running scheduled job: __mine_interval
2019-04-16 11:26:19,869 [salt.minion      :1308][INFO    ][3440] User sudo_ubuntu Executing command cp.push_dir with jid 20190416112619855561
2019-04-16 11:26:19,892 [salt.minion      :1432][INFO    ][27554] Starting a new job with PID 27554
