2019-04-27 02:06:08,769 [salt.utils.decorators:613 ][WARNING ][2281] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:06:09,503 [salt.utils.decorators:613 ][WARNING ][2281] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:06:12,402 [salt.loaded.int.states.file:2298][WARNING ][2599] 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-27 02:06:29,705 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3043] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-27 02:06:29,724 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3043] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-27 02:06:29,764 [salt.utils.parsers:1051][WARNING ][380] Minion received a SIGTERM. Exiting.
2019-04-27 02:06:30,738 [salt.cli.daemons :293 ][INFO    ][3156] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-04-27 02:06:30,849 [salt.cli.daemons :82  ][INFO    ][3156] Starting up the Salt Minion
2019-04-27 02:06:30,849 [salt.utils.event :1017][INFO    ][3156] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-04-27 02:06:31,904 [salt.minion      :976 ][INFO    ][3156] Creating minion process manager
2019-04-27 02:06:33,743 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3156] Executing command ['date', '+%z'] in directory '/root'
2019-04-27 02:06:33,764 [salt.utils.schedule:568 ][INFO    ][3156] Updating job settings for scheduled job: __mine_interval
2019-04-27 02:06:33,766 [salt.minion      :1108][INFO    ][3156] Added mine.update to scheduler
2019-04-27 02:06:33,773 [salt.minion      :1975][INFO    ][3156] Minion is starting as user 'root'
2019-04-27 02:06:33,787 [salt.minion      :2336][INFO    ][3156] Minion is ready to receive requests!
2019-04-27 02:06:38,468 [salt.state       :2022][WARNING ][3047] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-27 02:06:41,458 [salt.utils.decorators:613 ][WARNING ][3047] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:06:42,732 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427020642724947
2019-04-27 02:06:42,744 [salt.minion      :1432][INFO    ][3702] Starting a new job with PID 3702
2019-04-27 02:06:42,764 [salt.minion      :1711][INFO    ][3702] Returning information for job: 20190427020642724947
2019-04-27 02:06:53,577 [salt.utils.decorators:613 ][WARNING ][3047] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:07:12,799 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427020712789320
2019-04-27 02:07:12,822 [salt.minion      :1432][INFO    ][4322] Starting a new job with PID 4322
2019-04-27 02:07:12,850 [salt.minion      :1711][INFO    ][4322] Returning information for job: 20190427020712789320
2019-04-27 02:07:19,258 [salt.utils.decorators:613 ][WARNING ][3047] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:07:20,353 [salt.utils.decorators:613 ][WARNING ][3047] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:07:22,410 [salt.loaded.ext.module.maasng:1008][WARNING ][3047] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-27 02:07:22,411 [salt.loaded.ext.module.maasng:1011][WARNING ][3047] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-27 02:07:23,139 [salt.loaded.ext.module.maasng:1235][WARNING ][3047] Ignoring parameter vlan:0
2019-04-27 02:07:24,757 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command state.apply with jid 20190427020724743784
2019-04-27 02:07:24,778 [salt.minion      :1432][INFO    ][4682] Starting a new job with PID 4682
2019-04-27 02:07:31,000 [salt.state       :915 ][INFO    ][4682] Loading fresh modules for state activity
2019-04-27 02:07:31,068 [salt.fileclient  :1219][INFO    ][4682] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-04-27 02:07:31,112 [salt.state       :1780][INFO    ][4682] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:07:31.112640
2019-04-27 02:07:31,112 [salt.state       :1813][INFO    ][4682] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-27 02:07:31,114 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4682] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-27 02:07:32,974 [salt.state       :300 ][INFO    ][4682] {'pid': 4723, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-27 02:07:32,975 [salt.state       :1951][INFO    ][4682] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:07:32.975731 duration_in_ms=1863.09
2019-04-27 02:07:32,978 [salt.state       :1780][INFO    ][4682] Running state [maas.process_machines] at time 02:07:32.978109
2019-04-27 02:07:32,978 [salt.state       :1813][INFO    ][4682] Executing state module.run for [maas.process_machines]
2019-04-27 02:07:32,980 [salt.utils.decorators:613 ][WARNING ][4682] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:07:33,046 [salt.loaded.ext.module.maas:412 ][WARNING ][4682] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-27 02:07:33,047 [salt.loaded.ext.module.maas:92  ][INFO    ][4682] 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-27 02:07:34,449 [salt.loaded.ext.module.maas:412 ][WARNING ][4682] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-27 02:07:34,449 [salt.loaded.ext.module.maas:92  ][INFO    ][4682] 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-27 02:07:35,786 [salt.loaded.ext.module.maas:412 ][WARNING ][4682] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-27 02:07:35,786 [salt.loaded.ext.module.maas:92  ][INFO    ][4682] 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-27 02:07:37,117 [salt.loaded.ext.module.maas:412 ][WARNING ][4682] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-27 02:07:37,118 [salt.loaded.ext.module.maas:92  ][INFO    ][4682] 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-27 02:07:38,322 [salt.loaded.ext.module.maas:412 ][WARNING ][4682] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-27 02:07:38,323 [salt.loaded.ext.module.maas:92  ][INFO    ][4682] 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-27 02:07:39,392 [salt.state       :300 ][INFO    ][4682] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-27 02:07:39,392 [salt.state       :1951][INFO    ][4682] Completed state [maas.process_machines] at time 02:07:39.392887 duration_in_ms=6414.778
2019-04-27 02:07:39,396 [salt.minion      :1711][INFO    ][4682] Returning information for job: 20190427020724743784
2019-04-27 02:08:10,738 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command state.apply with jid 20190427020810728058
2019-04-27 02:08:10,757 [salt.minion      :1432][INFO    ][5076] Starting a new job with PID 5076
2019-04-27 02:08:16,689 [salt.state       :915 ][INFO    ][5076] Loading fresh modules for state activity
2019-04-27 02:08:16,741 [salt.fileclient  :1219][INFO    ][5076] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-27 02:08:16,792 [salt.state       :1780][INFO    ][5076] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:16.792634
2019-04-27 02:08:16,792 [salt.state       :1813][INFO    ][5076] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-27 02:08:16,794 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5076] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-27 02:08:18,625 [salt.state       :300 ][INFO    ][5076] {'pid': 5089, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-27 02:08:18,626 [salt.state       :1951][INFO    ][5076] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:18.626169 duration_in_ms=1833.535
2019-04-27 02:08:18,630 [salt.state       :1780][INFO    ][5076] Running state [maas.wait_for_machine_status] at time 02:08:18.630112
2019-04-27 02:08:18,630 [salt.state       :1813][INFO    ][5076] Executing state module.run for [maas.wait_for_machine_status]
2019-04-27 02:08:18,631 [salt.utils.decorators:613 ][WARNING ][5076] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:08:19,440 [salt.loaded.ext.module.maas:1023][INFO    ][5076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.204952s left)
2019-04-27 02:08:25,845 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427020825837094
2019-04-27 02:08:25,869 [salt.minion      :1432][INFO    ][5101] Starting a new job with PID 5101
2019-04-27 02:08:25,896 [salt.minion      :1711][INFO    ][5101] Returning information for job: 20190427020825837094
2019-04-27 02:08:50,472 [salt.loaded.ext.module.maas:1023][INFO    ][5076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.17275s left)
2019-04-27 02:08:56,050 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427020856041815
2019-04-27 02:08:56,071 [salt.minion      :1432][INFO    ][5204] Starting a new job with PID 5204
2019-04-27 02:08:56,113 [salt.minion      :1711][INFO    ][5204] Returning information for job: 20190427020856041815
2019-04-27 02:09:21,289 [salt.loaded.ext.module.maas:1023][INFO    ][5076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.35535598s left)
2019-04-27 02:09:26,257 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427020926249713
2019-04-27 02:09:26,280 [salt.minion      :1432][INFO    ][5228] Starting a new job with PID 5228
2019-04-27 02:09:26,311 [salt.minion      :1711][INFO    ][5228] Returning information for job: 20190427020926249713
2019-04-27 02:09:51,932 [salt.loaded.ext.module.maas:1023][INFO    ][5076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.71272993s left)
2019-04-27 02:09:56,462 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427020956449736
2019-04-27 02:09:56,481 [salt.minion      :1432][INFO    ][5304] Starting a new job with PID 5304
2019-04-27 02:09:56,508 [salt.minion      :1711][INFO    ][5304] Returning information for job: 20190427020956449736
2019-04-27 02:10:22,966 [salt.loaded.ext.module.maas:1023][INFO    ][5076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.67869782s left)
2019-04-27 02:10:26,660 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021026651782
2019-04-27 02:10:26,683 [salt.minion      :1432][INFO    ][5352] Starting a new job with PID 5352
2019-04-27 02:10:26,711 [salt.minion      :1711][INFO    ][5352] Returning information for job: 20190427021026651782
2019-04-27 02:10:54,160 [salt.loaded.ext.module.maas:1023][INFO    ][5076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.48431301s left)
2019-04-27 02:10:56,873 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021056864789
2019-04-27 02:10:56,887 [salt.minion      :1432][INFO    ][5566] Starting a new job with PID 5566
2019-04-27 02:10:56,913 [salt.minion      :1711][INFO    ][5566] Returning information for job: 20190427021056864789
2019-04-27 02:11:25,438 [salt.loaded.ext.module.maas:1023][INFO    ][5076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.20701694s left)
2019-04-27 02:11:27,084 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021127066349
2019-04-27 02:11:27,103 [salt.minion      :1432][INFO    ][5698] Starting a new job with PID 5698
2019-04-27 02:11:27,131 [salt.minion      :1711][INFO    ][5698] Returning information for job: 20190427021127066349
2019-04-27 02:11:56,669 [salt.loaded.ext.module.maas:1023][INFO    ][5076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.97556686s left)
2019-04-27 02:11:57,101 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021157089541
2019-04-27 02:11:57,114 [salt.minion      :1432][INFO    ][5971] Starting a new job with PID 5971
2019-04-27 02:11:57,138 [salt.minion      :1711][INFO    ][5971] Returning information for job: 20190427021157089541
2019-04-27 02:12:27,323 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021227311670
2019-04-27 02:12:27,338 [salt.minion      :1432][INFO    ][6160] Starting a new job with PID 6160
2019-04-27 02:12:27,362 [salt.minion      :1711][INFO    ][6160] Returning information for job: 20190427021227311670
2019-04-27 02:12:28,637 [salt.loaded.ext.module.maas:1023][INFO    ][5076] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.00748587s left)
2019-04-27 02:12:57,417 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021257409858
2019-04-27 02:12:57,439 [salt.minion      :1432][INFO    ][6635] Starting a new job with PID 6635
2019-04-27 02:12:57,472 [salt.minion      :1711][INFO    ][6635] Returning information for job: 20190427021257409858
2019-04-27 02:13:00,752 [salt.loaded.ext.module.maas:1023][INFO    ][5076] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.89266682s left)
2019-04-27 02:13:27,514 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021327499841
2019-04-27 02:13:27,535 [salt.minion      :1432][INFO    ][6741] Starting a new job with PID 6741
2019-04-27 02:13:27,561 [salt.minion      :1711][INFO    ][6741] Returning information for job: 20190427021327499841
2019-04-27 02:13:32,891 [salt.loaded.ext.module.maas:1023][INFO    ][5076] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.75316596s left)
2019-04-27 02:13:57,621 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021357613892
2019-04-27 02:13:57,643 [salt.minion      :1432][INFO    ][6961] Starting a new job with PID 6961
2019-04-27 02:13:57,669 [salt.minion      :1711][INFO    ][6961] Returning information for job: 20190427021357613892
2019-04-27 02:14:05,742 [salt.state       :300 ][INFO    ][5076] {'ret': True}
2019-04-27 02:14:05,743 [salt.state       :1951][INFO    ][5076] Completed state [maas.wait_for_machine_status] at time 02:14:05.742914 duration_in_ms=347112.801
2019-04-27 02:14:05,747 [salt.minion      :1711][INFO    ][5076] Returning information for job: 20190427020810728058
2019-04-27 02:14:06,444 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command state.apply with jid 20190427021406432370
2019-04-27 02:14:06,464 [salt.minion      :1432][INFO    ][6987] Starting a new job with PID 6987
2019-04-27 02:14:12,493 [salt.state       :915 ][INFO    ][6987] Loading fresh modules for state activity
2019-04-27 02:14:12,541 [salt.fileclient  :1219][INFO    ][6987] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-27 02:14:12,637 [salt.state       :1780][INFO    ][6987] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:12.637113
2019-04-27 02:14:12,637 [salt.state       :1813][INFO    ][6987] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-27 02:14:12,639 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6987] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-27 02:14:14,556 [salt.state       :300 ][INFO    ][6987] {'pid': 7028, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-27 02:14:14,556 [salt.state       :1951][INFO    ][6987] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:14.556514 duration_in_ms=1919.401
2019-04-27 02:14:14,560 [salt.state       :1780][INFO    ][6987] Running state [maas_machines_storage_cmp002_lvm] at time 02:14:14.560569
2019-04-27 02:14:14,560 [salt.state       :1813][INFO    ][6987] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-27 02:14:15,623 [salt.loaded.ext.module.maasng:610 ][INFO    ][6987] syemn3
2019-04-27 02:14:15,623 [salt.loaded.ext.module.maasng:626 ][INFO    ][6987] sda
2019-04-27 02:14:16,197 [salt.loaded.ext.module.maasng:361 ][INFO    ][6987] syemn3
2019-04-27 02:14:16,286 [salt.loaded.ext.module.maasng:367 ][INFO    ][6987] [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'65a2d88c-0a5c-424c-af3e-899537693791', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'syemn3', u'device_id': 1, u'filesystem': {u'uuid': u'f6e8988c-d9ee-4ace-aa30-d2c9f45308fb', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/blockdevices/1/partition/1'}], u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'syemn3', 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'size': 800109715456, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/blockdevices/1/'}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'0dd5a9f4-4bac-4e6f-a5bf-cb536b20dce0', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'syemn3', u'partition_table_type': None, u'filesystem': {u'uuid': u'f1893a6b-7d5a-4eeb-8670-d7188a0537a2', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 800097042432, u'used_size': 800097042432, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/blockdevices/3/'}]
2019-04-27 02:14:16,287 [salt.loaded.ext.module.maasng:632 ][INFO    ][6987] vgroot
2019-04-27 02:14:16,287 [salt.loaded.ext.module.maasng:635 ][INFO    ][6987] lvroot
2019-04-27 02:14:16,288 [salt.loaded.ext.module.maasng:639 ][INFO    ][6987] 107374182400
2019-04-27 02:14:16,782 [salt.loaded.ext.module.maasng:645 ][INFO    ][6987] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 1, u'system_id': u'syemn3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/blockdevices/1/', u'partitions': [{u'uuid': u'e3edb23a-804d-44df-831a-1c8db3c88798', u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'syemn3', u'filesystem': {u'uuid': u'6a7642a5-8dc1-40a7-85eb-1e7daff31407', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}]}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/syemn3/', u'hostname': u'cmp002', u'storage': 800109.715456, u'status_action': u'', u'system_id': u'syemn3', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'uuid': u'5892f92c-948a-463b-a970-b0b6ee375b83', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'f2253e3c-61ca-42de-9d2d-b3a02335b31c', u'id': 11, u'system_id': u'syemn3', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/blockdevices/11/', u'partitions': []}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 1, u'system_id': u'syemn3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/blockdevices/1/', u'partitions': [{u'uuid': u'e3edb23a-804d-44df-831a-1c8db3c88798', u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'syemn3', u'filesystem': {u'uuid': u'6a7642a5-8dc1-40a7-85eb-1e7daff31407', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}]}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'uuid': u'5892f92c-948a-463b-a970-b0b6ee375b83', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'f2253e3c-61ca-42de-9d2d-b3a02335b31c', u'id': 11, u'system_id': u'syemn3', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/blockdevices/11/', u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 1, u'system_id': u'syemn3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/blockdevices/1/', u'partitions': [{u'uuid': u'e3edb23a-804d-44df-831a-1c8db3c88798', u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'syemn3', u'filesystem': {u'uuid': u'6a7642a5-8dc1-40a7-85eb-1e7daff31407', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}]}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'syemn3', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'dx86rx', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, 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'dx86rx', 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'fabric': u'pxe_admin'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:10:18', 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'dx86rx', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'syemn3', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:07:50', u'id': 14, u'discovered': None, u'parents': [], u'system_id': u'syemn3', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/interfaces/14/'}, {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'dx86rx', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 19, 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'dx86rx', 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'fabric': u'pxe_admin'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:10:1c', u'id': 13, 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'dx86rx', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'system_id': u'syemn3', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/interfaces/13/'}, {u'name': u'ens2f1', u'links': [{u'id': 20, 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'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'fabric': u'fabric-0'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:12:49', u'id': 15, u'discovered': None, u'parents': [], u'system_id': u'syemn3', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'id': 21, 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'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'fabric': u'fabric-0'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:12:48', u'id': 12, u'discovered': None, u'parents': [], u'system_id': u'syemn3', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/interfaces/12/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:07:51', u'id': 11, u'discovered': None, u'parents': [], u'system_id': u'syemn3', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/interfaces/11/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'dx86rx', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, 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'dx86rx', 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'fabric': u'pxe_admin'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:10:18', 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'dx86rx', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'syemn3', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/syemn3/interfaces/4/'}, u'current_commissioning_result_id': 2, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-04-27 02:14:16,784 [salt.state       :300 ][INFO    ][6987] {'new': {'storage_layout': 'lvm'}}
2019-04-27 02:14:16,784 [salt.state       :1951][INFO    ][6987] Completed state [maas_machines_storage_cmp002_lvm] at time 02:14:16.784328 duration_in_ms=2223.758
2019-04-27 02:14:16,784 [salt.state       :1780][INFO    ][6987] Running state [maas_machines_storage_cmp001_lvm] at time 02:14:16.784730
2019-04-27 02:14:16,785 [salt.state       :1813][INFO    ][6987] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-27 02:14:17,842 [salt.loaded.ext.module.maasng:610 ][INFO    ][6987] 47rbky
2019-04-27 02:14:17,843 [salt.loaded.ext.module.maasng:626 ][INFO    ][6987] sda
2019-04-27 02:14:18,408 [salt.loaded.ext.module.maasng:361 ][INFO    ][6987] 47rbky
2019-04-27 02:14:18,501 [salt.loaded.ext.module.maasng:367 ][INFO    ][6987] [{u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'55754923-73d4-4fcf-8f93-2f2ec21ff0ee', u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'47rbky', u'filesystem': {u'uuid': u'1f606b03-daeb-4f8d-aa0e-1819d0c21399', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'id': 2, u'system_id': u'47rbky', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/blockdevices/4/', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'7e3a92c7-9f38-4a9f-9a87-41f13375eb30', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'4d2c1ecf-8d80-40cf-989d-19b7c6f2c54c', u'id': 4, u'system_id': u'47rbky', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'serial': None, u'size': 800097042432}]
2019-04-27 02:14:18,502 [salt.loaded.ext.module.maasng:632 ][INFO    ][6987] vgroot
2019-04-27 02:14:18,502 [salt.loaded.ext.module.maasng:635 ][INFO    ][6987] lvroot
2019-04-27 02:14:18,503 [salt.loaded.ext.module.maasng:639 ][INFO    ][6987] 107374182400
2019-04-27 02:14:19,088 [salt.loaded.ext.module.maasng:645 ][INFO    ][6987] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 2, u'system_id': u'47rbky', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/blockdevices/2/', u'partitions': [{u'uuid': u'15fe1e1b-ccb8-4d6d-8f74-961b07415b67', u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'47rbky', u'filesystem': {u'uuid': u'03105c4d-8be6-41d8-bf5e-c6fdc053c284', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/47rbky/', u'hostname': u'cmp001', u'storage': 800109.715456, u'status_action': u'', u'system_id': u'47rbky', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'uuid': u'f1dc9d5e-63d5-4803-ad9a-6be36f21af5a', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'653ba538-0909-4bba-a658-ac7f372d953e', u'id': 12, u'system_id': u'47rbky', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/blockdevices/12/', u'partitions': []}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 2, u'system_id': u'47rbky', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/blockdevices/2/', u'partitions': [{u'uuid': u'15fe1e1b-ccb8-4d6d-8f74-961b07415b67', u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'47rbky', u'filesystem': {u'uuid': u'03105c4d-8be6-41d8-bf5e-c6fdc053c284', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'uuid': u'f1dc9d5e-63d5-4803-ad9a-6be36f21af5a', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'653ba538-0909-4bba-a658-ac7f372d953e', u'id': 12, u'system_id': u'47rbky', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/blockdevices/12/', u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 2, u'system_id': u'47rbky', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/blockdevices/2/', u'partitions': [{u'uuid': u'15fe1e1b-ccb8-4d6d-8f74-961b07415b67', u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'47rbky', u'filesystem': {u'uuid': u'03105c4d-8be6-41d8-bf5e-c6fdc053c284', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'47rbky', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'dx86rx', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.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'dx86rx', 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'fabric': u'pxe_admin'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, 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'dx86rx', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'47rbky', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 16, u'discovered': None, u'parents': [], u'system_id': u'47rbky', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/interfaces/16/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 19, u'discovered': None, u'parents': [], u'system_id': u'47rbky', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{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': False, u'fabric_id': 0, 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'fabric': u'fabric-0'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 17, u'discovered': None, u'parents': [], u'system_id': u'47rbky', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/interfaces/17/'}, {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'dx86rx', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'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': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'dx86rx', 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'fabric': u'pxe_admin'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 18, 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'dx86rx', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.41'}], u'parents': [], u'system_id': u'47rbky', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/interfaces/18/'}, {u'name': u'ens2f1', 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'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'fabric': u'fabric-0'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 20, u'discovered': None, u'parents': [], u'system_id': u'47rbky', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'dx86rx', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.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'dx86rx', 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'fabric': u'pxe_admin'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, 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'dx86rx', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'47rbky', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/47rbky/interfaces/5/'}, u'current_commissioning_result_id': 4, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-04-27 02:14:19,089 [salt.state       :300 ][INFO    ][6987] {'new': {'storage_layout': 'lvm'}}
2019-04-27 02:14:19,089 [salt.state       :1951][INFO    ][6987] Completed state [maas_machines_storage_cmp001_lvm] at time 02:14:19.089915 duration_in_ms=2305.184
2019-04-27 02:14:19,093 [salt.minion      :1711][INFO    ][6987] Returning information for job: 20190427021406432370
2019-04-27 02:14:19,798 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command state.apply with jid 20190427021419786440
2019-04-27 02:14:19,819 [salt.minion      :1432][INFO    ][7047] Starting a new job with PID 7047
2019-04-27 02:14:20,820 [salt.state       :915 ][INFO    ][7047] Loading fresh modules for state activity
2019-04-27 02:14:20,881 [salt.fileclient  :1219][INFO    ][7047] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-27 02:14:20,932 [salt.state       :1780][INFO    ][7047] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:20.932102
2019-04-27 02:14:20,932 [salt.state       :1813][INFO    ][7047] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-27 02:14:20,934 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7047] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-27 02:14:22,896 [salt.state       :300 ][INFO    ][7047] {'pid': 7055, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-27 02:14:22,897 [salt.state       :1951][INFO    ][7047] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:22.896966 duration_in_ms=1964.864
2019-04-27 02:14:22,899 [salt.state       :1780][INFO    ][7047] Running state [maas.deploy_machines] at time 02:14:22.899773
2019-04-27 02:14:22,900 [salt.state       :1813][INFO    ][7047] Executing state module.run for [maas.deploy_machines]
2019-04-27 02:14:22,901 [salt.utils.decorators:613 ][WARNING ][7047] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:14:23,476 [salt.loaded.ext.module.maas:684 ][INFO    ][7047] deploymachines hwe_kernel=ga-16.04 system_id=syemn3 distro_series=xenial
2019-04-27 02:14:25,858 [salt.loaded.ext.module.maas:684 ][INFO    ][7047] deploymachines hwe_kernel=ga-16.04 system_id=47rbky distro_series=xenial
2019-04-27 02:14:28,208 [salt.loaded.ext.module.maas:684 ][INFO    ][7047] deploymachines hwe_kernel=ga-16.04 system_id=7ekstm distro_series=xenial
2019-04-27 02:14:30,550 [salt.loaded.ext.module.maas:684 ][INFO    ][7047] deploymachines hwe_kernel=ga-16.04 system_id=mbr84t distro_series=xenial
2019-04-27 02:14:32,915 [salt.loaded.ext.module.maas:684 ][INFO    ][7047] deploymachines hwe_kernel=ga-16.04 system_id=demp34 distro_series=xenial
2019-04-27 02:14:34,872 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021434861540
2019-04-27 02:14:34,892 [salt.minion      :1432][INFO    ][7320] Starting a new job with PID 7320
2019-04-27 02:14:34,917 [salt.minion      :1711][INFO    ][7320] Returning information for job: 20190427021434861540
2019-04-27 02:14:35,312 [salt.state       :300 ][INFO    ][7047] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-27 02:14:35,313 [salt.state       :1951][INFO    ][7047] Completed state [maas.deploy_machines] at time 02:14:35.313154 duration_in_ms=12413.381
2019-04-27 02:14:35,316 [salt.minion      :1711][INFO    ][7047] Returning information for job: 20190427021419786440
2019-04-27 02:14:36,010 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command state.apply with jid 20190427021435995980
2019-04-27 02:14:36,032 [salt.minion      :1432][INFO    ][7337] Starting a new job with PID 7337
2019-04-27 02:14:42,080 [salt.state       :915 ][INFO    ][7337] Loading fresh modules for state activity
2019-04-27 02:14:42,138 [salt.fileclient  :1219][INFO    ][7337] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-27 02:14:42,187 [salt.state       :1780][INFO    ][7337] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:42.187323
2019-04-27 02:14:42,187 [salt.state       :1813][INFO    ][7337] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-27 02:14:42,189 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7337] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-27 02:14:44,151 [salt.state       :300 ][INFO    ][7337] {'pid': 7380, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-27 02:14:44,152 [salt.state       :1951][INFO    ][7337] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:44.152509 duration_in_ms=1965.184
2019-04-27 02:14:44,156 [salt.state       :1780][INFO    ][7337] Running state [maas.wait_for_machine_status] at time 02:14:44.156662
2019-04-27 02:14:44,157 [salt.state       :1813][INFO    ][7337] Executing state module.run for [maas.wait_for_machine_status]
2019-04-27 02:14:44,158 [salt.utils.decorators:613 ][WARNING ][7337] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:14:47,032 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.14051199s left)
2019-04-27 02:14:51,042 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021451027843
2019-04-27 02:14:51,065 [salt.minion      :1432][INFO    ][7410] Starting a new job with PID 7410
2019-04-27 02:14:51,092 [salt.minion      :1711][INFO    ][7410] Returning information for job: 20190427021451027843
2019-04-27 02:15:19,483 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.69018316s left)
2019-04-27 02:15:21,096 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021521087757
2019-04-27 02:15:21,108 [salt.minion      :1432][INFO    ][7435] Starting a new job with PID 7435
2019-04-27 02:15:21,133 [salt.minion      :1711][INFO    ][7435] Returning information for job: 20190427021521087757
2019-04-27 02:15:51,141 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021551133474
2019-04-27 02:15:51,154 [salt.minion      :1432][INFO    ][7509] Starting a new job with PID 7509
2019-04-27 02:15:51,184 [salt.minion      :1711][INFO    ][7509] Returning information for job: 20190427021551133474
2019-04-27 02:15:52,307 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.86540818s left)
2019-04-27 02:16:21,200 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021621188760
2019-04-27 02:16:21,223 [salt.minion      :1432][INFO    ][7532] Starting a new job with PID 7532
2019-04-27 02:16:21,250 [salt.minion      :1711][INFO    ][7532] Returning information for job: 20190427021621188760
2019-04-27 02:16:24,878 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2149.29486418s left)
2019-04-27 02:16:51,271 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021651257988
2019-04-27 02:16:51,293 [salt.minion      :1432][INFO    ][7627] Starting a new job with PID 7627
2019-04-27 02:16:51,318 [salt.minion      :1711][INFO    ][7627] Returning information for job: 20190427021651257988
2019-04-27 02:16:57,789 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2116.38357115s left)
2019-04-27 02:17:21,320 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021721308565
2019-04-27 02:17:21,343 [salt.minion      :1432][INFO    ][7691] Starting a new job with PID 7691
2019-04-27 02:17:21,370 [salt.minion      :1711][INFO    ][7691] Returning information for job: 20190427021721308565
2019-04-27 02:17:30,451 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.72136402s left)
2019-04-27 02:17:51,383 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021751373864
2019-04-27 02:17:51,403 [salt.minion      :1432][INFO    ][7916] Starting a new job with PID 7916
2019-04-27 02:17:51,431 [salt.minion      :1711][INFO    ][7916] Returning information for job: 20190427021751373864
2019-04-27 02:18:03,274 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.89840508s left)
2019-04-27 02:18:21,409 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021821395620
2019-04-27 02:18:21,432 [salt.minion      :1432][INFO    ][8033] Starting a new job with PID 8033
2019-04-27 02:18:21,463 [salt.minion      :1711][INFO    ][8033] Returning information for job: 20190427021821395620
2019-04-27 02:18:36,002 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2018.1702981s left)
2019-04-27 02:18:51,480 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021851470732
2019-04-27 02:18:51,493 [salt.minion      :1432][INFO    ][8282] Starting a new job with PID 8282
2019-04-27 02:18:51,525 [salt.minion      :1711][INFO    ][8282] Returning information for job: 20190427021851470732
2019-04-27 02:19:09,192 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.98071718s left)
2019-04-27 02:19:21,575 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021921566046
2019-04-27 02:19:21,595 [salt.minion      :1432][INFO    ][8404] Starting a new job with PID 8404
2019-04-27 02:19:21,624 [salt.minion      :1711][INFO    ][8404] Returning information for job: 20190427021921566046
2019-04-27 02:19:42,119 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1952.05316114s left)
2019-04-27 02:19:51,710 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427021951695876
2019-04-27 02:19:51,728 [salt.minion      :1432][INFO    ][8696] Starting a new job with PID 8696
2019-04-27 02:19:51,756 [salt.minion      :1711][INFO    ][8696] Returning information for job: 20190427021951695876
2019-04-27 02:20:15,134 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1919.03874803s left)
2019-04-27 02:20:21,822 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022021808613
2019-04-27 02:20:21,841 [salt.minion      :1432][INFO    ][8812] Starting a new job with PID 8812
2019-04-27 02:20:21,873 [salt.minion      :1711][INFO    ][8812] Returning information for job: 20190427022021808613
2019-04-27 02:20:47,774 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1886.39836502s left)
2019-04-27 02:20:51,968 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022051957303
2019-04-27 02:20:51,986 [salt.minion      :1432][INFO    ][9059] Starting a new job with PID 9059
2019-04-27 02:20:52,015 [salt.minion      :1711][INFO    ][9059] Returning information for job: 20190427022051957303
2019-04-27 02:21:20,816 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1853.35627604s left)
2019-04-27 02:21:22,073 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022122059831
2019-04-27 02:21:22,090 [salt.minion      :1432][INFO    ][9149] Starting a new job with PID 9149
2019-04-27 02:21:22,121 [salt.minion      :1711][INFO    ][9149] Returning information for job: 20190427022122059831
2019-04-27 02:21:52,201 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022152193693
2019-04-27 02:21:52,214 [salt.minion      :1432][INFO    ][9461] Starting a new job with PID 9461
2019-04-27 02:21:52,244 [salt.minion      :1711][INFO    ][9461] Returning information for job: 20190427022152193693
2019-04-27 02:21:53,450 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1820.72250915s left)
2019-04-27 02:22:22,329 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022222316963
2019-04-27 02:22:22,348 [salt.minion      :1432][INFO    ][9563] Starting a new job with PID 9563
2019-04-27 02:22:22,374 [salt.minion      :1711][INFO    ][9563] Returning information for job: 20190427022222316963
2019-04-27 02:22:26,137 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1788.03585005s left)
2019-04-27 02:22:52,497 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022252478373
2019-04-27 02:22:52,519 [salt.minion      :1432][INFO    ][9791] Starting a new job with PID 9791
2019-04-27 02:22:52,546 [salt.minion      :1711][INFO    ][9791] Returning information for job: 20190427022252478373
2019-04-27 02:22:58,865 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1755.30723s left)
2019-04-27 02:23:22,621 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022322611730
2019-04-27 02:23:22,639 [salt.minion      :1432][INFO    ][9853] Starting a new job with PID 9853
2019-04-27 02:23:22,666 [salt.minion      :1711][INFO    ][9853] Returning information for job: 20190427022322611730
2019-04-27 02:23:31,591 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1722.58124709s left)
2019-04-27 02:23:52,764 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022352752319
2019-04-27 02:23:52,784 [salt.minion      :1432][INFO    ][9930] Starting a new job with PID 9930
2019-04-27 02:23:52,810 [salt.minion      :1711][INFO    ][9930] Returning information for job: 20190427022352752319
2019-04-27 02:24:04,120 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1690.05208015s left)
2019-04-27 02:24:22,870 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022422858027
2019-04-27 02:24:22,891 [salt.minion      :1432][INFO    ][10004] Starting a new job with PID 10004
2019-04-27 02:24:22,919 [salt.minion      :1711][INFO    ][10004] Returning information for job: 20190427022422858027
2019-04-27 02:24:36,855 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1657.31724s left)
2019-04-27 02:24:52,916 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022452901173
2019-04-27 02:24:52,937 [salt.minion      :1432][INFO    ][10264] Starting a new job with PID 10264
2019-04-27 02:24:52,964 [salt.minion      :1711][INFO    ][10264] Returning information for job: 20190427022452901173
2019-04-27 02:25:09,412 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1624.76075411s left)
2019-04-27 02:25:23,015 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022523002171
2019-04-27 02:25:23,034 [salt.minion      :1432][INFO    ][10338] Starting a new job with PID 10338
2019-04-27 02:25:23,060 [salt.minion      :1711][INFO    ][10338] Returning information for job: 20190427022523002171
2019-04-27 02:25:42,300 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1591.87274718s left)
2019-04-27 02:25:53,185 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022553171414
2019-04-27 02:25:53,206 [salt.minion      :1432][INFO    ][10416] Starting a new job with PID 10416
2019-04-27 02:25:53,238 [salt.minion      :1711][INFO    ][10416] Returning information for job: 20190427022553171414
2019-04-27 02:26:15,081 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1559.09214401s left)
2019-04-27 02:26:23,344 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022623332276
2019-04-27 02:26:23,361 [salt.minion      :1432][INFO    ][10441] Starting a new job with PID 10441
2019-04-27 02:26:23,394 [salt.minion      :1711][INFO    ][10441] Returning information for job: 20190427022623332276
2019-04-27 02:26:47,737 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1526.43589115s left)
2019-04-27 02:26:53,539 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022653528804
2019-04-27 02:26:53,554 [salt.minion      :1432][INFO    ][10723] Starting a new job with PID 10723
2019-04-27 02:26:53,585 [salt.minion      :1711][INFO    ][10723] Returning information for job: 20190427022653528804
2019-04-27 02:27:20,266 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1493.90655303s left)
2019-04-27 02:27:23,713 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022723705968
2019-04-27 02:27:23,724 [salt.minion      :1432][INFO    ][10847] Starting a new job with PID 10847
2019-04-27 02:27:23,752 [salt.minion      :1711][INFO    ][10847] Returning information for job: 20190427022723705968
2019-04-27 02:27:53,125 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1461.04730606s left)
2019-04-27 02:27:53,929 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022753913619
2019-04-27 02:27:53,949 [salt.minion      :1432][INFO    ][11070] Starting a new job with PID 11070
2019-04-27 02:27:53,977 [salt.minion      :1711][INFO    ][11070] Returning information for job: 20190427022753913619
2019-04-27 02:28:23,982 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022823973228
2019-04-27 02:28:23,999 [salt.minion      :1432][INFO    ][11093] Starting a new job with PID 11093
2019-04-27 02:28:24,033 [salt.minion      :1711][INFO    ][11093] Returning information for job: 20190427022823973228
2019-04-27 02:28:25,794 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1428.37892699s left)
2019-04-27 02:28:54,056 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022854046323
2019-04-27 02:28:54,080 [salt.minion      :1432][INFO    ][11166] Starting a new job with PID 11166
2019-04-27 02:28:54,109 [salt.minion      :1711][INFO    ][11166] Returning information for job: 20190427022854046323
2019-04-27 02:28:58,495 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1395.67759109s left)
2019-04-27 02:29:24,077 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022924064248
2019-04-27 02:29:24,098 [salt.minion      :1432][INFO    ][11190] Starting a new job with PID 11190
2019-04-27 02:29:24,125 [salt.minion      :1711][INFO    ][11190] Returning information for job: 20190427022924064248
2019-04-27 02:29:31,084 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1363.08867908s left)
2019-04-27 02:29:54,097 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427022954083430
2019-04-27 02:29:54,118 [salt.minion      :1432][INFO    ][11264] Starting a new job with PID 11264
2019-04-27 02:29:54,149 [salt.minion      :1711][INFO    ][11264] Returning information for job: 20190427022954083430
2019-04-27 02:30:03,845 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1330.32764816s left)
2019-04-27 02:30:24,144 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023024136571
2019-04-27 02:30:24,164 [salt.minion      :1432][INFO    ][11289] Starting a new job with PID 11289
2019-04-27 02:30:24,197 [salt.minion      :1711][INFO    ][11289] Returning information for job: 20190427023024136571
2019-04-27 02:30:36,536 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1297.63645911s left)
2019-04-27 02:30:54,177 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023054169670
2019-04-27 02:30:54,191 [salt.minion      :1432][INFO    ][11368] Starting a new job with PID 11368
2019-04-27 02:30:54,220 [salt.minion      :1711][INFO    ][11368] Returning information for job: 20190427023054169670
2019-04-27 02:31:09,282 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1264.89063501s left)
2019-04-27 02:31:24,257 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023124243997
2019-04-27 02:31:24,282 [salt.minion      :1432][INFO    ][11399] Starting a new job with PID 11399
2019-04-27 02:31:24,312 [salt.minion      :1711][INFO    ][11399] Returning information for job: 20190427023124243997
2019-04-27 02:31:41,973 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1232.19953108s left)
2019-04-27 02:31:54,312 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023154303795
2019-04-27 02:31:54,333 [salt.minion      :1432][INFO    ][11476] Starting a new job with PID 11476
2019-04-27 02:31:54,364 [salt.minion      :1711][INFO    ][11476] Returning information for job: 20190427023154303795
2019-04-27 02:32:14,665 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1199.5078392s left)
2019-04-27 02:32:24,414 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023224404250
2019-04-27 02:32:24,434 [salt.minion      :1432][INFO    ][11500] Starting a new job with PID 11500
2019-04-27 02:32:24,465 [salt.minion      :1711][INFO    ][11500] Returning information for job: 20190427023224404250
2019-04-27 02:32:47,400 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1166.77238202s left)
2019-04-27 02:32:54,501 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023254486156
2019-04-27 02:32:54,522 [salt.minion      :1432][INFO    ][11575] Starting a new job with PID 11575
2019-04-27 02:32:54,551 [salt.minion      :1711][INFO    ][11575] Returning information for job: 20190427023254486156
2019-04-27 02:33:20,216 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1133.95634818s left)
2019-04-27 02:33:24,596 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023324581124
2019-04-27 02:33:24,617 [salt.minion      :1432][INFO    ][11602] Starting a new job with PID 11602
2019-04-27 02:33:24,645 [salt.minion      :1711][INFO    ][11602] Returning information for job: 20190427023324581124
2019-04-27 02:33:52,852 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1101.32060218s left)
2019-04-27 02:33:54,705 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023354697597
2019-04-27 02:33:54,719 [salt.minion      :1432][INFO    ][11683] Starting a new job with PID 11683
2019-04-27 02:33:54,749 [salt.minion      :1711][INFO    ][11683] Returning information for job: 20190427023354697597
2019-04-27 02:34:24,821 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023424814010
2019-04-27 02:34:24,837 [salt.minion      :1432][INFO    ][11707] Starting a new job with PID 11707
2019-04-27 02:34:24,868 [salt.minion      :1711][INFO    ][11707] Returning information for job: 20190427023424814010
2019-04-27 02:34:25,582 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1068.59051418s left)
2019-04-27 02:34:54,995 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023454985526
2019-04-27 02:34:55,016 [salt.minion      :1432][INFO    ][11779] Starting a new job with PID 11779
2019-04-27 02:34:55,053 [salt.minion      :1711][INFO    ][11779] Returning information for job: 20190427023454985526
2019-04-27 02:34:58,143 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1036.0292902s left)
2019-04-27 02:35:25,145 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023525129987
2019-04-27 02:35:25,162 [salt.minion      :1432][INFO    ][11804] Starting a new job with PID 11804
2019-04-27 02:35:25,192 [salt.minion      :1711][INFO    ][11804] Returning information for job: 20190427023525129987
2019-04-27 02:35:30,801 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1003.37171316s left)
2019-04-27 02:35:55,342 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023555328630
2019-04-27 02:35:55,364 [salt.minion      :1432][INFO    ][11884] Starting a new job with PID 11884
2019-04-27 02:35:55,393 [salt.minion      :1711][INFO    ][11884] Returning information for job: 20190427023555328630
2019-04-27 02:36:03,487 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (970.685224056s left)
2019-04-27 02:36:25,521 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023625506041
2019-04-27 02:36:25,543 [salt.minion      :1432][INFO    ][11909] Starting a new job with PID 11909
2019-04-27 02:36:25,572 [salt.minion      :1711][INFO    ][11909] Returning information for job: 20190427023625506041
2019-04-27 02:36:36,218 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (937.955075026s left)
2019-04-27 02:36:55,745 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023655730033
2019-04-27 02:36:55,764 [salt.minion      :1432][INFO    ][12007] Starting a new job with PID 12007
2019-04-27 02:36:55,795 [salt.minion      :1711][INFO    ][12007] Returning information for job: 20190427023655730033
2019-04-27 02:37:08,878 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (905.294751167s left)
2019-04-27 02:37:25,949 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023725939475
2019-04-27 02:37:25,967 [salt.minion      :1432][INFO    ][12034] Starting a new job with PID 12034
2019-04-27 02:37:25,996 [salt.minion      :1711][INFO    ][12034] Returning information for job: 20190427023725939475
2019-04-27 02:37:41,652 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (872.520883083s left)
2019-04-27 02:37:55,986 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023755974835
2019-04-27 02:37:56,010 [salt.minion      :1432][INFO    ][12113] Starting a new job with PID 12113
2019-04-27 02:37:56,041 [salt.minion      :1711][INFO    ][12113] Returning information for job: 20190427023755974835
2019-04-27 02:38:14,270 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (839.902227163s left)
2019-04-27 02:38:26,001 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023825993124
2019-04-27 02:38:26,025 [salt.minion      :1432][INFO    ][12138] Starting a new job with PID 12138
2019-04-27 02:38:26,053 [salt.minion      :1711][INFO    ][12138] Returning information for job: 20190427023825993124
2019-04-27 02:38:46,916 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (807.256735086s left)
2019-04-27 02:38:56,077 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023856066187
2019-04-27 02:38:56,097 [salt.minion      :1432][INFO    ][12217] Starting a new job with PID 12217
2019-04-27 02:38:56,129 [salt.minion      :1711][INFO    ][12217] Returning information for job: 20190427023856066187
2019-04-27 02:39:19,677 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (774.495588064s left)
2019-04-27 02:39:26,129 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023926116096
2019-04-27 02:39:26,148 [salt.minion      :1432][INFO    ][12244] Starting a new job with PID 12244
2019-04-27 02:39:26,178 [salt.minion      :1711][INFO    ][12244] Returning information for job: 20190427023926116096
2019-04-27 02:39:52,427 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (741.74567914s left)
2019-04-27 02:39:56,237 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427023956222821
2019-04-27 02:39:56,251 [salt.minion      :1432][INFO    ][12323] Starting a new job with PID 12323
2019-04-27 02:39:56,282 [salt.minion      :1711][INFO    ][12323] Returning information for job: 20190427023956222821
2019-04-27 02:40:25,021 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (709.151962996s left)
2019-04-27 02:40:26,347 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024026338310
2019-04-27 02:40:26,359 [salt.minion      :1432][INFO    ][12350] Starting a new job with PID 12350
2019-04-27 02:40:26,388 [salt.minion      :1711][INFO    ][12350] Returning information for job: 20190427024026338310
2019-04-27 02:40:56,445 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024056435995
2019-04-27 02:40:56,461 [salt.minion      :1432][INFO    ][12425] Starting a new job with PID 12425
2019-04-27 02:40:56,493 [salt.minion      :1711][INFO    ][12425] Returning information for job: 20190427024056435995
2019-04-27 02:40:57,642 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (676.530318022s left)
2019-04-27 02:41:26,628 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024126613144
2019-04-27 02:41:26,648 [salt.minion      :1432][INFO    ][12447] Starting a new job with PID 12447
2019-04-27 02:41:26,680 [salt.minion      :1711][INFO    ][12447] Returning information for job: 20190427024126613144
2019-04-27 02:41:30,374 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (643.798991203s left)
2019-04-27 02:41:56,773 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024156760626
2019-04-27 02:41:56,793 [salt.minion      :1432][INFO    ][12525] Starting a new job with PID 12525
2019-04-27 02:41:56,822 [salt.minion      :1711][INFO    ][12525] Returning information for job: 20190427024156760626
2019-04-27 02:42:03,089 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (611.083662987s left)
2019-04-27 02:42:26,983 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024226973244
2019-04-27 02:42:27,000 [salt.minion      :1432][INFO    ][12550] Starting a new job with PID 12550
2019-04-27 02:42:27,033 [salt.minion      :1711][INFO    ][12550] Returning information for job: 20190427024226973244
2019-04-27 02:42:35,829 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (578.343121052s left)
2019-04-27 02:42:57,152 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024257136690
2019-04-27 02:42:57,174 [salt.minion      :1432][INFO    ][12630] Starting a new job with PID 12630
2019-04-27 02:42:57,204 [salt.minion      :1711][INFO    ][12630] Returning information for job: 20190427024257136690
2019-04-27 02:43:08,507 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (545.665728092s left)
2019-04-27 02:43:27,372 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024327364528
2019-04-27 02:43:27,395 [salt.minion      :1432][INFO    ][12657] Starting a new job with PID 12657
2019-04-27 02:43:27,426 [salt.minion      :1711][INFO    ][12657] Returning information for job: 20190427024327364528
2019-04-27 02:43:41,242 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (512.930384159s left)
2019-04-27 02:43:57,562 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024357550765
2019-04-27 02:43:57,583 [salt.minion      :1432][INFO    ][12735] Starting a new job with PID 12735
2019-04-27 02:43:57,611 [salt.minion      :1711][INFO    ][12735] Returning information for job: 20190427024357550765
2019-04-27 02:44:13,877 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (480.295475006s left)
2019-04-27 02:44:27,721 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024427709610
2019-04-27 02:44:27,749 [salt.minion      :1432][INFO    ][12762] Starting a new job with PID 12762
2019-04-27 02:44:27,776 [salt.minion      :1711][INFO    ][12762] Returning information for job: 20190427024427709610
2019-04-27 02:44:46,457 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (447.715837002s left)
2019-04-27 02:44:57,924 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024457911314
2019-04-27 02:44:57,944 [salt.minion      :1432][INFO    ][12839] Starting a new job with PID 12839
2019-04-27 02:44:57,974 [salt.minion      :1711][INFO    ][12839] Returning information for job: 20190427024457911314
2019-04-27 02:45:19,312 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (414.862248182s left)
2019-04-27 02:45:28,045 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024528033029
2019-04-27 02:45:28,067 [salt.minion      :1432][INFO    ][12864] Starting a new job with PID 12864
2019-04-27 02:45:28,098 [salt.minion      :1711][INFO    ][12864] Returning information for job: 20190427024528033029
2019-04-27 02:45:52,100 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (382.072720051s left)
2019-04-27 02:45:58,087 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024558075631
2019-04-27 02:45:58,109 [salt.minion      :1432][INFO    ][12942] Starting a new job with PID 12942
2019-04-27 02:45:58,139 [salt.minion      :1711][INFO    ][12942] Returning information for job: 20190427024558075631
2019-04-27 02:46:24,724 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (349.448188066s left)
2019-04-27 02:46:28,250 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024628243747
2019-04-27 02:46:28,267 [salt.minion      :1432][INFO    ][12967] Starting a new job with PID 12967
2019-04-27 02:46:28,295 [salt.minion      :1711][INFO    ][12967] Returning information for job: 20190427024628243747
2019-04-27 02:46:57,410 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (316.762449026s left)
2019-04-27 02:46:58,406 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024658397826
2019-04-27 02:46:58,422 [salt.minion      :1432][INFO    ][13196] Starting a new job with PID 13196
2019-04-27 02:46:58,447 [salt.minion      :1711][INFO    ][13196] Returning information for job: 20190427024658397826
2019-04-27 02:47:28,549 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024728538261
2019-04-27 02:47:28,568 [salt.minion      :1432][INFO    ][13229] Starting a new job with PID 13229
2019-04-27 02:47:28,596 [salt.minion      :1711][INFO    ][13229] Returning information for job: 20190427024728538261
2019-04-27 02:47:30,117 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (284.055132151s left)
2019-04-27 02:47:58,760 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024758749985
2019-04-27 02:47:58,777 [salt.minion      :1432][INFO    ][13295] Starting a new job with PID 13295
2019-04-27 02:47:58,806 [salt.minion      :1711][INFO    ][13295] Returning information for job: 20190427024758749985
2019-04-27 02:48:02,709 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (251.463657141s left)
2019-04-27 02:48:28,904 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024828895574
2019-04-27 02:48:28,922 [salt.minion      :1432][INFO    ][13331] Starting a new job with PID 13331
2019-04-27 02:48:28,953 [salt.minion      :1711][INFO    ][13331] Returning information for job: 20190427024828895574
2019-04-27 02:48:35,322 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (218.850687981s left)
2019-04-27 02:48:58,940 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024858930655
2019-04-27 02:48:58,963 [salt.minion      :1432][INFO    ][13402] Starting a new job with PID 13402
2019-04-27 02:48:58,990 [salt.minion      :1711][INFO    ][13402] Returning information for job: 20190427024858930655
2019-04-27 02:49:07,996 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (186.176424026s left)
2019-04-27 02:49:29,145 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024929133825
2019-04-27 02:49:29,168 [salt.minion      :1432][INFO    ][13436] Starting a new job with PID 13436
2019-04-27 02:49:29,198 [salt.minion      :1711][INFO    ][13436] Returning information for job: 20190427024929133825
2019-04-27 02:49:40,805 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (153.367268085s left)
2019-04-27 02:49:59,216 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427024959203130
2019-04-27 02:49:59,239 [salt.minion      :1432][INFO    ][13503] Starting a new job with PID 13503
2019-04-27 02:49:59,268 [salt.minion      :1711][INFO    ][13503] Returning information for job: 20190427024959203130
2019-04-27 02:50:13,457 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (120.715483189s left)
2019-04-27 02:50:29,445 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025029433431
2019-04-27 02:50:29,466 [salt.minion      :1432][INFO    ][13537] Starting a new job with PID 13537
2019-04-27 02:50:29,494 [salt.minion      :1711][INFO    ][13537] Returning information for job: 20190427025029433431
2019-04-27 02:50:46,192 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (87.9806270599s left)
2019-04-27 02:50:59,546 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025059532888
2019-04-27 02:50:59,567 [salt.minion      :1432][INFO    ][13605] Starting a new job with PID 13605
2019-04-27 02:50:59,594 [salt.minion      :1711][INFO    ][13605] Returning information for job: 20190427025059532888
2019-04-27 02:51:18,950 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (55.2227530479s left)
2019-04-27 02:51:29,624 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025129610041
2019-04-27 02:51:29,646 [salt.minion      :1432][INFO    ][13641] Starting a new job with PID 13641
2019-04-27 02:51:29,676 [salt.minion      :1711][INFO    ][13641] Returning information for job: 20190427025129610041
2019-04-27 02:51:51,618 [salt.loaded.ext.module.maas:1023][INFO    ][7337] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (22.5550091267s left)
2019-04-27 02:51:59,784 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025159769585
2019-04-27 02:51:59,807 [salt.minion      :1432][INFO    ][13711] Starting a new job with PID 13711
2019-04-27 02:51:59,837 [salt.minion      :1711][INFO    ][13711] Returning information for job: 20190427025159769585
2019-04-27 02:52:24,354 [salt.state       :302 ][ERROR   ][7337] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-27 02:52:24,355 [salt.state       :1951][INFO    ][7337] Completed state [maas.wait_for_machine_status] at time 02:52:24.355461 duration_in_ms=2260198.796
2019-04-27 02:52:24,361 [salt.minion      :1711][INFO    ][7337] Returning information for job: 20190427021435995980
2019-04-27 02:52:35,257 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command pillar.get with jid 20190427025235248465
2019-04-27 02:52:35,281 [salt.minion      :1432][INFO    ][13751] Starting a new job with PID 13751
2019-04-27 02:52:35,291 [salt.minion      :1711][INFO    ][13751] Returning information for job: 20190427025235248465
2019-04-27 02:52:35,968 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command service.status with jid 20190427025235956230
2019-04-27 02:52:35,986 [salt.minion      :1432][INFO    ][13756] Starting a new job with PID 13756
2019-04-27 02:52:36,726 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13756] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-27 02:52:36,761 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13756] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-27 02:52:36,777 [salt.minion      :1711][INFO    ][13756] Returning information for job: 20190427025235956230
2019-04-27 02:52:37,465 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command state.apply with jid 20190427025237454698
2019-04-27 02:52:37,484 [salt.minion      :1432][INFO    ][13767] Starting a new job with PID 13767
2019-04-27 02:52:43,605 [salt.state       :915 ][INFO    ][13767] Loading fresh modules for state activity
2019-04-27 02:52:44,178 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command 'salt-minion --version' in directory '/root'
2019-04-27 02:52:44,508 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command 'salt-minion --version' in directory '/root'
2019-04-27 02:52:45,502 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command 'salt-minion --version' in directory '/root'
2019-04-27 02:52:45,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command 'salt-minion --version' in directory '/root'
2019-04-27 02:52:47,956 [salt.state       :1780][INFO    ][13767] Running state [salt-minion] at time 02:52:47.956091
2019-04-27 02:52:47,956 [salt.state       :1813][INFO    ][13767] Executing state pkg.installed for [salt-minion]
2019-04-27 02:52:47,957 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-27 02:52:48,054 [salt.state       :300 ][INFO    ][13767] All specified packages are already installed
2019-04-27 02:52:48,055 [salt.state       :1951][INFO    ][13767] Completed state [salt-minion] at time 02:52:48.054869 duration_in_ms=98.779
2019-04-27 02:52:48,055 [salt.state       :1780][INFO    ][13767] Running state [salt_minion_dependency_packages] at time 02:52:48.055537
2019-04-27 02:52:48,055 [salt.state       :1813][INFO    ][13767] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-27 02:52:48,066 [salt.state       :300 ][INFO    ][13767] All specified packages are already installed
2019-04-27 02:52:48,066 [salt.state       :1951][INFO    ][13767] Completed state [salt_minion_dependency_packages] at time 02:52:48.066672 duration_in_ms=11.135
2019-04-27 02:52:48,070 [salt.state       :1780][INFO    ][13767] Running state [/etc/salt/minion.d/minion.conf] at time 02:52:48.070219
2019-04-27 02:52:48,070 [salt.state       :1813][INFO    ][13767] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-27 02:52:48,346 [salt.state       :300 ][INFO    ][13767] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-27 02:52:48,346 [salt.state       :1951][INFO    ][13767] Completed state [/etc/salt/minion.d/minion.conf] at time 02:52:48.346510 duration_in_ms=276.29
2019-04-27 02:52:48,347 [salt.state       :1780][INFO    ][13767] Running state [python-netaddr] at time 02:52:48.347260
2019-04-27 02:52:48,347 [salt.state       :1813][INFO    ][13767] Executing state pkg.installed for [python-netaddr]
2019-04-27 02:52:48,358 [salt.state       :300 ][INFO    ][13767] All specified packages are already installed
2019-04-27 02:52:48,358 [salt.state       :1951][INFO    ][13767] Completed state [python-netaddr] at time 02:52:48.358734 duration_in_ms=11.473
2019-04-27 02:52:48,362 [salt.state       :1780][INFO    ][13767] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:52:48.362259
2019-04-27 02:52:48,362 [salt.state       :1813][INFO    ][13767] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-27 02:52:48,374 [salt.state       :300 ][INFO    ][13767] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-27 02:52:48,374 [salt.state       :1951][INFO    ][13767] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:52:48.374278 duration_in_ms=12.018
2019-04-27 02:52:48,377 [salt.state       :1780][INFO    ][13767] Running state [salt-minion] at time 02:52:48.377765
2019-04-27 02:52:48,378 [salt.state       :1813][INFO    ][13767] Executing state service.running for [salt-minion]
2019-04-27 02:52:48,379 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-27 02:52:48,408 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-27 02:52:48,420 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-27 02:52:48,432 [salt.state       :300 ][INFO    ][13767] The service salt-minion is already running
2019-04-27 02:52:48,433 [salt.state       :1951][INFO    ][13767] Completed state [salt-minion] at time 02:52:48.432973 duration_in_ms=55.209
2019-04-27 02:52:48,434 [salt.state       :1780][INFO    ][13767] Running state [/etc/salt/grains.d] at time 02:52:48.434630
2019-04-27 02:52:48,436 [salt.state       :1813][INFO    ][13767] Executing state file.directory for [/etc/salt/grains.d]
2019-04-27 02:52:48,437 [salt.state       :300 ][INFO    ][13767] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-27 02:52:48,438 [salt.state       :1951][INFO    ][13767] Completed state [/etc/salt/grains.d] at time 02:52:48.437983 duration_in_ms=3.353
2019-04-27 02:52:48,438 [salt.state       :1780][INFO    ][13767] Running state [/etc/salt/grains] at time 02:52:48.438688
2019-04-27 02:52:48,439 [salt.state       :1813][INFO    ][13767] Executing state file.managed for [/etc/salt/grains]
2019-04-27 02:52:48,439 [salt.state       :300 ][INFO    ][13767] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-27 02:52:48,439 [salt.state       :1951][INFO    ][13767] Completed state [/etc/salt/grains] at time 02:52:48.439839 duration_in_ms=1.151
2019-04-27 02:52:48,440 [salt.state       :1780][INFO    ][13767] Running state [/etc/salt/grains.d/placeholder] at time 02:52:48.440351
2019-04-27 02:52:48,440 [salt.state       :1813][INFO    ][13767] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-27 02:52:48,441 [salt.state       :300 ][INFO    ][13767] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-27 02:52:48,441 [salt.state       :1951][INFO    ][13767] Completed state [/etc/salt/grains.d/placeholder] at time 02:52:48.441754 duration_in_ms=1.403
2019-04-27 02:52:48,442 [salt.state       :1780][INFO    ][13767] Running state [/etc/salt/grains.d/sphinx] at time 02:52:48.442260
2019-04-27 02:52:48,442 [salt.state       :1813][INFO    ][13767] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-27 02:52:48,443 [salt.state       :300 ][INFO    ][13767] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-27 02:52:48,444 [salt.state       :1951][INFO    ][13767] Completed state [/etc/salt/grains.d/sphinx] at time 02:52:48.444135 duration_in_ms=1.875
2019-04-27 02:52:48,446 [salt.state       :1780][INFO    ][13767] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:52:48.446239
2019-04-27 02:52:48,446 [salt.state       :1813][INFO    ][13767] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-27 02:52:48,449 [salt.state       :300 ][INFO    ][13767] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-27 02:52:48,449 [salt.state       :1951][INFO    ][13767] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:52:48.449320 duration_in_ms=3.081
2019-04-27 02:52:48,449 [salt.state       :1780][INFO    ][13767] Running state [/etc/salt/grains.d/dns_records] at time 02:52:48.449843
2019-04-27 02:52:48,450 [salt.state       :1813][INFO    ][13767] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-27 02:52:48,451 [salt.state       :300 ][INFO    ][13767] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-27 02:52:48,451 [salt.state       :1951][INFO    ][13767] Completed state [/etc/salt/grains.d/dns_records] at time 02:52:48.451443 duration_in_ms=1.6
2019-04-27 02:52:48,452 [salt.state       :1780][INFO    ][13767] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:52:48.452408
2019-04-27 02:52:48,452 [salt.state       :1813][INFO    ][13767] 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-27 02:52:48,453 [salt.state       :300 ][INFO    ][13767] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-27 02:52:48,453 [salt.state       :1951][INFO    ][13767] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:52:48.453244 duration_in_ms=0.836
2019-04-27 02:52:48,453 [salt.state       :1780][INFO    ][13767] Running state [/etc/salt/grains.d/salt] at time 02:52:48.453764
2019-04-27 02:52:48,454 [salt.state       :1813][INFO    ][13767] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-27 02:52:48,455 [salt.state       :300 ][INFO    ][13767] File /etc/salt/grains.d/salt is in the correct state
2019-04-27 02:52:48,455 [salt.state       :1951][INFO    ][13767] Completed state [/etc/salt/grains.d/salt] at time 02:52:48.455350 duration_in_ms=1.586
2019-04-27 02:52:48,456 [salt.state       :1780][INFO    ][13767] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:52:48.456272
2019-04-27 02:52:48,456 [salt.state       :1813][INFO    ][13767] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-27 02:52:48,456 [salt.state       :300 ][INFO    ][13767] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-27 02:52:48,457 [salt.state       :1951][INFO    ][13767] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:52:48.457123 duration_in_ms=0.85
2019-04-27 02:52:48,460 [salt.state       :1780][INFO    ][13767] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:52:48.460711
2019-04-27 02:52:48,461 [salt.state       :1813][INFO    ][13767] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-27 02:52:48,461 [salt.state       :300 ][INFO    ][13767] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-27 02:52:48,461 [salt.state       :1951][INFO    ][13767] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:52:48.461576 duration_in_ms=0.865
2019-04-27 02:52:48,462 [salt.state       :1780][INFO    ][13767] Running state [mine.update] at time 02:52:48.462290
2019-04-27 02:52:48,462 [salt.state       :1813][INFO    ][13767] Executing state module.wait for [mine.update]
2019-04-27 02:52:48,463 [salt.state       :300 ][INFO    ][13767] No changes made for mine.update
2019-04-27 02:52:48,463 [salt.state       :1951][INFO    ][13767] Completed state [mine.update] at time 02:52:48.463288 duration_in_ms=0.999
2019-04-27 02:52:48,463 [salt.state       :1780][INFO    ][13767] Running state [ca-certificates] at time 02:52:48.463567
2019-04-27 02:52:48,463 [salt.state       :1813][INFO    ][13767] Executing state pkg.installed for [ca-certificates]
2019-04-27 02:52:48,474 [salt.state       :300 ][INFO    ][13767] All specified packages are already installed
2019-04-27 02:52:48,474 [salt.state       :1951][INFO    ][13767] Completed state [ca-certificates] at time 02:52:48.474916 duration_in_ms=11.348
2019-04-27 02:52:48,475 [salt.state       :1780][INFO    ][13767] Running state [update-ca-certificates] at time 02:52:48.475678
2019-04-27 02:52:48,476 [salt.state       :1813][INFO    ][13767] Executing state cmd.wait for [update-ca-certificates]
2019-04-27 02:52:48,476 [salt.state       :300 ][INFO    ][13767] No changes made for update-ca-certificates
2019-04-27 02:52:48,476 [salt.state       :1951][INFO    ][13767] Completed state [update-ca-certificates] at time 02:52:48.476519 duration_in_ms=0.841
2019-04-27 02:52:48,476 [salt.state       :1780][INFO    ][13767] Running state [iptables] at time 02:52:48.476796
2019-04-27 02:52:48,477 [salt.state       :1813][INFO    ][13767] Executing state pkg.installed for [iptables]
2019-04-27 02:52:48,487 [salt.state       :300 ][INFO    ][13767] All specified packages are already installed
2019-04-27 02:52:48,487 [salt.state       :1951][INFO    ][13767] Completed state [iptables] at time 02:52:48.487719 duration_in_ms=10.923
2019-04-27 02:52:48,488 [salt.state       :1780][INFO    ][13767] Running state [iptables-persistent] at time 02:52:48.488010
2019-04-27 02:52:48,488 [salt.state       :1813][INFO    ][13767] Executing state pkg.installed for [iptables-persistent]
2019-04-27 02:52:48,499 [salt.state       :300 ][INFO    ][13767] All specified packages are already installed
2019-04-27 02:52:48,499 [salt.state       :1951][INFO    ][13767] Completed state [iptables-persistent] at time 02:52:48.499550 duration_in_ms=11.539
2019-04-27 02:52:48,500 [salt.state       :1780][INFO    ][13767] Running state [iptables_modules_v4_load] at time 02:52:48.500729
2019-04-27 02:52:48,501 [salt.state       :1813][INFO    ][13767] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-27 02:52:48,501 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command 'lsmod' in directory '/root'
2019-04-27 02:52:48,521 [salt.state       :300 ][INFO    ][13767] Kernel modules iptable_filter, ip_tables are already present
2019-04-27 02:52:48,521 [salt.state       :1951][INFO    ][13767] Completed state [iptables_modules_v4_load] at time 02:52:48.521473 duration_in_ms=20.744
2019-04-27 02:52:48,522 [salt.state       :1780][INFO    ][13767] Running state [/etc/iptables/rules.v4] at time 02:52:48.522240
2019-04-27 02:52:48,522 [salt.state       :1813][INFO    ][13767] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-27 02:52:48,618 [salt.state       :300 ][INFO    ][13767] File /etc/iptables/rules.v4 is in the correct state
2019-04-27 02:52:48,619 [salt.state       :1951][INFO    ][13767] Completed state [/etc/iptables/rules.v4] at time 02:52:48.619332 duration_in_ms=97.091
2019-04-27 02:52:48,621 [salt.state       :1780][INFO    ][13767] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:52:48.621443
2019-04-27 02:52:48,621 [salt.state       :1813][INFO    ][13767] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-27 02:52:48,623 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-27 02:52:48,644 [salt.state       :300 ][INFO    ][13767] onlyif execution failed
2019-04-27 02:52:48,644 [salt.state       :1951][INFO    ][13767] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:52:48.644476 duration_in_ms=23.033
2019-04-27 02:52:48,645 [salt.state       :1780][INFO    ][13767] Running state [netfilter-persistent] at time 02:52:48.645652
2019-04-27 02:52:48,646 [salt.state       :1813][INFO    ][13767] Executing state service.running for [netfilter-persistent]
2019-04-27 02:52:48,647 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-27 02:52:48,670 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-27 02:52:48,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-27 02:52:48,708 [salt.state       :300 ][INFO    ][13767] The service netfilter-persistent is already running
2019-04-27 02:52:48,708 [salt.state       :1951][INFO    ][13767] Completed state [netfilter-persistent] at time 02:52:48.708891 duration_in_ms=63.239
2019-04-27 02:52:48,709 [salt.state       :1780][INFO    ][13767] Running state [iptables_extra.remove_stale_tables] at time 02:52:48.709877
2019-04-27 02:52:48,710 [salt.state       :1813][INFO    ][13767] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-27 02:52:48,710 [salt.state       :300 ][INFO    ][13767] No changes made for iptables_extra.remove_stale_tables
2019-04-27 02:52:48,713 [salt.state       :1951][INFO    ][13767] Completed state [iptables_extra.remove_stale_tables] at time 02:52:48.711108 duration_in_ms=0.962
2019-04-27 02:52:48,714 [salt.state       :1780][INFO    ][13767] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:52:48.714168
2019-04-27 02:52:48,714 [salt.state       :1813][INFO    ][13767] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-27 02:52:48,715 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13767] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-27 02:52:48,730 [salt.state       :300 ][INFO    ][13767] onlyif execution failed
2019-04-27 02:52:48,730 [salt.state       :1951][INFO    ][13767] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:52:48.730618 duration_in_ms=16.45
2019-04-27 02:52:48,732 [salt.state       :1780][INFO    ][13767] Running state [/etc/iptables/rules.v6] at time 02:52:48.732288
2019-04-27 02:52:48,732 [salt.state       :1813][INFO    ][13767] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-27 02:52:48,733 [salt.state       :300 ][INFO    ][13767] File /etc/iptables/rules.v6 is not present
2019-04-27 02:52:48,733 [salt.state       :1951][INFO    ][13767] Completed state [/etc/iptables/rules.v6] at time 02:52:48.733370 duration_in_ms=1.082
2019-04-27 02:52:48,734 [salt.state       :1780][INFO    ][13767] Running state [iptables_extra.flush_all] at time 02:52:48.734092
2019-04-27 02:52:48,734 [salt.state       :1813][INFO    ][13767] Executing state module.wait for [iptables_extra.flush_all]
2019-04-27 02:52:48,734 [salt.state       :300 ][INFO    ][13767] No changes made for iptables_extra.flush_all
2019-04-27 02:52:48,738 [salt.state       :1951][INFO    ][13767] Completed state [iptables_extra.flush_all] at time 02:52:48.737944 duration_in_ms=3.852
2019-04-27 02:52:48,741 [salt.minion      :1711][INFO    ][13767] Returning information for job: 20190427025237454698
2019-04-27 02:52:49,421 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command state.apply with jid 20190427025249409256
2019-04-27 02:52:49,437 [salt.minion      :1432][INFO    ][13895] Starting a new job with PID 13895
2019-04-27 02:52:50,356 [salt.state       :915 ][INFO    ][13895] Loading fresh modules for state activity
2019-04-27 02:52:51,587 [salt.state       :1780][INFO    ][13895] Running state [maas-rack-controller] at time 02:52:51.587192
2019-04-27 02:52:51,587 [salt.state       :1813][INFO    ][13895] Executing state pkg.installed for [maas-rack-controller]
2019-04-27 02:52:51,588 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13895] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-27 02:52:51,701 [salt.state       :300 ][INFO    ][13895] All specified packages are already installed
2019-04-27 02:52:51,701 [salt.state       :1951][INFO    ][13895] Completed state [maas-rack-controller] at time 02:52:51.701342 duration_in_ms=114.15
2019-04-27 02:52:51,701 [salt.state       :1780][INFO    ][13895] Running state [ipmitool] at time 02:52:51.701706
2019-04-27 02:52:51,702 [salt.state       :1813][INFO    ][13895] Executing state pkg.installed for [ipmitool]
2019-04-27 02:52:51,710 [salt.state       :300 ][INFO    ][13895] All specified packages are already installed
2019-04-27 02:52:51,712 [salt.state       :1951][INFO    ][13895] Completed state [ipmitool] at time 02:52:51.712097 duration_in_ms=10.391
2019-04-27 02:52:51,715 [salt.state       :1780][INFO    ][13895] Running state [/etc/maas/rackd.conf] at time 02:52:51.714959
2019-04-27 02:52:51,715 [salt.state       :1813][INFO    ][13895] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-27 02:52:51,716 [salt.state       :300 ][INFO    ][13895] No changes needed to be made
2019-04-27 02:52:51,716 [salt.state       :1951][INFO    ][13895] Completed state [/etc/maas/rackd.conf] at time 02:52:51.716429 duration_in_ms=1.47
2019-04-27 02:52:51,716 [salt.state       :1780][INFO    ][13895] Running state [/etc/maas/rackd.conf] at time 02:52:51.716716
2019-04-27 02:52:51,717 [salt.state       :1813][INFO    ][13895] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-27 02:52:51,717 [salt.loaded.int.states.file:2298][WARNING ][13895] 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-27 02:52:51,718 [salt.state       :300 ][INFO    ][13895] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-27 02:52:51,718 [salt.state       :1951][INFO    ][13895] Completed state [/etc/maas/rackd.conf] at time 02:52:51.718342 duration_in_ms=1.627
2019-04-27 02:52:51,719 [salt.state       :1780][INFO    ][13895] Running state [maas-rackd] at time 02:52:51.719586
2019-04-27 02:52:51,719 [salt.state       :1813][INFO    ][13895] Executing state service.running for [maas-rackd]
2019-04-27 02:52:51,720 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13895] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-27 02:52:51,754 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13895] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-27 02:52:51,769 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13895] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-27 02:52:51,791 [salt.state       :300 ][INFO    ][13895] The service maas-rackd is already running
2019-04-27 02:52:51,792 [salt.state       :1951][INFO    ][13895] Completed state [maas-rackd] at time 02:52:51.792251 duration_in_ms=72.665
2019-04-27 02:52:51,794 [salt.minion      :1711][INFO    ][13895] Returning information for job: 20190427025249409256
2019-04-27 02:52:52,474 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command state.apply with jid 20190427025252464757
2019-04-27 02:52:52,493 [salt.minion      :1432][INFO    ][13918] Starting a new job with PID 13918
2019-04-27 02:52:53,478 [salt.state       :915 ][INFO    ][13918] Loading fresh modules for state activity
2019-04-27 02:52:54,806 [salt.state       :1780][INFO    ][13918] Running state [maas-region-controller] at time 02:52:54.806350
2019-04-27 02:52:54,806 [salt.state       :1813][INFO    ][13918] Executing state pkg.installed for [maas-region-controller]
2019-04-27 02:52:54,808 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-27 02:52:54,920 [salt.state       :300 ][INFO    ][13918] All specified packages are already installed
2019-04-27 02:52:54,920 [salt.state       :1951][INFO    ][13918] Completed state [maas-region-controller] at time 02:52:54.920639 duration_in_ms=114.289
2019-04-27 02:52:54,921 [salt.state       :1780][INFO    ][13918] Running state [python-oauth] at time 02:52:54.920996
2019-04-27 02:52:54,921 [salt.state       :1813][INFO    ][13918] Executing state pkg.installed for [python-oauth]
2019-04-27 02:52:54,930 [salt.state       :300 ][INFO    ][13918] All specified packages are already installed
2019-04-27 02:52:54,931 [salt.state       :1951][INFO    ][13918] Completed state [python-oauth] at time 02:52:54.931069 duration_in_ms=10.073
2019-04-27 02:52:54,934 [salt.state       :1780][INFO    ][13918] Running state [/etc/maas/regiond.conf] at time 02:52:54.934212
2019-04-27 02:52:54,934 [salt.state       :1813][INFO    ][13918] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-27 02:52:54,939 [salt.state       :300 ][INFO    ][13918] No changes needed to be made
2019-04-27 02:52:54,939 [salt.state       :1951][INFO    ][13918] Completed state [/etc/maas/regiond.conf] at time 02:52:54.939811 duration_in_ms=5.599
2019-04-27 02:52:54,940 [salt.state       :1780][INFO    ][13918] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:52:54.940329
2019-04-27 02:52:54,940 [salt.state       :1813][INFO    ][13918] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-27 02:52:55,001 [salt.state       :300 ][INFO    ][13918] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-27 02:52:55,001 [salt.state       :1951][INFO    ][13918] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:52:55.001741 duration_in_ms=61.412
2019-04-27 02:52:55,002 [salt.state       :1780][INFO    ][13918] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:55.002337
2019-04-27 02:52:55,002 [salt.state       :1813][INFO    ][13918] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-27 02:52:55,012 [salt.state       :300 ][INFO    ][13918] No changes needed to be made
2019-04-27 02:52:55,012 [salt.state       :1951][INFO    ][13918] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:55.012720 duration_in_ms=10.384
2019-04-27 02:52:55,013 [salt.state       :1780][INFO    ][13918] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:55.013254
2019-04-27 02:52:55,013 [salt.state       :1813][INFO    ][13918] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-27 02:52:55,018 [salt.state       :300 ][INFO    ][13918] No changes needed to be made
2019-04-27 02:52:55,018 [salt.state       :1951][INFO    ][13918] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:55.018235 duration_in_ms=4.98
2019-04-27 02:52:55,018 [salt.state       :1780][INFO    ][13918] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:52:55.018751
2019-04-27 02:52:55,019 [salt.state       :1813][INFO    ][13918] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-27 02:52:55,043 [salt.state       :300 ][INFO    ][13918] No changes needed to be made
2019-04-27 02:52:55,043 [salt.state       :1951][INFO    ][13918] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:52:55.043869 duration_in_ms=25.118
2019-04-27 02:52:55,044 [salt.state       :1780][INFO    ][13918] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:52:55.044389
2019-04-27 02:52:55,044 [salt.state       :1813][INFO    ][13918] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-27 02:52:55,056 [salt.state       :300 ][INFO    ][13918] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-27 02:52:55,056 [salt.state       :1951][INFO    ][13918] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:52:55.056573 duration_in_ms=12.184
2019-04-27 02:52:55,057 [salt.state       :1780][INFO    ][13918] Running state [a2enmod headers] at time 02:52:55.057772
2019-04-27 02:52:55,058 [salt.state       :1813][INFO    ][13918] Executing state cmd.run for [a2enmod headers]
2019-04-27 02:52:55,058 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13918] Executing command 'a2enmod headers' in directory '/root'
2019-04-27 02:52:55,126 [salt.state       :300 ][INFO    ][13918] {'pid': 13937, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-27 02:52:55,127 [salt.state       :1951][INFO    ][13918] Completed state [a2enmod headers] at time 02:52:55.127642 duration_in_ms=69.869
2019-04-27 02:52:55,128 [salt.state       :1780][INFO    ][13918] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:52:55.128021
2019-04-27 02:52:55,128 [salt.state       :1813][INFO    ][13918] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-27 02:52:55,149 [salt.state       :300 ][INFO    ][13918] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-27 02:52:55,150 [salt.state       :1951][INFO    ][13918] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:52:55.150058 duration_in_ms=22.036
2019-04-27 02:52:55,151 [salt.state       :1780][INFO    ][13918] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:52:55.151029
2019-04-27 02:52:55,151 [salt.state       :1813][INFO    ][13918] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-27 02:52:55,208 [salt.state       :300 ][INFO    ][13918] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-27 02:52:55,208 [salt.state       :1951][INFO    ][13918] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:52:55.208680 duration_in_ms=57.649
2019-04-27 02:52:55,209 [salt.state       :1780][INFO    ][13918] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:52:55.209753
2019-04-27 02:52:55,210 [salt.state       :1813][INFO    ][13918] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-27 02:52:55,260 [salt.state       :300 ][INFO    ][13918] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-27 02:52:55,261 [salt.state       :1951][INFO    ][13918] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:52:55.261192 duration_in_ms=51.44
2019-04-27 02:52:55,261 [salt.state       :1780][INFO    ][13918] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:52:55.261740
2019-04-27 02:52:55,262 [salt.state       :1813][INFO    ][13918] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-27 02:52:55,322 [salt.state       :300 ][INFO    ][13918] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-27 02:52:55,322 [salt.state       :1951][INFO    ][13918] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:52:55.322396 duration_in_ms=60.656
2019-04-27 02:52:55,322 [salt.state       :1780][INFO    ][13918] Running state [/root/.pgpass] at time 02:52:55.322693
2019-04-27 02:52:55,323 [salt.state       :1813][INFO    ][13918] Executing state file.managed for [/root/.pgpass]
2019-04-27 02:52:55,372 [salt.state       :300 ][INFO    ][13918] File /root/.pgpass is in the correct state
2019-04-27 02:52:55,372 [salt.state       :1951][INFO    ][13918] Completed state [/root/.pgpass] at time 02:52:55.372780 duration_in_ms=50.086
2019-04-27 02:52:55,381 [salt.state       :1780][INFO    ][13918] Running state [maas-region syncdb --noinput] at time 02:52:55.381858
2019-04-27 02:52:55,382 [salt.state       :1813][INFO    ][13918] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-27 02:52:55,384 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13918] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-27 02:52:58,072 [salt.state       :300 ][INFO    ][13918] {'pid': 13950, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, contenttypes, auth, sites, piston3, maasserver, 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-27 02:52:58,073 [salt.state       :1951][INFO    ][13918] Completed state [maas-region syncdb --noinput] at time 02:52:58.073228 duration_in_ms=2691.369
2019-04-27 02:52:58,073 [salt.state       :2022][WARNING ][13918] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-27 02:52:58,077 [salt.state       :1780][INFO    ][13918] Running state [maas-regiond] at time 02:52:58.077193
2019-04-27 02:52:58,077 [salt.state       :1813][INFO    ][13918] Executing state service.running for [maas-regiond]
2019-04-27 02:52:58,079 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-27 02:52:58,120 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-27 02:52:58,135 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-27 02:52:58,152 [salt.state       :300 ][INFO    ][13918] The service maas-regiond is already running
2019-04-27 02:52:58,153 [salt.state       :1951][INFO    ][13918] Completed state [maas-regiond] at time 02:52:58.152978 duration_in_ms=75.786
2019-04-27 02:52:58,157 [salt.state       :1780][INFO    ][13918] Running state [bind9] at time 02:52:58.157556
2019-04-27 02:52:58,158 [salt.state       :1813][INFO    ][13918] Executing state service.running for [bind9]
2019-04-27 02:52:58,159 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-27 02:52:58,178 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-27 02:52:58,198 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-27 02:52:58,218 [salt.state       :300 ][INFO    ][13918] The service bind9 is already running
2019-04-27 02:52:58,219 [salt.state       :1951][INFO    ][13918] Completed state [bind9] at time 02:52:58.219318 duration_in_ms=61.761
2019-04-27 02:52:58,221 [salt.state       :1780][INFO    ][13918] Running state [apache2] at time 02:52:58.221323
2019-04-27 02:52:58,221 [salt.state       :1813][INFO    ][13918] Executing state service.running for [apache2]
2019-04-27 02:52:58,222 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-27 02:52:58,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-27 02:52:58,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-27 02:52:58,268 [salt.state       :300 ][INFO    ][13918] The service apache2 is already running
2019-04-27 02:52:58,268 [salt.state       :1951][INFO    ][13918] Completed state [apache2] at time 02:52:58.268841 duration_in_ms=47.517
2019-04-27 02:52:58,270 [salt.state       :1780][INFO    ][13918] Running state [maasng.wait_for_http_code] at time 02:52:58.269953
2019-04-27 02:52:58,270 [salt.state       :1813][INFO    ][13918] Executing state module.run for [maasng.wait_for_http_code]
2019-04-27 02:52:58,270 [salt.utils.decorators:613 ][WARNING ][13918] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:52:58,417 [salt.state       :300 ][INFO    ][13918] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-27 02:52:58,418 [salt.state       :1951][INFO    ][13918] Completed state [maasng.wait_for_http_code] at time 02:52:58.417982 duration_in_ms=148.027
2019-04-27 02:52:58,421 [salt.state       :1780][INFO    ][13918] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:52:58.421020
2019-04-27 02:52:58,421 [salt.state       :1813][INFO    ][13918] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-27 02:52:58,422 [salt.state       :300 ][INFO    ][13918] /var/lib/maas/.setup_admin exists
2019-04-27 02:52:58,422 [salt.state       :1951][INFO    ][13918] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:52:58.422706 duration_in_ms=1.686
2019-04-27 02:52:58,423 [salt.state       :1780][INFO    ][13918] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:58.423711
2019-04-27 02:52:58,424 [salt.state       :1813][INFO    ][13918] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-27 02:52:58,425 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13918] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-27 02:53:00,302 [salt.state       :300 ][INFO    ][13918] {'pid': 13975, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-27 02:53:00,304 [salt.state       :1951][INFO    ][13918] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:00.304738 duration_in_ms=1881.025
2019-04-27 02:53:00,314 [salt.state       :1780][INFO    ][13918] Running state [maas_region_boot_source_resources_mirror] at time 02:53:00.313918
2019-04-27 02:53:00,314 [salt.state       :1813][INFO    ][13918] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-27 02:53:00,400 [salt.state       :300 ][INFO    ][13918] {'changes': {}}
2019-04-27 02:53:00,401 [salt.state       :1951][INFO    ][13918] Completed state [maas_region_boot_source_resources_mirror] at time 02:53:00.401059 duration_in_ms=87.141
2019-04-27 02:53:00,402 [salt.state       :1780][INFO    ][13918] Running state [maasng.boot_resources_import] at time 02:53:00.402007
2019-04-27 02:53:00,402 [salt.state       :1813][INFO    ][13918] Executing state module.run for [maasng.boot_resources_import]
2019-04-27 02:53:00,403 [salt.utils.decorators:613 ][WARNING ][13918] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:53:00,487 [salt.loaded.ext.module.maasng:1600][INFO    ][13918] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-27 02:53:05,539 [salt.loaded.ext.module.maasng:1600][INFO    ][13918] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-27 02:53:07,532 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025307517957
2019-04-27 02:53:07,555 [salt.minion      :1432][INFO    ][14010] Starting a new job with PID 14010
2019-04-27 02:53:07,585 [salt.minion      :1711][INFO    ][14010] Returning information for job: 20190427025307517957
2019-04-27 02:53:10,629 [salt.state       :300 ][INFO    ][13918] {'ret': True}
2019-04-27 02:53:10,629 [salt.state       :1951][INFO    ][13918] Completed state [maasng.boot_resources_import] at time 02:53:10.629582 duration_in_ms=10227.574
2019-04-27 02:53:10,631 [salt.state       :1780][INFO    ][13918] Running state [maas_region_boot_sources_selection_xenial] at time 02:53:10.630747
2019-04-27 02:53:10,631 [salt.state       :1813][INFO    ][13918] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-27 02:53:10,787 [salt.state       :300 ][INFO    ][13918] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-27 02:53:10,787 [salt.state       :1951][INFO    ][13918] Completed state [maas_region_boot_sources_selection_xenial] at time 02:53:10.787882 duration_in_ms=157.134
2019-04-27 02:53:10,789 [salt.state       :1780][INFO    ][13918] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:10.789192
2019-04-27 02:53:10,789 [salt.state       :1813][INFO    ][13918] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-27 02:53:10,790 [salt.utils.decorators:613 ][WARNING ][13918] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:53:10,790 [salt.loaded.ext.module.maasng:1771][INFO    ][13918] boot-sources sync initiated for ALL Rack's
2019-04-27 02:53:11,740 [salt.state       :300 ][INFO    ][13918] {'ret': True}
2019-04-27 02:53:11,741 [salt.state       :1951][INFO    ][13918] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:11.741196 duration_in_ms=952.003
2019-04-27 02:53:11,744 [salt.state       :1780][INFO    ][13918] Running state [maas.process_maas_config] at time 02:53:11.744163
2019-04-27 02:53:11,744 [salt.state       :1813][INFO    ][13918] Executing state module.run for [maas.process_maas_config]
2019-04-27 02:53:11,745 [salt.utils.decorators:613 ][WARNING ][13918] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:53:11,746 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=enable_http_proxy value=True
2019-04-27 02:53:11,792 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=upstream_dns value=8.8.8.8
2019-04-27 02:53:11,843 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=commissioning_distro_series value=xenial
2019-04-27 02:53:11,900 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=default_osystem value=ubuntu
2019-04-27 02:53:13,285 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=active_discovery_interval value=600
2019-04-27 02:53:13,333 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=dnssec_validation value=no
2019-04-27 02:53:13,379 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=maas_name value=mas01
2019-04-27 02:53:13,425 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=network_discovery value=enabled
2019-04-27 02:53:13,513 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=enable_third_party_drivers value=True
2019-04-27 02:53:13,568 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=default_storage_layout value=lvm
2019-04-27 02:53:13,614 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=ntp_external_only value=True
2019-04-27 02:53:13,653 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-27 02:53:13,695 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=default_distro_series value=xenial
2019-04-27 02:53:13,763 [salt.loaded.ext.module.maas:92  ][INFO    ][13918] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-04-27 02:53:13,870 [salt.state       :300 ][INFO    ][13918] {'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-27 02:53:13,870 [salt.state       :1951][INFO    ][13918] Completed state [maas.process_maas_config] at time 02:53:13.870643 duration_in_ms=2126.481
2019-04-27 02:53:13,871 [salt.state       :1780][INFO    ][13918] Running state [pxe_admin] at time 02:53:13.871423
2019-04-27 02:53:13,871 [salt.state       :1813][INFO    ][13918] Executing state maasng.fabric_present for [pxe_admin]
2019-04-27 02:53:13,922 [salt.loaded.ext.module.maasng:945 ][INFO    ][13918] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'dx86rx', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-27 02:53:13,988 [salt.loaded.ext.module.maasng:1008][WARNING ][13918] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-27 02:53:13,989 [salt.loaded.ext.module.maasng:1011][WARNING ][13918] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-27 02:53:14,053 [salt.state       :300 ][INFO    ][13918] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-27 02:53:14,053 [salt.state       :1951][INFO    ][13918] Completed state [pxe_admin] at time 02:53:14.053878 duration_in_ms=182.454
2019-04-27 02:53:14,054 [salt.state       :1780][INFO    ][13918] Running state [vlan 0] at time 02:53:14.054302
2019-04-27 02:53:14,054 [salt.state       :1813][INFO    ][13918] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-27 02:53:14,128 [salt.loaded.ext.module.maasng:945 ][INFO    ][13918] [{u'class_type': None, 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'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'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'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'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'fabric': u'fabric-2'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'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'dx86rx', 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'fabric': u'pxe_admin'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-27 02:53:14,221 [salt.loaded.ext.module.maasng:945 ][INFO    ][13918] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dx86rx', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-04-27 02:53:14,446 [salt.loaded.ext.module.maasng:945 ][INFO    ][13918] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dx86rx', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-04-27 02:53:14,520 [salt.state       :300 ][INFO    ][13918] {'new': 'Vlan untagged was updated'}
2019-04-27 02:53:14,520 [salt.state       :1951][INFO    ][13918] Completed state [vlan 0] at time 02:53:14.520584 duration_in_ms=466.281
2019-04-27 02:53:14,521 [salt.state       :1780][INFO    ][13918] Running state [192.168.11.0/24] at time 02:53:14.521816
2019-04-27 02:53:14,522 [salt.state       :1813][INFO    ][13918] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-27 02:53:14,686 [salt.loaded.ext.module.maasng:945 ][INFO    ][13918] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'dx86rx', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-27 02:53:14,687 [salt.loaded.ext.module.maasng:1235][WARNING ][13918] Ignoring parameter vlan:0
2019-04-27 02:53:14,756 [salt.state       :300 ][INFO    ][13918] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-27 02:53:14,756 [salt.state       :1951][INFO    ][13918] Completed state [192.168.11.0/24] at time 02:53:14.756726 duration_in_ms=234.909
2019-04-27 02:53:14,757 [salt.state       :1780][INFO    ][13918] Running state [maas_create_iprange_1] at time 02:53:14.757795
2019-04-27 02:53:14,758 [salt.state       :1813][INFO    ][13918] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-27 02:53:14,806 [salt.state       :300 ][INFO    ][13918] Iprange maas_create_iprange_1 already exist.
2019-04-27 02:53:14,807 [salt.state       :1951][INFO    ][13918] Completed state [maas_create_iprange_1] at time 02:53:14.807502 duration_in_ms=49.706
2019-04-27 02:53:14,807 [salt.state       :1780][INFO    ][13918] Running state [vlan 0] at time 02:53:14.807862
2019-04-27 02:53:14,808 [salt.state       :1813][INFO    ][13918] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-27 02:53:14,854 [salt.loaded.ext.module.maasng:945 ][INFO    ][13918] [{u'class_type': None, 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'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'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'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'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'fabric': u'fabric-2'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'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'dx86rx', 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'fabric': u'pxe_admin'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-27 02:53:14,948 [salt.loaded.ext.module.maasng:945 ][INFO    ][13918] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'dx86rx', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-27 02:53:15,150 [salt.loaded.ext.module.maasng:945 ][INFO    ][13918] [{u'class_type': None, 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'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'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'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'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'fabric': u'fabric-2'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'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'dx86rx', 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'fabric': u'pxe_admin'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-27 02:53:15,225 [salt.state       :300 ][INFO    ][13918] {'new': 'Vlan untagged was updated'}
2019-04-27 02:53:15,225 [salt.state       :1951][INFO    ][13918] Completed state [vlan 0] at time 02:53:15.225595 duration_in_ms=417.733
2019-04-27 02:53:15,226 [salt.state       :1780][INFO    ][13918] Running state [opnfv] at time 02:53:15.226277
2019-04-27 02:53:15,226 [salt.state       :1813][INFO    ][13918] Executing state maasng.sshkey_present for [opnfv]
2019-04-27 02:53:15,270 [salt.loaded.ext.module.maasng:1903][INFO    ][13918] [{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-27 02:53:15,271 [salt.state       :300 ][INFO    ][13918] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-27 02:53:15,272 [salt.state       :1951][INFO    ][13918] Completed state [opnfv] at time 02:53:15.271986 duration_in_ms=45.708
2019-04-27 02:53:15,275 [salt.minion      :1711][INFO    ][13918] Returning information for job: 20190427025252464757
2019-04-27 02:53:16,030 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command state.apply with jid 20190427025316020718
2019-04-27 02:53:16,045 [salt.minion      :1432][INFO    ][14369] Starting a new job with PID 14369
2019-04-27 02:53:22,076 [salt.state       :915 ][INFO    ][14369] Loading fresh modules for state activity
2019-04-27 02:53:22,178 [salt.state       :1780][INFO    ][14369] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:22.178253
2019-04-27 02:53:22,178 [salt.state       :1813][INFO    ][14369] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-27 02:53:22,181 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14369] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-27 02:53:24,035 [salt.state       :300 ][INFO    ][14369] {'pid': 14393, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-27 02:53:24,036 [salt.state       :1951][INFO    ][14369] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:24.036227 duration_in_ms=1857.973
2019-04-27 02:53:24,040 [salt.state       :1780][INFO    ][14369] Running state [maas.process_machines] at time 02:53:24.040241
2019-04-27 02:53:24,040 [salt.state       :1813][INFO    ][14369] Executing state module.run for [maas.process_machines]
2019-04-27 02:53:24,041 [salt.utils.decorators:613 ][WARNING ][14369] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:53:24,604 [salt.loaded.ext.module.maas:412 ][WARNING ][14369] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-27 02:53:24,605 [salt.loaded.ext.module.maas:92  ][INFO    ][14369] 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=syemn3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-27 02:53:25,685 [salt.loaded.ext.module.maas:412 ][WARNING ][14369] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-27 02:53:25,685 [salt.loaded.ext.module.maas:92  ][INFO    ][14369] 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=47rbky architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-27 02:53:26,786 [salt.loaded.ext.module.maas:412 ][WARNING ][14369] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-27 02:53:26,787 [salt.loaded.ext.module.maas:92  ][INFO    ][14369] 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=7ekstm architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-27 02:53:27,877 [salt.loaded.ext.module.maas:412 ][WARNING ][14369] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-27 02:53:27,878 [salt.loaded.ext.module.maas:92  ][INFO    ][14369] 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=mbr84t architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-27 02:53:29,012 [salt.loaded.ext.module.maas:412 ][WARNING ][14369] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-27 02:53:29,012 [salt.loaded.ext.module.maas:92  ][INFO    ][14369] 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=demp34 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-27 02:53:30,119 [salt.state       :300 ][INFO    ][14369] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-27 02:53:30,120 [salt.state       :1951][INFO    ][14369] Completed state [maas.process_machines] at time 02:53:30.120286 duration_in_ms=6080.043
2019-04-27 02:53:30,126 [salt.minion      :1711][INFO    ][14369] Returning information for job: 20190427025316020718
2019-04-27 02:54:03,918 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command state.apply with jid 20190427025403906759
2019-04-27 02:54:03,933 [salt.minion      :1432][INFO    ][14678] Starting a new job with PID 14678
2019-04-27 02:54:09,801 [salt.state       :915 ][INFO    ][14678] Loading fresh modules for state activity
2019-04-27 02:54:09,894 [salt.state       :1780][INFO    ][14678] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:09.894337
2019-04-27 02:54:09,895 [salt.state       :1813][INFO    ][14678] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-27 02:54:09,897 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14678] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-27 02:54:11,745 [salt.state       :300 ][INFO    ][14678] {'pid': 14685, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-27 02:54:11,747 [salt.state       :1951][INFO    ][14678] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:11.747195 duration_in_ms=1852.859
2019-04-27 02:54:11,750 [salt.state       :1780][INFO    ][14678] Running state [maas.wait_for_machine_status] at time 02:54:11.750133
2019-04-27 02:54:11,752 [salt.state       :1813][INFO    ][14678] Executing state module.run for [maas.wait_for_machine_status]
2019-04-27 02:54:11,752 [salt.utils.decorators:613 ][WARNING ][14678] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:54:14,132 [salt.state       :300 ][INFO    ][14678] {'ret': True}
2019-04-27 02:54:14,133 [salt.state       :1951][INFO    ][14678] Completed state [maas.wait_for_machine_status] at time 02:54:14.132965 duration_in_ms=2382.831
2019-04-27 02:54:14,135 [salt.minion      :1711][INFO    ][14678] Returning information for job: 20190427025403906759
2019-04-27 02:54:14,817 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command state.apply with jid 20190427025414808125
2019-04-27 02:54:14,839 [salt.minion      :1432][INFO    ][14707] Starting a new job with PID 14707
2019-04-27 02:54:15,771 [salt.state       :915 ][INFO    ][14707] Loading fresh modules for state activity
2019-04-27 02:54:15,917 [salt.state       :1780][INFO    ][14707] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:15.917041
2019-04-27 02:54:15,917 [salt.state       :1813][INFO    ][14707] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-27 02:54:15,919 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14707] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-27 02:54:17,754 [salt.state       :300 ][INFO    ][14707] {'pid': 14714, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-27 02:54:17,754 [salt.state       :1951][INFO    ][14707] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:17.754660 duration_in_ms=1837.62
2019-04-27 02:54:17,756 [salt.state       :1780][INFO    ][14707] Running state [maas_machines_storage_cmp002_lvm] at time 02:54:17.756486
2019-04-27 02:54:17,756 [salt.state       :1813][INFO    ][14707] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-27 02:54:18,294 [salt.state       :300 ][INFO    ][14707] Machine cmp002 is not in Ready state.
2019-04-27 02:54:18,294 [salt.state       :1951][INFO    ][14707] Completed state [maas_machines_storage_cmp002_lvm] at time 02:54:18.294580 duration_in_ms=538.095
2019-04-27 02:54:18,295 [salt.state       :1780][INFO    ][14707] Running state [maas_machines_storage_cmp001_lvm] at time 02:54:18.295555
2019-04-27 02:54:18,295 [salt.state       :1813][INFO    ][14707] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-27 02:54:18,828 [salt.state       :300 ][INFO    ][14707] Machine cmp001 is not in Ready state.
2019-04-27 02:54:18,829 [salt.state       :1951][INFO    ][14707] Completed state [maas_machines_storage_cmp001_lvm] at time 02:54:18.829157 duration_in_ms=533.602
2019-04-27 02:54:18,833 [salt.minion      :1711][INFO    ][14707] Returning information for job: 20190427025414808125
2019-04-27 02:54:19,497 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command state.apply with jid 20190427025419485024
2019-04-27 02:54:19,519 [salt.minion      :1432][INFO    ][14724] Starting a new job with PID 14724
2019-04-27 02:54:20,515 [salt.state       :915 ][INFO    ][14724] Loading fresh modules for state activity
2019-04-27 02:54:20,604 [salt.state       :1780][INFO    ][14724] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:20.604213
2019-04-27 02:54:20,604 [salt.state       :1813][INFO    ][14724] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-27 02:54:20,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14724] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-27 02:54:22,460 [salt.state       :300 ][INFO    ][14724] {'pid': 14731, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-27 02:54:22,461 [salt.state       :1951][INFO    ][14724] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:22.461040 duration_in_ms=1856.827
2019-04-27 02:54:22,464 [salt.state       :1780][INFO    ][14724] Running state [maas.deploy_machines] at time 02:54:22.463949
2019-04-27 02:54:22,464 [salt.state       :1813][INFO    ][14724] Executing state module.run for [maas.deploy_machines]
2019-04-27 02:54:22,465 [salt.utils.decorators:613 ][WARNING ][14724] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:54:23,021 [salt.loaded.ext.module.maas:684 ][INFO    ][14724] deploymachines hwe_kernel=ga-16.04 system_id=demp34 distro_series=xenial
2019-04-27 02:54:25,318 [salt.state       :300 ][INFO    ][14724] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-27 02:54:25,319 [salt.state       :1951][INFO    ][14724] Completed state [maas.deploy_machines] at time 02:54:25.319563 duration_in_ms=2855.614
2019-04-27 02:54:25,321 [salt.minion      :1711][INFO    ][14724] Returning information for job: 20190427025419485024
2019-04-27 02:54:25,998 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command state.apply with jid 20190427025425986774
2019-04-27 02:54:26,014 [salt.minion      :1432][INFO    ][14791] Starting a new job with PID 14791
2019-04-27 02:54:31,940 [salt.state       :915 ][INFO    ][14791] Loading fresh modules for state activity
2019-04-27 02:54:32,030 [salt.state       :1780][INFO    ][14791] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:32.030749
2019-04-27 02:54:32,031 [salt.state       :1813][INFO    ][14791] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-27 02:54:32,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14791] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-27 02:54:33,904 [salt.state       :300 ][INFO    ][14791] {'pid': 14815, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-27 02:54:33,905 [salt.state       :1951][INFO    ][14791] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:33.905318 duration_in_ms=1874.57
2019-04-27 02:54:33,907 [salt.state       :1780][INFO    ][14791] Running state [maas.wait_for_machine_status] at time 02:54:33.907709
2019-04-27 02:54:33,908 [salt.state       :1813][INFO    ][14791] Executing state module.run for [maas.wait_for_machine_status]
2019-04-27 02:54:33,908 [salt.utils.decorators:613 ][WARNING ][14791] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-27 02:54:36,441 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.4794209s left)
2019-04-27 02:54:41,086 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025441036342
2019-04-27 02:54:41,109 [salt.minion      :1432][INFO    ][14829] Starting a new job with PID 14829
2019-04-27 02:54:41,143 [salt.minion      :1711][INFO    ][14829] Returning information for job: 20190427025441036342
2019-04-27 02:55:09,229 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.69212794s left)
2019-04-27 02:55:11,171 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025511155451
2019-04-27 02:55:11,191 [salt.minion      :1432][INFO    ][14903] Starting a new job with PID 14903
2019-04-27 02:55:11,230 [salt.minion      :1711][INFO    ][14903] Returning information for job: 20190427025511155451
2019-04-27 02:55:41,244 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025541236191
2019-04-27 02:55:41,258 [salt.minion      :1432][INFO    ][14932] Starting a new job with PID 14932
2019-04-27 02:55:41,294 [salt.minion      :1711][INFO    ][14932] Returning information for job: 20190427025541236191
2019-04-27 02:55:42,208 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.71448398s left)
2019-04-27 02:56:11,313 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025611300862
2019-04-27 02:56:11,335 [salt.minion      :1432][INFO    ][15004] Starting a new job with PID 15004
2019-04-27 02:56:11,365 [salt.minion      :1711][INFO    ][15004] Returning information for job: 20190427025611300862
2019-04-27 02:56:14,938 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.98333502s left)
2019-04-27 02:56:41,395 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025641378223
2019-04-27 02:56:41,414 [salt.minion      :1432][INFO    ][15060] Starting a new job with PID 15060
2019-04-27 02:56:41,454 [salt.minion      :1711][INFO    ][15060] Returning information for job: 20190427025641378223
2019-04-27 02:56:47,442 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.47889495s left)
2019-04-27 02:57:11,467 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025711456365
2019-04-27 02:57:11,482 [salt.minion      :1432][INFO    ][15130] Starting a new job with PID 15130
2019-04-27 02:57:11,514 [salt.minion      :1711][INFO    ][15130] Returning information for job: 20190427025711456365
2019-04-27 02:57:20,183 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.73790193s left)
2019-04-27 02:57:41,540 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025741531065
2019-04-27 02:57:41,554 [salt.minion      :1432][INFO    ][15176] Starting a new job with PID 15176
2019-04-27 02:57:41,585 [salt.minion      :1711][INFO    ][15176] Returning information for job: 20190427025741531065
2019-04-27 02:57:52,718 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.20307589s left)
2019-04-27 02:58:11,730 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025811722590
2019-04-27 02:58:11,747 [salt.minion      :1432][INFO    ][15235] Starting a new job with PID 15235
2019-04-27 02:58:11,776 [salt.minion      :1711][INFO    ][15235] Returning information for job: 20190427025811722590
2019-04-27 02:58:25,449 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.47168398s left)
2019-04-27 02:58:41,804 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025841786500
2019-04-27 02:58:41,818 [salt.minion      :1432][INFO    ][15312] Starting a new job with PID 15312
2019-04-27 02:58:41,854 [salt.minion      :1711][INFO    ][15312] Returning information for job: 20190427025841786500
2019-04-27 02:58:58,019 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.90196204s left)
2019-04-27 02:59:11,874 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025911863080
2019-04-27 02:59:11,900 [salt.minion      :1432][INFO    ][15403] Starting a new job with PID 15403
2019-04-27 02:59:11,930 [salt.minion      :1711][INFO    ][15403] Returning information for job: 20190427025911863080
2019-04-27 02:59:30,923 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.99747086s left)
2019-04-27 02:59:41,977 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427025941965475
2019-04-27 02:59:42,000 [salt.minion      :1432][INFO    ][15514] Starting a new job with PID 15514
2019-04-27 02:59:42,045 [salt.minion      :1711][INFO    ][15514] Returning information for job: 20190427025941965475
2019-04-27 03:00:03,697 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1920.22414589s left)
2019-04-27 03:00:12,092 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030012082125
2019-04-27 03:00:12,114 [salt.minion      :1432][INFO    ][15644] Starting a new job with PID 15644
2019-04-27 03:00:12,145 [salt.minion      :1711][INFO    ][15644] Returning information for job: 20190427030012082125
2019-04-27 03:00:36,511 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.40955305s left)
2019-04-27 03:00:42,305 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030042297504
2019-04-27 03:00:42,322 [salt.minion      :1432][INFO    ][15723] Starting a new job with PID 15723
2019-04-27 03:00:42,357 [salt.minion      :1711][INFO    ][15723] Returning information for job: 20190427030042297504
2019-04-27 03:01:09,245 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1854.67621493s left)
2019-04-27 03:01:12,398 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030112391234
2019-04-27 03:01:12,414 [salt.minion      :1432][INFO    ][15776] Starting a new job with PID 15776
2019-04-27 03:01:12,442 [salt.minion      :1711][INFO    ][15776] Returning information for job: 20190427030112391234
2019-04-27 03:01:42,516 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030142504451
2019-04-27 03:01:42,535 [salt.minion      :1432][INFO    ][15899] Starting a new job with PID 15899
2019-04-27 03:01:42,568 [salt.minion      :1711][INFO    ][15899] Returning information for job: 20190427030142504451
2019-04-27 03:01:42,583 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.33772802s left)
2019-04-27 03:02:12,661 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030212649248
2019-04-27 03:02:12,680 [salt.minion      :1432][INFO    ][15957] Starting a new job with PID 15957
2019-04-27 03:02:12,710 [salt.minion      :1711][INFO    ][15957] Returning information for job: 20190427030212649248
2019-04-27 03:02:15,192 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1788.72841191s left)
2019-04-27 03:02:42,786 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030242773445
2019-04-27 03:02:42,810 [salt.minion      :1432][INFO    ][16106] Starting a new job with PID 16106
2019-04-27 03:02:42,839 [salt.minion      :1711][INFO    ][16106] Returning information for job: 20190427030242773445
2019-04-27 03:02:47,908 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1756.01241803s left)
2019-04-27 03:03:12,801 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030312793676
2019-04-27 03:03:12,824 [salt.minion      :1432][INFO    ][16161] Starting a new job with PID 16161
2019-04-27 03:03:12,856 [salt.minion      :1711][INFO    ][16161] Returning information for job: 20190427030312793676
2019-04-27 03:03:20,693 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1723.22740293s left)
2019-04-27 03:03:42,944 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030342931881
2019-04-27 03:03:42,961 [salt.minion      :1432][INFO    ][16228] Starting a new job with PID 16228
2019-04-27 03:03:42,994 [salt.minion      :1711][INFO    ][16228] Returning information for job: 20190427030342931881
2019-04-27 03:03:53,558 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1690.36321402s left)
2019-04-27 03:04:13,100 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030413088076
2019-04-27 03:04:13,122 [salt.minion      :1432][INFO    ][16264] Starting a new job with PID 16264
2019-04-27 03:04:13,152 [salt.minion      :1711][INFO    ][16264] Returning information for job: 20190427030413088076
2019-04-27 03:04:26,076 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1657.84489989s left)
2019-04-27 03:04:43,253 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030443240801
2019-04-27 03:04:43,270 [salt.minion      :1432][INFO    ][16329] Starting a new job with PID 16329
2019-04-27 03:04:43,301 [salt.minion      :1711][INFO    ][16329] Returning information for job: 20190427030443240801
2019-04-27 03:04:58,932 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1624.988554s left)
2019-04-27 03:05:13,417 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030513405002
2019-04-27 03:05:13,436 [salt.minion      :1432][INFO    ][16370] Starting a new job with PID 16370
2019-04-27 03:05:13,466 [salt.minion      :1711][INFO    ][16370] Returning information for job: 20190427030513405002
2019-04-27 03:05:31,466 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1592.45517707s left)
2019-04-27 03:05:43,569 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030543558661
2019-04-27 03:05:43,590 [salt.minion      :1432][INFO    ][16435] Starting a new job with PID 16435
2019-04-27 03:05:43,618 [salt.minion      :1711][INFO    ][16435] Returning information for job: 20190427030543558661
2019-04-27 03:06:04,256 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1559.66462207s left)
2019-04-27 03:06:13,744 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030613730036
2019-04-27 03:06:13,762 [salt.minion      :1432][INFO    ][16473] Starting a new job with PID 16473
2019-04-27 03:06:13,794 [salt.minion      :1711][INFO    ][16473] Returning information for job: 20190427030613730036
2019-04-27 03:06:34,789 [salt.utils.schedule:1377][INFO    ][3156] Running scheduled job: __mine_interval
2019-04-27 03:06:36,887 [salt.loaded.ext.module.maas:1023][INFO    ][14791] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1527.03348589s left)
2019-04-27 03:06:43,921 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command saltutil.find_job with jid 20190427030643913450
2019-04-27 03:06:43,938 [salt.minion      :1432][INFO    ][16577] Starting a new job with PID 16577
2019-04-27 03:06:43,969 [salt.minion      :1711][INFO    ][16577] Returning information for job: 20190427030643913450
2019-04-27 03:07:09,657 [salt.state       :300 ][INFO    ][14791] {'ret': True}
2019-04-27 03:07:09,658 [salt.state       :1951][INFO    ][14791] Completed state [maas.wait_for_machine_status] at time 03:07:09.658333 duration_in_ms=755750.603
2019-04-27 03:07:09,665 [salt.minion      :1711][INFO    ][14791] Returning information for job: 20190427025425986774
2019-04-27 04:06:34,789 [salt.utils.schedule:1377][INFO    ][3156] Running scheduled job: __mine_interval
2019-04-27 04:47:04,066 [salt.minion      :1308][INFO    ][3156] User sudo_ubuntu Executing command cp.push_dir with jid 20190427044704055730
2019-04-27 04:47:04,082 [salt.minion      :1432][INFO    ][27025] Starting a new job with PID 27025
