2019-04-26 20:07:19,940 [salt.utils.decorators:613 ][WARNING ][2226] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:07:21,061 [salt.utils.decorators:613 ][WARNING ][2226] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:07:24,633 [salt.loaded.int.states.file:2298][WARNING ][2440] 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-26 20:07:47,330 [salt.state       :2022][WARNING ][2724] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-26 20:07:50,458 [salt.utils.decorators:613 ][WARNING ][2724] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:08:03,571 [salt.utils.decorators:613 ][WARNING ][2724] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:08:25,393 [salt.utils.decorators:613 ][WARNING ][2724] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:08:46,896 [salt.utils.decorators:613 ][WARNING ][2724] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:08:48,814 [salt.loaded.ext.module.maasng:1008][WARNING ][2724] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-26 20:08:48,814 [salt.loaded.ext.module.maasng:1011][WARNING ][2724] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-26 20:08:49,450 [salt.loaded.ext.module.maasng:1235][WARNING ][2724] Ignoring parameter vlan:0
2019-04-26 20:08:50,267 [salt.utils.decorators:613 ][WARNING ][2724] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:09:01,987 [salt.utils.decorators:613 ][WARNING ][4915] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:09:02,041 [salt.loaded.ext.module.maas:412 ][WARNING ][4915] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 20:09:03,278 [salt.loaded.ext.module.maas:412 ][WARNING ][4915] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 20:09:04,412 [salt.loaded.ext.module.maas:412 ][WARNING ][4915] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 20:09:05,538 [salt.loaded.ext.module.maas:412 ][WARNING ][4915] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 20:09:10,344 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5458] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-26 20:09:10,380 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5458] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-26 20:09:10,429 [salt.utils.parsers:1051][WARNING ][386] Minion received a SIGTERM. Exiting.
2019-04-26 20:09:11,562 [salt.cli.daemons :293 ][INFO    ][5512] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2019-04-26 20:09:11,732 [salt.cli.daemons :82  ][INFO    ][5512] Starting up the Salt Minion
2019-04-26 20:09:11,733 [salt.utils.event :1017][INFO    ][5512] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2019-04-26 20:09:12,992 [salt.minion      :976 ][INFO    ][5512] Creating minion process manager
2019-04-26 20:09:14,967 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5512] Executing command ['date', '+%z'] in directory '/root'
2019-04-26 20:09:14,994 [salt.utils.schedule:568 ][INFO    ][5512] Updating job settings for scheduled job: __mine_interval
2019-04-26 20:09:14,996 [salt.minion      :1108][INFO    ][5512] Added mine.update to scheduler
2019-04-26 20:09:15,001 [salt.minion      :1975][INFO    ][5512] Minion is starting as user 'root'
2019-04-26 20:09:15,018 [salt.minion      :2336][INFO    ][5512] Minion is ready to receive requests!
2019-04-26 20:09:38,072 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command state.apply with jid 20190426200938061635
2019-04-26 20:09:38,088 [salt.minion      :1432][INFO    ][5616] Starting a new job with PID 5616
2019-04-26 20:09:45,974 [salt.state       :915 ][INFO    ][5616] Loading fresh modules for state activity
2019-04-26 20:09:46,037 [salt.fileclient  :1219][INFO    ][5616] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-26 20:09:46,085 [salt.state       :1780][INFO    ][5616] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:09:46.085293
2019-04-26 20:09:46,085 [salt.state       :1813][INFO    ][5616] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 20:09:46,087 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5616] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 20:09:47,922 [salt.state       :300 ][INFO    ][5616] {'pid': 5624, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 20:09:47,923 [salt.state       :1951][INFO    ][5616] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:09:47.923363 duration_in_ms=1838.07
2019-04-26 20:09:47,926 [salt.state       :1780][INFO    ][5616] Running state [maas.wait_for_machine_status] at time 20:09:47.926316
2019-04-26 20:09:47,927 [salt.state       :1813][INFO    ][5616] Executing state module.run for [maas.wait_for_machine_status]
2019-04-26 20:09:47,928 [salt.utils.decorators:613 ][WARNING ][5616] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:09:48,474 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.46673393s left)
2019-04-26 20:09:53,169 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426200953158401
2019-04-26 20:09:53,182 [salt.minion      :1432][INFO    ][5676] Starting a new job with PID 5676
2019-04-26 20:09:53,206 [salt.minion      :1711][INFO    ][5676] Returning information for job: 20190426200953158401
2019-04-26 20:10:19,148 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.7919631s left)
2019-04-26 20:10:23,232 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201023216038
2019-04-26 20:10:23,252 [salt.minion      :1432][INFO    ][5708] Starting a new job with PID 5708
2019-04-26 20:10:23,280 [salt.minion      :1711][INFO    ][5708] Returning information for job: 20190426201023216038
2019-04-26 20:10:49,758 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1438.18202591s left)
2019-04-26 20:10:53,316 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201053306479
2019-04-26 20:10:53,336 [salt.minion      :1432][INFO    ][5777] Starting a new job with PID 5777
2019-04-26 20:10:53,364 [salt.minion      :1711][INFO    ][5777] Returning information for job: 20190426201053306479
2019-04-26 20:11:20,326 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.6147151s left)
2019-04-26 20:11:23,383 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201123367975
2019-04-26 20:11:23,405 [salt.minion      :1432][INFO    ][5813] Starting a new job with PID 5813
2019-04-26 20:11:23,433 [salt.minion      :1711][INFO    ][5813] Returning information for job: 20190426201123367975
2019-04-26 20:11:51,088 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.85240698s left)
2019-04-26 20:11:53,453 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201153433069
2019-04-26 20:11:53,469 [salt.minion      :1432][INFO    ][5906] Starting a new job with PID 5906
2019-04-26 20:11:53,494 [salt.minion      :1711][INFO    ][5906] Returning information for job: 20190426201153433069
2019-04-26 20:12:21,857 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1346.08327389s left)
2019-04-26 20:12:23,507 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201223493815
2019-04-26 20:12:23,529 [salt.minion      :1432][INFO    ][5994] Starting a new job with PID 5994
2019-04-26 20:12:23,556 [salt.minion      :1711][INFO    ][5994] Returning information for job: 20190426201223493815
2019-04-26 20:12:52,860 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1315.08066392s left)
2019-04-26 20:12:53,640 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201253621556
2019-04-26 20:12:53,660 [salt.minion      :1432][INFO    ][6293] Starting a new job with PID 6293
2019-04-26 20:12:53,686 [salt.minion      :1711][INFO    ][6293] Returning information for job: 20190426201253621556
2019-04-26 20:13:23,681 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1284.25886512s left)
2019-04-26 20:13:23,720 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201323709838
2019-04-26 20:13:23,733 [salt.minion      :1432][INFO    ][6390] Starting a new job with PID 6390
2019-04-26 20:13:23,757 [salt.minion      :1711][INFO    ][6390] Returning information for job: 20190426201323709838
2019-04-26 20:13:53,808 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201353795213
2019-04-26 20:13:53,828 [salt.minion      :1432][INFO    ][6554] Starting a new job with PID 6554
2019-04-26 20:13:53,855 [salt.minion      :1711][INFO    ][6554] Returning information for job: 20190426201353795213
2019-04-26 20:13:54,980 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1252.96061802s left)
2019-04-26 20:14:23,889 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201423877844
2019-04-26 20:14:23,904 [salt.minion      :1432][INFO    ][6644] Starting a new job with PID 6644
2019-04-26 20:14:23,936 [salt.minion      :1711][INFO    ][6644] Returning information for job: 20190426201423877844
2019-04-26 20:14:26,425 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1221.51515603s left)
2019-04-26 20:14:54,009 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201453999934
2019-04-26 20:14:54,031 [salt.minion      :1432][INFO    ][6900] Starting a new job with PID 6900
2019-04-26 20:14:54,058 [salt.minion      :1711][INFO    ][6900] Returning information for job: 20190426201453999934
2019-04-26 20:14:57,907 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1190.03316593s left)
2019-04-26 20:15:24,076 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201524061128
2019-04-26 20:15:24,094 [salt.minion      :1432][INFO    ][6958] Starting a new job with PID 6958
2019-04-26 20:15:24,121 [salt.minion      :1711][INFO    ][6958] Returning information for job: 20190426201524061128
2019-04-26 20:15:29,473 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1158.46691489s left)
2019-04-26 20:15:54,214 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201554206273
2019-04-26 20:15:54,237 [salt.minion      :1432][INFO    ][7173] Starting a new job with PID 7173
2019-04-26 20:15:54,266 [salt.minion      :1711][INFO    ][7173] Returning information for job: 20190426201554206273
2019-04-26 20:16:01,190 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1126.75031996s left)
2019-04-26 20:16:24,326 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201624318524
2019-04-26 20:16:24,338 [salt.minion      :1432][INFO    ][7245] Starting a new job with PID 7245
2019-04-26 20:16:24,365 [salt.minion      :1711][INFO    ][7245] Returning information for job: 20190426201624318524
2019-04-26 20:16:32,992 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1094.94988108s left)
2019-04-26 20:16:54,429 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201654418775
2019-04-26 20:16:54,451 [salt.minion      :1432][INFO    ][7369] Starting a new job with PID 7369
2019-04-26 20:16:54,478 [salt.minion      :1711][INFO    ][7369] Returning information for job: 20190426201654418775
2019-04-26 20:17:04,836 [salt.loaded.ext.module.maas:1023][INFO    ][5616] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1063.10378599s left)
2019-04-26 20:17:24,558 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201724544823
2019-04-26 20:17:24,575 [salt.minion      :1432][INFO    ][7437] Starting a new job with PID 7437
2019-04-26 20:17:24,604 [salt.minion      :1711][INFO    ][7437] Returning information for job: 20190426201724544823
2019-04-26 20:17:36,750 [salt.state       :300 ][INFO    ][5616] {'ret': True}
2019-04-26 20:17:36,750 [salt.state       :1951][INFO    ][5616] Completed state [maas.wait_for_machine_status] at time 20:17:36.750705 duration_in_ms=468824.387
2019-04-26 20:17:36,755 [salt.minion      :1711][INFO    ][5616] Returning information for job: 20190426200938061635
2019-04-26 20:17:37,448 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command state.apply with jid 20190426201737434823
2019-04-26 20:17:37,464 [salt.minion      :1432][INFO    ][7466] Starting a new job with PID 7466
2019-04-26 20:17:45,414 [salt.state       :915 ][INFO    ][7466] Loading fresh modules for state activity
2019-04-26 20:17:45,470 [salt.fileclient  :1219][INFO    ][7466] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-26 20:17:45,568 [salt.state       :1780][INFO    ][7466] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:17:45.568850
2019-04-26 20:17:45,569 [salt.state       :1813][INFO    ][7466] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 20:17:45,571 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7466] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 20:17:47,397 [salt.state       :300 ][INFO    ][7466] {'pid': 7486, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 20:17:47,398 [salt.state       :1951][INFO    ][7466] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:17:47.398564 duration_in_ms=1829.715
2019-04-26 20:17:47,402 [salt.state       :1780][INFO    ][7466] Running state [maas_machines_storage_cmp002_lvm] at time 20:17:47.402562
2019-04-26 20:17:47,404 [salt.state       :1813][INFO    ][7466] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-26 20:17:48,348 [salt.loaded.ext.module.maasng:610 ][INFO    ][7466] 7twrst
2019-04-26 20:17:48,348 [salt.loaded.ext.module.maasng:626 ][INFO    ][7466] sda
2019-04-26 20:17:48,786 [salt.loaded.ext.module.maasng:361 ][INFO    ][7466] 7twrst
2019-04-26 20:17:48,883 [salt.loaded.ext.module.maasng:367 ][INFO    ][7466] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'1c45c3d3-659a-456f-aac0-b627d68b826a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7twrst', u'filesystem': {u'label': None, u'uuid': u'bfa3f41e-5c7e-4a0e-a32c-7c43cc29e138', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'7twrst', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/blockdevices/1/', u'type': u'physical', u'id': 1, u'name': u'sda'}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'uuid': u'0cb15781-5ab1-478c-af52-6a92a1b502ed', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'label': u'root', u'uuid': u'463d33cc-4d6f-41b0-bd68-1aa31c5dd898', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7twrst', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/blockdevices/3/', u'type': u'virtual', u'id': 3, u'name': u'vgroot-lvroot'}]
2019-04-26 20:17:48,884 [salt.loaded.ext.module.maasng:632 ][INFO    ][7466] vgroot
2019-04-26 20:17:48,884 [salt.loaded.ext.module.maasng:635 ][INFO    ][7466] lvroot
2019-04-26 20:17:48,884 [salt.loaded.ext.module.maasng:639 ][INFO    ][7466] 107374182400
2019-04-26 20:17:49,476 [salt.loaded.ext.module.maasng:645 ][INFO    ][7466] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 15}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'7twrst', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/7twrst/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'7twrst', 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'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'uuid': u'1190e1ba-95cf-4226-b9e8-9c80e1475efe', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 9, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7twrst', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'ac528e27-636b-4955-949f-42b3c2027842', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/blockdevices/9/', u'used_size': 107374182400, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7twrst', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'2de1754d-9d51-407c-8dc5-3e0381a9924c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7twrst', u'filesystem': {u'mount_options': None, u'uuid': u'ea2484b8-865e-475a-aff2-0b75f4a0bb9d', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/blockdevices/1/partition/5', u'type': u'partition', u'id': 5, u'device_id': 1}], u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/blockdevices/1/'}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'uuid': u'1190e1ba-95cf-4226-b9e8-9c80e1475efe', u'id': 9, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'7twrst', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'ac528e27-636b-4955-949f-42b3c2027842', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 107374182400, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/blockdevices/9/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'7twrst', u'id': 5}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'7twrst', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/blockdevices/1/', u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'2de1754d-9d51-407c-8dc5-3e0381a9924c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7twrst', u'filesystem': {u'mount_options': None, u'uuid': u'ea2484b8-865e-475a-aff2-0b75f4a0bb9d', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/blockdevices/1/partition/5', u'type': u'partition', u'id': 5, u'device_id': 1}]}, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 15}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'7twrst', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/interfaces/5/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 16}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'id': 14, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'7twrst', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'id': 10, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'7twrst', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/interfaces/10/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'id': 11, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'7twrst', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/interfaces/11/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'id': 12, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'7twrst', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/interfaces/12/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'7twrst', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/interfaces/13/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'7twrst', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/blockdevices/1/', u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'2de1754d-9d51-407c-8dc5-3e0381a9924c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7twrst', u'filesystem': {u'mount_options': None, u'uuid': u'ea2484b8-865e-475a-aff2-0b75f4a0bb9d', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7twrst/blockdevices/1/partition/5', u'type': u'partition', u'id': 5, u'device_id': 1}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-04-26 20:17:49,480 [salt.state       :300 ][INFO    ][7466] {'new': {'storage_layout': 'lvm'}}
2019-04-26 20:17:49,480 [salt.state       :1951][INFO    ][7466] Completed state [maas_machines_storage_cmp002_lvm] at time 20:17:49.480776 duration_in_ms=2078.214
2019-04-26 20:17:49,481 [salt.state       :1780][INFO    ][7466] Running state [maas_machines_storage_cmp001_lvm] at time 20:17:49.481152
2019-04-26 20:17:49,481 [salt.state       :1813][INFO    ][7466] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-26 20:17:50,517 [salt.loaded.ext.module.maasng:610 ][INFO    ][7466] e76a8b
2019-04-26 20:17:50,517 [salt.loaded.ext.module.maasng:626 ][INFO    ][7466] sda
2019-04-26 20:17:51,013 [salt.loaded.ext.module.maasng:361 ][INFO    ][7466] e76a8b
2019-04-26 20:17:51,089 [salt.loaded.ext.module.maasng:367 ][INFO    ][7466] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'6c66e603-7494-4fad-b217-4e9921f28a57', u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e76a8b', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'a75a7188-785e-4f8a-9221-7ec36631dc1c', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'e76a8b', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/blockdevices/2/'}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'uuid': u'67b30426-e01b-494a-a877-e3b203e2e30e', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'e76a8b', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'05c7154d-6a63-4731-b675-ab556e70e763', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 800097042432, u'type': u'virtual', u'id': 4, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/blockdevices/4/'}]
2019-04-26 20:17:51,090 [salt.loaded.ext.module.maasng:632 ][INFO    ][7466] vgroot
2019-04-26 20:17:51,090 [salt.loaded.ext.module.maasng:635 ][INFO    ][7466] lvroot
2019-04-26 20:17:51,090 [salt.loaded.ext.module.maasng:639 ][INFO    ][7466] 107374182400
2019-04-26 20:17:51,672 [salt.loaded.ext.module.maasng:645 ][INFO    ][7466] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.39', u'192.168.11.42'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'e76a8b', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/interfaces/6/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/e76a8b/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'e76a8b', 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'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'uuid': u'96291de9-d426-4aa9-884f-2d29548b5476', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 10, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'e76a8b', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'3882e9ee-1be0-4257-ba79-f2181aaa1dfd', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/blockdevices/10/', u'used_size': 107374182400, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'e76a8b', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'cf03ff10-bbe3-486b-8055-61208d7d0e9f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e76a8b', u'filesystem': {u'mount_options': None, u'uuid': u'3efd14d0-d311-43d1-b6fb-66189c3b3470', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}], u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/blockdevices/2/'}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'uuid': u'96291de9-d426-4aa9-884f-2d29548b5476', u'id': 10, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'e76a8b', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'3882e9ee-1be0-4257-ba79-f2181aaa1dfd', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 107374182400, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/blockdevices/10/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'e76a8b', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 6, u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'e76a8b', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/blockdevices/2/', u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'cf03ff10-bbe3-486b-8055-61208d7d0e9f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e76a8b', u'filesystem': {u'mount_options': None, u'uuid': u'3efd14d0-d311-43d1-b6fb-66189c3b3470', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}]}, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'e76a8b', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/interfaces/6/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'id': 17, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'e76a8b', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/interfaces/17/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'id': 18, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'e76a8b', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/interfaces/18/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'id': 15, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'e76a8b', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 23}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'id': 16, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'e76a8b', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/interfaces/16/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'id': 19, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'e76a8b', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/interfaces/19/'}], u'current_testing_result_id': 7, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'e76a8b', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/blockdevices/2/', u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'cf03ff10-bbe3-486b-8055-61208d7d0e9f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e76a8b', u'filesystem': {u'mount_options': None, u'uuid': u'3efd14d0-d311-43d1-b6fb-66189c3b3470', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/e76a8b/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-04-26 20:17:51,673 [salt.state       :300 ][INFO    ][7466] {'new': {'storage_layout': 'lvm'}}
2019-04-26 20:17:51,674 [salt.state       :1951][INFO    ][7466] Completed state [maas_machines_storage_cmp001_lvm] at time 20:17:51.674025 duration_in_ms=2192.872
2019-04-26 20:17:51,678 [salt.minion      :1711][INFO    ][7466] Returning information for job: 20190426201737434823
2019-04-26 20:17:52,353 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command state.apply with jid 20190426201752342714
2019-04-26 20:17:52,368 [salt.minion      :1432][INFO    ][7565] Starting a new job with PID 7565
2019-04-26 20:17:53,414 [salt.state       :915 ][INFO    ][7565] Loading fresh modules for state activity
2019-04-26 20:17:53,472 [salt.fileclient  :1219][INFO    ][7565] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-26 20:17:53,517 [salt.state       :1780][INFO    ][7565] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:17:53.517627
2019-04-26 20:17:53,517 [salt.state       :1813][INFO    ][7565] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 20:17:53,520 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7565] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 20:17:55,365 [salt.state       :300 ][INFO    ][7565] {'pid': 7577, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 20:17:55,366 [salt.state       :1951][INFO    ][7565] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:17:55.366071 duration_in_ms=1848.444
2019-04-26 20:17:55,369 [salt.state       :1780][INFO    ][7565] Running state [maas.deploy_machines] at time 20:17:55.368975
2019-04-26 20:17:55,369 [salt.state       :1813][INFO    ][7565] Executing state module.run for [maas.deploy_machines]
2019-04-26 20:17:55,370 [salt.utils.decorators:613 ][WARNING ][7565] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:17:55,846 [salt.loaded.ext.module.maas:684 ][INFO    ][7565] deploymachines hwe_kernel=hwe-16.04 system_id=fabhf6 distro_series=xenial
2019-04-26 20:17:58,121 [salt.loaded.ext.module.maas:684 ][INFO    ][7565] deploymachines hwe_kernel=hwe-16.04 system_id=7twrst distro_series=xenial
2019-04-26 20:18:00,433 [salt.loaded.ext.module.maas:684 ][INFO    ][7565] deploymachines hwe_kernel=hwe-16.04 system_id=e76a8b distro_series=xenial
2019-04-26 20:18:02,804 [salt.loaded.ext.module.maas:684 ][INFO    ][7565] deploymachines hwe_kernel=hwe-16.04 system_id=bwfrxe distro_series=xenial
2019-04-26 20:18:05,190 [salt.state       :300 ][INFO    ][7565] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-04-26 20:18:05,191 [salt.state       :1951][INFO    ][7565] Completed state [maas.deploy_machines] at time 20:18:05.191715 duration_in_ms=9822.74
2019-04-26 20:18:05,196 [salt.minion      :1711][INFO    ][7565] Returning information for job: 20190426201752342714
2019-04-26 20:18:05,901 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command state.apply with jid 20190426201805889340
2019-04-26 20:18:05,923 [salt.minion      :1432][INFO    ][7816] Starting a new job with PID 7816
2019-04-26 20:18:13,816 [salt.state       :915 ][INFO    ][7816] Loading fresh modules for state activity
2019-04-26 20:18:13,872 [salt.fileclient  :1219][INFO    ][7816] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-26 20:18:13,925 [salt.state       :1780][INFO    ][7816] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:18:13.925481
2019-04-26 20:18:13,925 [salt.state       :1813][INFO    ][7816] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 20:18:13,928 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7816] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 20:18:15,756 [salt.state       :300 ][INFO    ][7816] {'pid': 7830, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 20:18:15,757 [salt.state       :1951][INFO    ][7816] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:18:15.757368 duration_in_ms=1831.885
2019-04-26 20:18:15,761 [salt.state       :1780][INFO    ][7816] Running state [maas.wait_for_machine_status] at time 20:18:15.761705
2019-04-26 20:18:15,762 [salt.state       :1813][INFO    ][7816] Executing state module.run for [maas.wait_for_machine_status]
2019-04-26 20:18:15,763 [salt.utils.decorators:613 ][WARNING ][7816] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:18:17,445 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2248.3324461s left)
2019-04-26 20:18:21,012 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201821004564
2019-04-26 20:18:21,033 [salt.minion      :1432][INFO    ][7844] Starting a new job with PID 7844
2019-04-26 20:18:21,060 [salt.minion      :1711][INFO    ][7844] Returning information for job: 20190426201821004564
2019-04-26 20:18:49,475 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2216.30324411s left)
2019-04-26 20:18:51,235 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201851218832
2019-04-26 20:18:51,254 [salt.minion      :1432][INFO    ][7905] Starting a new job with PID 7905
2019-04-26 20:18:51,289 [salt.minion      :1711][INFO    ][7905] Returning information for job: 20190426201851218832
2019-04-26 20:19:21,321 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201921311969
2019-04-26 20:19:21,337 [salt.minion      :1432][INFO    ][7948] Starting a new job with PID 7948
2019-04-26 20:19:21,371 [salt.minion      :1711][INFO    ][7948] Returning information for job: 20190426201921311969
2019-04-26 20:19:21,429 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2184.34815812s left)
2019-04-26 20:19:51,384 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426201951371913
2019-04-26 20:19:51,407 [salt.minion      :1432][INFO    ][8018] Starting a new job with PID 8018
2019-04-26 20:19:51,442 [salt.minion      :1711][INFO    ][8018] Returning information for job: 20190426201951371913
2019-04-26 20:19:53,489 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2152.28836703s left)
2019-04-26 20:20:21,440 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202021428823
2019-04-26 20:20:21,461 [salt.minion      :1432][INFO    ][8057] Starting a new job with PID 8057
2019-04-26 20:20:21,489 [salt.minion      :1711][INFO    ][8057] Returning information for job: 20190426202021428823
2019-04-26 20:20:25,404 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2120.37362719s left)
2019-04-26 20:20:51,480 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202051470331
2019-04-26 20:20:51,498 [salt.minion      :1432][INFO    ][8139] Starting a new job with PID 8139
2019-04-26 20:20:51,527 [salt.minion      :1711][INFO    ][8139] Returning information for job: 20190426202051470331
2019-04-26 20:20:57,549 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2088.22841716s left)
2019-04-26 20:21:21,547 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202121536022
2019-04-26 20:21:21,570 [salt.minion      :1432][INFO    ][8222] Starting a new job with PID 8222
2019-04-26 20:21:21,597 [salt.minion      :1711][INFO    ][8222] Returning information for job: 20190426202121536022
2019-04-26 20:21:29,542 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2056.23542809s left)
2019-04-26 20:21:51,657 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202151643444
2019-04-26 20:21:51,683 [salt.minion      :1432][INFO    ][8449] Starting a new job with PID 8449
2019-04-26 20:21:51,728 [salt.minion      :1711][INFO    ][8449] Returning information for job: 20190426202151643444
2019-04-26 20:22:01,521 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2024.25619221s left)
2019-04-26 20:22:21,764 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202221754506
2019-04-26 20:22:21,777 [salt.minion      :1432][INFO    ][8592] Starting a new job with PID 8592
2019-04-26 20:22:21,804 [salt.minion      :1711][INFO    ][8592] Returning information for job: 20190426202221754506
2019-04-26 20:22:33,554 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1992.22347116s left)
2019-04-26 20:22:51,851 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202251830839
2019-04-26 20:22:51,877 [salt.minion      :1432][INFO    ][8804] Starting a new job with PID 8804
2019-04-26 20:22:51,934 [salt.minion      :1711][INFO    ][8804] Returning information for job: 20190426202251830839
2019-04-26 20:23:05,620 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1960.15745807s left)
2019-04-26 20:23:21,989 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202321977957
2019-04-26 20:23:22,014 [salt.minion      :1432][INFO    ][8902] Starting a new job with PID 8902
2019-04-26 20:23:22,042 [salt.minion      :1711][INFO    ][8902] Returning information for job: 20190426202321977957
2019-04-26 20:23:37,546 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1928.23208714s left)
2019-04-26 20:23:52,038 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202352021898
2019-04-26 20:23:52,056 [salt.minion      :1432][INFO    ][9140] Starting a new job with PID 9140
2019-04-26 20:23:52,093 [salt.minion      :1711][INFO    ][9140] Returning information for job: 20190426202352021898
2019-04-26 20:24:09,491 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1896.28642821s left)
2019-04-26 20:24:22,134 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202422116590
2019-04-26 20:24:22,158 [salt.minion      :1432][INFO    ][9199] Starting a new job with PID 9199
2019-04-26 20:24:22,184 [salt.minion      :1711][INFO    ][9199] Returning information for job: 20190426202422116590
2019-04-26 20:24:41,412 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1864.36593199s left)
2019-04-26 20:24:52,293 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202452280970
2019-04-26 20:24:52,319 [salt.minion      :1432][INFO    ][9487] Starting a new job with PID 9487
2019-04-26 20:24:52,364 [salt.minion      :1711][INFO    ][9487] Returning information for job: 20190426202452280970
2019-04-26 20:25:13,328 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1832.449579s left)
2019-04-26 20:25:22,450 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202522433395
2019-04-26 20:25:22,470 [salt.minion      :1432][INFO    ][9688] Starting a new job with PID 9688
2019-04-26 20:25:22,500 [salt.minion      :1711][INFO    ][9688] Returning information for job: 20190426202522433395
2019-04-26 20:25:45,239 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1800.53839898s left)
2019-04-26 20:25:52,569 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202552556095
2019-04-26 20:25:52,592 [salt.minion      :1432][INFO    ][9933] Starting a new job with PID 9933
2019-04-26 20:25:52,614 [salt.minion      :1711][INFO    ][9933] Returning information for job: 20190426202552556095
2019-04-26 20:26:17,009 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1768.7690742s left)
2019-04-26 20:26:22,585 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202622577963
2019-04-26 20:26:22,609 [salt.minion      :1432][INFO    ][9973] Starting a new job with PID 9973
2019-04-26 20:26:22,635 [salt.minion      :1711][INFO    ][9973] Returning information for job: 20190426202622577963
2019-04-26 20:26:48,874 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1736.90373921s left)
2019-04-26 20:26:52,745 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202652727817
2019-04-26 20:26:52,769 [salt.minion      :1432][INFO    ][10113] Starting a new job with PID 10113
2019-04-26 20:26:52,794 [salt.minion      :1711][INFO    ][10113] Returning information for job: 20190426202652727817
2019-04-26 20:27:20,536 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1705.24187398s left)
2019-04-26 20:27:22,895 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202722888295
2019-04-26 20:27:22,909 [salt.minion      :1432][INFO    ][10166] Starting a new job with PID 10166
2019-04-26 20:27:22,932 [salt.minion      :1711][INFO    ][10166] Returning information for job: 20190426202722888295
2019-04-26 20:27:53,086 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202753077955
2019-04-26 20:27:53,096 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1672.68153119s left)
2019-04-26 20:27:53,108 [salt.minion      :1432][INFO    ][10523] Starting a new job with PID 10523
2019-04-26 20:27:53,132 [salt.minion      :1711][INFO    ][10523] Returning information for job: 20190426202753077955
2019-04-26 20:28:23,173 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202823165944
2019-04-26 20:28:23,186 [salt.minion      :1432][INFO    ][10589] Starting a new job with PID 10589
2019-04-26 20:28:23,208 [salt.minion      :1711][INFO    ][10589] Returning information for job: 20190426202823165944
2019-04-26 20:28:24,874 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1640.90345502s left)
2019-04-26 20:28:53,230 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202853220255
2019-04-26 20:28:53,251 [salt.minion      :1432][INFO    ][10775] Starting a new job with PID 10775
2019-04-26 20:28:53,272 [salt.minion      :1711][INFO    ][10775] Returning information for job: 20190426202853220255
2019-04-26 20:28:56,914 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1608.86340308s left)
2019-04-26 20:29:23,399 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202923385533
2019-04-26 20:29:23,421 [salt.minion      :1432][INFO    ][10807] Starting a new job with PID 10807
2019-04-26 20:29:23,445 [salt.minion      :1711][INFO    ][10807] Returning information for job: 20190426202923385533
2019-04-26 20:29:28,790 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1576.9877162s left)
2019-04-26 20:29:53,554 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426202953546514
2019-04-26 20:29:53,575 [salt.minion      :1432][INFO    ][10873] Starting a new job with PID 10873
2019-04-26 20:29:53,603 [salt.minion      :1711][INFO    ][10873] Returning information for job: 20190426202953546514
2019-04-26 20:30:00,724 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1545.05334401s left)
2019-04-26 20:30:23,741 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203023728189
2019-04-26 20:30:23,761 [salt.minion      :1432][INFO    ][10905] Starting a new job with PID 10905
2019-04-26 20:30:23,784 [salt.minion      :1711][INFO    ][10905] Returning information for job: 20190426203023728189
2019-04-26 20:30:32,699 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1513.07836914s left)
2019-04-26 20:30:53,932 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203053917505
2019-04-26 20:30:53,952 [salt.minion      :1432][INFO    ][11057] Starting a new job with PID 11057
2019-04-26 20:30:53,974 [salt.minion      :1711][INFO    ][11057] Returning information for job: 20190426203053917505
2019-04-26 20:31:04,715 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1481.06266999s left)
2019-04-26 20:31:24,142 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203124130470
2019-04-26 20:31:24,164 [salt.minion      :1432][INFO    ][11103] Starting a new job with PID 11103
2019-04-26 20:31:24,186 [salt.minion      :1711][INFO    ][11103] Returning information for job: 20190426203124130470
2019-04-26 20:31:36,676 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1449.10136414s left)
2019-04-26 20:31:54,357 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203154340971
2019-04-26 20:31:54,380 [salt.minion      :1432][INFO    ][11247] Starting a new job with PID 11247
2019-04-26 20:31:54,402 [salt.minion      :1711][INFO    ][11247] Returning information for job: 20190426203154340971
2019-04-26 20:32:08,567 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1417.21059608s left)
2019-04-26 20:32:24,370 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203224362431
2019-04-26 20:32:24,393 [salt.minion      :1432][INFO    ][11278] Starting a new job with PID 11278
2019-04-26 20:32:24,413 [salt.minion      :1711][INFO    ][11278] Returning information for job: 20190426203224362431
2019-04-26 20:32:40,463 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1385.31497002s left)
2019-04-26 20:32:54,594 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203254579636
2019-04-26 20:32:54,617 [salt.minion      :1432][INFO    ][11346] Starting a new job with PID 11346
2019-04-26 20:32:54,640 [salt.minion      :1711][INFO    ][11346] Returning information for job: 20190426203254579636
2019-04-26 20:33:12,372 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1353.40601921s left)
2019-04-26 20:33:24,801 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203324790612
2019-04-26 20:33:24,821 [salt.minion      :1432][INFO    ][11377] Starting a new job with PID 11377
2019-04-26 20:33:24,842 [salt.minion      :1711][INFO    ][11377] Returning information for job: 20190426203324790612
2019-04-26 20:33:44,380 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1321.39745021s left)
2019-04-26 20:33:54,817 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203354810010
2019-04-26 20:33:54,833 [salt.minion      :1432][INFO    ][11443] Starting a new job with PID 11443
2019-04-26 20:33:54,853 [salt.minion      :1711][INFO    ][11443] Returning information for job: 20190426203354810010
2019-04-26 20:34:16,481 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1289.29687715s left)
2019-04-26 20:34:24,873 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203424859327
2019-04-26 20:34:24,896 [salt.minion      :1432][INFO    ][11482] Starting a new job with PID 11482
2019-04-26 20:34:24,918 [salt.minion      :1711][INFO    ][11482] Returning information for job: 20190426203424859327
2019-04-26 20:34:48,309 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1257.46908998s left)
2019-04-26 20:34:55,103 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203455088223
2019-04-26 20:34:55,122 [salt.minion      :1432][INFO    ][11551] Starting a new job with PID 11551
2019-04-26 20:34:55,144 [salt.minion      :1711][INFO    ][11551] Returning information for job: 20190426203455088223
2019-04-26 20:35:20,273 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1225.50430918s left)
2019-04-26 20:35:25,147 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203525135879
2019-04-26 20:35:25,170 [salt.minion      :1432][INFO    ][11591] Starting a new job with PID 11591
2019-04-26 20:35:25,192 [salt.minion      :1711][INFO    ][11591] Returning information for job: 20190426203525135879
2019-04-26 20:35:52,256 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1193.52136207s left)
2019-04-26 20:35:55,268 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203555253170
2019-04-26 20:35:55,292 [salt.minion      :1432][INFO    ][11659] Starting a new job with PID 11659
2019-04-26 20:35:55,313 [salt.minion      :1711][INFO    ][11659] Returning information for job: 20190426203555253170
2019-04-26 20:36:24,071 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1161.70683408s left)
2019-04-26 20:36:25,349 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203625333836
2019-04-26 20:36:25,365 [salt.minion      :1432][INFO    ][11691] Starting a new job with PID 11691
2019-04-26 20:36:25,391 [salt.minion      :1711][INFO    ][11691] Returning information for job: 20190426203625333836
2019-04-26 20:36:55,461 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203655451427
2019-04-26 20:36:55,474 [salt.minion      :1432][INFO    ][11760] Starting a new job with PID 11760
2019-04-26 20:36:55,499 [salt.minion      :1711][INFO    ][11760] Returning information for job: 20190426203655451427
2019-04-26 20:36:55,930 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1129.84815907s left)
2019-04-26 20:37:25,568 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203725555676
2019-04-26 20:37:25,591 [salt.minion      :1432][INFO    ][11789] Starting a new job with PID 11789
2019-04-26 20:37:25,616 [salt.minion      :1711][INFO    ][11789] Returning information for job: 20190426203725555676
2019-04-26 20:37:27,729 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1098.048944s left)
2019-04-26 20:37:55,734 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203755718447
2019-04-26 20:37:55,758 [salt.minion      :1432][INFO    ][11876] Starting a new job with PID 11876
2019-04-26 20:37:55,782 [salt.minion      :1711][INFO    ][11876] Returning information for job: 20190426203755718447
2019-04-26 20:37:59,540 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1066.23732901s left)
2019-04-26 20:38:25,869 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203825856832
2019-04-26 20:38:25,891 [salt.minion      :1432][INFO    ][11912] Starting a new job with PID 11912
2019-04-26 20:38:25,915 [salt.minion      :1711][INFO    ][11912] Returning information for job: 20190426203825856832
2019-04-26 20:38:31,376 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1034.40157604s left)
2019-04-26 20:38:56,045 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203856033192
2019-04-26 20:38:56,069 [salt.minion      :1432][INFO    ][11980] Starting a new job with PID 11980
2019-04-26 20:38:56,092 [salt.minion      :1711][INFO    ][11980] Returning information for job: 20190426203856033192
2019-04-26 20:39:03,362 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1002.41540909s left)
2019-04-26 20:39:26,198 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203926184310
2019-04-26 20:39:26,219 [salt.minion      :1432][INFO    ][12014] Starting a new job with PID 12014
2019-04-26 20:39:26,241 [salt.minion      :1711][INFO    ][12014] Returning information for job: 20190426203926184310
2019-04-26 20:39:35,308 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (970.469197035s left)
2019-04-26 20:39:56,416 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426203956399649
2019-04-26 20:39:56,434 [salt.minion      :1432][INFO    ][12082] Starting a new job with PID 12082
2019-04-26 20:39:56,459 [salt.minion      :1711][INFO    ][12082] Returning information for job: 20190426203956399649
2019-04-26 20:40:07,150 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (938.627485991s left)
2019-04-26 20:40:26,610 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204026602600
2019-04-26 20:40:26,624 [salt.minion      :1432][INFO    ][12116] Starting a new job with PID 12116
2019-04-26 20:40:26,646 [salt.minion      :1711][INFO    ][12116] Returning information for job: 20190426204026602600
2019-04-26 20:40:39,096 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (906.681569099s left)
2019-04-26 20:40:56,829 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204056820899
2019-04-26 20:40:56,842 [salt.minion      :1432][INFO    ][12188] Starting a new job with PID 12188
2019-04-26 20:40:56,866 [salt.minion      :1711][INFO    ][12188] Returning information for job: 20190426204056820899
2019-04-26 20:41:10,992 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (874.785615206s left)
2019-04-26 20:41:26,865 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204126856774
2019-04-26 20:41:26,886 [salt.minion      :1432][INFO    ][12219] Starting a new job with PID 12219
2019-04-26 20:41:26,907 [salt.minion      :1711][INFO    ][12219] Returning information for job: 20190426204126856774
2019-04-26 20:41:42,815 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (842.962656021s left)
2019-04-26 20:41:57,072 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204157064575
2019-04-26 20:41:57,085 [salt.minion      :1432][INFO    ][12292] Starting a new job with PID 12292
2019-04-26 20:41:57,109 [salt.minion      :1711][INFO    ][12292] Returning information for job: 20190426204157064575
2019-04-26 20:42:14,633 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (811.144832134s left)
2019-04-26 20:42:27,158 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204227145626
2019-04-26 20:42:27,177 [salt.minion      :1432][INFO    ][12318] Starting a new job with PID 12318
2019-04-26 20:42:27,199 [salt.minion      :1711][INFO    ][12318] Returning information for job: 20190426204227145626
2019-04-26 20:42:46,446 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (779.331315041s left)
2019-04-26 20:42:57,187 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204257177466
2019-04-26 20:42:57,203 [salt.minion      :1432][INFO    ][12393] Starting a new job with PID 12393
2019-04-26 20:42:57,224 [salt.minion      :1711][INFO    ][12393] Returning information for job: 20190426204257177466
2019-04-26 20:43:18,396 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (747.382096052s left)
2019-04-26 20:43:27,317 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204327301615
2019-04-26 20:43:27,335 [salt.minion      :1432][INFO    ][12419] Starting a new job with PID 12419
2019-04-26 20:43:27,360 [salt.minion      :1711][INFO    ][12419] Returning information for job: 20190426204327301615
2019-04-26 20:43:50,334 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (715.443651199s left)
2019-04-26 20:43:57,382 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204357374523
2019-04-26 20:43:57,395 [salt.minion      :1432][INFO    ][12496] Starting a new job with PID 12496
2019-04-26 20:43:57,417 [salt.minion      :1711][INFO    ][12496] Returning information for job: 20190426204357374523
2019-04-26 20:44:22,183 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (683.595193148s left)
2019-04-26 20:44:27,529 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204427519731
2019-04-26 20:44:27,548 [salt.minion      :1432][INFO    ][12522] Starting a new job with PID 12522
2019-04-26 20:44:27,572 [salt.minion      :1711][INFO    ][12522] Returning information for job: 20190426204427519731
2019-04-26 20:44:54,031 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (651.747112989s left)
2019-04-26 20:44:57,607 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204457594889
2019-04-26 20:44:57,623 [salt.minion      :1432][INFO    ][12603] Starting a new job with PID 12603
2019-04-26 20:44:57,648 [salt.minion      :1711][INFO    ][12603] Returning information for job: 20190426204457594889
2019-04-26 20:45:25,829 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (619.948334217s left)
2019-04-26 20:45:27,776 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204527764774
2019-04-26 20:45:27,790 [salt.minion      :1432][INFO    ][12626] Starting a new job with PID 12626
2019-04-26 20:45:27,816 [salt.minion      :1711][INFO    ][12626] Returning information for job: 20190426204527764774
2019-04-26 20:45:57,714 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (588.064391136s left)
2019-04-26 20:45:57,919 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204557911679
2019-04-26 20:45:57,937 [salt.minion      :1432][INFO    ][12706] Starting a new job with PID 12706
2019-04-26 20:45:57,965 [salt.minion      :1711][INFO    ][12706] Returning information for job: 20190426204557911679
2019-04-26 20:46:27,953 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204627942999
2019-04-26 20:46:27,970 [salt.minion      :1432][INFO    ][12723] Starting a new job with PID 12723
2019-04-26 20:46:27,994 [salt.minion      :1711][INFO    ][12723] Returning information for job: 20190426204627942999
2019-04-26 20:46:29,525 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (556.252912998s left)
2019-04-26 20:46:57,978 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204657970125
2019-04-26 20:46:57,992 [salt.minion      :1432][INFO    ][12804] Starting a new job with PID 12804
2019-04-26 20:46:58,014 [salt.minion      :1711][INFO    ][12804] Returning information for job: 20190426204657970125
2019-04-26 20:47:01,184 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (524.593799114s left)
2019-04-26 20:47:28,025 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204728009181
2019-04-26 20:47:28,042 [salt.minion      :1432][INFO    ][12824] Starting a new job with PID 12824
2019-04-26 20:47:28,070 [salt.minion      :1711][INFO    ][12824] Returning information for job: 20190426204728009181
2019-04-26 20:47:32,984 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (492.793896198s left)
2019-04-26 20:47:58,112 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204758103805
2019-04-26 20:47:58,130 [salt.minion      :1432][INFO    ][13051] Starting a new job with PID 13051
2019-04-26 20:47:58,156 [salt.minion      :1711][INFO    ][13051] Returning information for job: 20190426204758103805
2019-04-26 20:48:04,665 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (461.113022089s left)
2019-04-26 20:48:28,169 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204828156295
2019-04-26 20:48:28,189 [salt.minion      :1432][INFO    ][13074] Starting a new job with PID 13074
2019-04-26 20:48:28,211 [salt.minion      :1711][INFO    ][13074] Returning information for job: 20190426204828156295
2019-04-26 20:48:36,665 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (429.112756014s left)
2019-04-26 20:48:58,261 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204858252646
2019-04-26 20:48:58,278 [salt.minion      :1432][INFO    ][13154] Starting a new job with PID 13154
2019-04-26 20:48:58,303 [salt.minion      :1711][INFO    ][13154] Returning information for job: 20190426204858252646
2019-04-26 20:49:08,232 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (397.546102047s left)
2019-04-26 20:49:28,352 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204928344129
2019-04-26 20:49:28,370 [salt.minion      :1432][INFO    ][13178] Starting a new job with PID 13178
2019-04-26 20:49:28,398 [salt.minion      :1711][INFO    ][13178] Returning information for job: 20190426204928344129
2019-04-26 20:49:40,086 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (365.69195199s left)
2019-04-26 20:49:58,513 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426204958500932
2019-04-26 20:49:58,533 [salt.minion      :1432][INFO    ][13259] Starting a new job with PID 13259
2019-04-26 20:49:58,557 [salt.minion      :1711][INFO    ][13259] Returning information for job: 20190426204958500932
2019-04-26 20:50:11,891 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (333.887222052s left)
2019-04-26 20:50:28,639 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205028625652
2019-04-26 20:50:28,661 [salt.minion      :1432][INFO    ][13279] Starting a new job with PID 13279
2019-04-26 20:50:28,685 [salt.minion      :1711][INFO    ][13279] Returning information for job: 20190426205028625652
2019-04-26 20:50:43,667 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (302.110188007s left)
2019-04-26 20:50:58,816 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205058803385
2019-04-26 20:50:58,836 [salt.minion      :1432][INFO    ][13358] Starting a new job with PID 13358
2019-04-26 20:50:58,864 [salt.minion      :1711][INFO    ][13358] Returning information for job: 20190426205058803385
2019-04-26 20:51:15,478 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (270.29953599s left)
2019-04-26 20:51:28,978 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205128970591
2019-04-26 20:51:28,993 [salt.minion      :1432][INFO    ][13378] Starting a new job with PID 13378
2019-04-26 20:51:29,017 [salt.minion      :1711][INFO    ][13378] Returning information for job: 20190426205128970591
2019-04-26 20:51:47,279 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (238.499126196s left)
2019-04-26 20:51:58,999 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205158986136
2019-04-26 20:51:59,022 [salt.minion      :1432][INFO    ][13458] Starting a new job with PID 13458
2019-04-26 20:51:59,044 [salt.minion      :1711][INFO    ][13458] Returning information for job: 20190426205158986136
2019-04-26 20:52:19,181 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (206.597016096s left)
2019-04-26 20:52:29,017 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205229007694
2019-04-26 20:52:29,036 [salt.minion      :1432][INFO    ][13478] Starting a new job with PID 13478
2019-04-26 20:52:29,061 [salt.minion      :1711][INFO    ][13478] Returning information for job: 20190426205229007694
2019-04-26 20:52:51,028 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (174.749408007s left)
2019-04-26 20:52:59,121 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205259108233
2019-04-26 20:52:59,143 [salt.minion      :1432][INFO    ][13563] Starting a new job with PID 13563
2019-04-26 20:52:59,166 [salt.minion      :1711][INFO    ][13563] Returning information for job: 20190426205259108233
2019-04-26 20:53:22,869 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (142.908756018s left)
2019-04-26 20:53:29,192 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205329185269
2019-04-26 20:53:29,206 [salt.minion      :1432][INFO    ][13583] Starting a new job with PID 13583
2019-04-26 20:53:29,228 [salt.minion      :1711][INFO    ][13583] Returning information for job: 20190426205329185269
2019-04-26 20:53:54,765 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (111.012939215s left)
2019-04-26 20:53:59,237 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205359225012
2019-04-26 20:53:59,253 [salt.minion      :1432][INFO    ][13662] Starting a new job with PID 13662
2019-04-26 20:53:59,275 [salt.minion      :1711][INFO    ][13662] Returning information for job: 20190426205359225012
2019-04-26 20:54:26,562 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (79.2154121399s left)
2019-04-26 20:54:29,348 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205429338041
2019-04-26 20:54:29,369 [salt.minion      :1432][INFO    ][13686] Starting a new job with PID 13686
2019-04-26 20:54:29,393 [salt.minion      :1711][INFO    ][13686] Returning information for job: 20190426205429338041
2019-04-26 20:54:58,498 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (47.2799150944s left)
2019-04-26 20:54:59,408 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205459398757
2019-04-26 20:54:59,431 [salt.minion      :1432][INFO    ][13766] Starting a new job with PID 13766
2019-04-26 20:54:59,455 [salt.minion      :1711][INFO    ][13766] Returning information for job: 20190426205459398757
2019-04-26 20:55:29,586 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205529576923
2019-04-26 20:55:29,601 [salt.minion      :1432][INFO    ][13785] Starting a new job with PID 13785
2019-04-26 20:55:29,623 [salt.minion      :1711][INFO    ][13785] Returning information for job: 20190426205529576923
2019-04-26 20:55:30,304 [salt.loaded.ext.module.maas:1023][INFO    ][7816] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (15.4733300209s left)
2019-04-26 20:55:59,668 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205559654795
2019-04-26 20:55:59,688 [salt.minion      :1432][INFO    ][13864] Starting a new job with PID 13864
2019-04-26 20:55:59,714 [salt.minion      :1711][INFO    ][13864] Returning information for job: 20190426205559654795
2019-04-26 20:56:02,153 [salt.state       :302 ][ERROR   ][7816] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['gtw01']not in Deployed state
2019-04-26 20:56:02,153 [salt.state       :1951][INFO    ][7816] Completed state [maas.wait_for_machine_status] at time 20:56:02.153654 duration_in_ms=2266391.947
2019-04-26 20:56:02,157 [salt.minion      :1711][INFO    ][7816] Returning information for job: 20190426201805889340
2019-04-26 20:56:12,906 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command pillar.get with jid 20190426205612897395
2019-04-26 20:56:12,923 [salt.minion      :1432][INFO    ][13880] Starting a new job with PID 13880
2019-04-26 20:56:12,933 [salt.minion      :1711][INFO    ][13880] Returning information for job: 20190426205612897395
2019-04-26 20:56:13,470 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command service.status with jid 20190426205613457956
2019-04-26 20:56:13,484 [salt.minion      :1432][INFO    ][13885] Starting a new job with PID 13885
2019-04-26 20:56:13,976 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13885] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-26 20:56:14,012 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13885] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-26 20:56:14,028 [salt.minion      :1711][INFO    ][13885] Returning information for job: 20190426205613457956
2019-04-26 20:56:14,576 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command state.apply with jid 20190426205614568463
2019-04-26 20:56:14,599 [salt.minion      :1432][INFO    ][13896] Starting a new job with PID 13896
2019-04-26 20:56:22,493 [salt.state       :915 ][INFO    ][13896] Loading fresh modules for state activity
2019-04-26 20:56:23,049 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command 'salt-minion --version' in directory '/root'
2019-04-26 20:56:23,333 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command 'salt-minion --version' in directory '/root'
2019-04-26 20:56:24,239 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command 'salt-minion --version' in directory '/root'
2019-04-26 20:56:24,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command 'salt-minion --version' in directory '/root'
2019-04-26 20:56:26,146 [salt.state       :1780][INFO    ][13896] Running state [salt-minion] at time 20:56:26.146491
2019-04-26 20:56:26,147 [salt.state       :1813][INFO    ][13896] Executing state pkg.installed for [salt-minion]
2019-04-26 20:56:26,147 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-26 20:56:26,247 [salt.state       :300 ][INFO    ][13896] All specified packages are already installed
2019-04-26 20:56:26,247 [salt.state       :1951][INFO    ][13896] Completed state [salt-minion] at time 20:56:26.247691 duration_in_ms=101.2
2019-04-26 20:56:26,248 [salt.state       :1780][INFO    ][13896] Running state [salt_minion_dependency_packages] at time 20:56:26.248011
2019-04-26 20:56:26,248 [salt.state       :1813][INFO    ][13896] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-26 20:56:26,256 [salt.state       :300 ][INFO    ][13896] All specified packages are already installed
2019-04-26 20:56:26,256 [salt.state       :1951][INFO    ][13896] Completed state [salt_minion_dependency_packages] at time 20:56:26.256938 duration_in_ms=8.927
2019-04-26 20:56:26,260 [salt.state       :1780][INFO    ][13896] Running state [/etc/salt/minion.d/minion.conf] at time 20:56:26.260881
2019-04-26 20:56:26,261 [salt.state       :1813][INFO    ][13896] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-26 20:56:26,523 [salt.state       :300 ][INFO    ][13896] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-26 20:56:26,523 [salt.state       :1951][INFO    ][13896] Completed state [/etc/salt/minion.d/minion.conf] at time 20:56:26.523550 duration_in_ms=262.668
2019-04-26 20:56:26,526 [salt.state       :1780][INFO    ][13896] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:56:26.526507
2019-04-26 20:56:26,526 [salt.state       :1813][INFO    ][13896] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-26 20:56:26,540 [salt.state       :300 ][INFO    ][13896] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-26 20:56:26,540 [salt.state       :1951][INFO    ][13896] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:56:26.540861 duration_in_ms=14.352
2019-04-26 20:56:26,542 [salt.state       :1780][INFO    ][13896] Running state [salt-minion] at time 20:56:26.542200
2019-04-26 20:56:26,542 [salt.state       :1813][INFO    ][13896] Executing state service.running for [salt-minion]
2019-04-26 20:56:26,543 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-26 20:56:26,576 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-26 20:56:26,591 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-26 20:56:26,611 [salt.state       :300 ][INFO    ][13896] The service salt-minion is already running
2019-04-26 20:56:26,611 [salt.state       :1951][INFO    ][13896] Completed state [salt-minion] at time 20:56:26.611796 duration_in_ms=69.595
2019-04-26 20:56:26,614 [salt.state       :1780][INFO    ][13896] Running state [/etc/salt/grains.d] at time 20:56:26.614598
2019-04-26 20:56:26,615 [salt.state       :1813][INFO    ][13896] Executing state file.directory for [/etc/salt/grains.d]
2019-04-26 20:56:26,616 [salt.state       :300 ][INFO    ][13896] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-26 20:56:26,617 [salt.state       :1951][INFO    ][13896] Completed state [/etc/salt/grains.d] at time 20:56:26.616923 duration_in_ms=2.324
2019-04-26 20:56:26,618 [salt.state       :1780][INFO    ][13896] Running state [/etc/salt/grains] at time 20:56:26.618122
2019-04-26 20:56:26,618 [salt.state       :1813][INFO    ][13896] Executing state file.managed for [/etc/salt/grains]
2019-04-26 20:56:26,620 [salt.state       :300 ][INFO    ][13896] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-26 20:56:26,621 [salt.state       :1951][INFO    ][13896] Completed state [/etc/salt/grains] at time 20:56:26.621163 duration_in_ms=3.042
2019-04-26 20:56:26,621 [salt.state       :1780][INFO    ][13896] Running state [/etc/salt/grains.d/placeholder] at time 20:56:26.621650
2019-04-26 20:56:26,621 [salt.state       :1813][INFO    ][13896] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-26 20:56:26,622 [salt.state       :300 ][INFO    ][13896] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-26 20:56:26,622 [salt.state       :1951][INFO    ][13896] Completed state [/etc/salt/grains.d/placeholder] at time 20:56:26.622673 duration_in_ms=1.023
2019-04-26 20:56:26,623 [salt.state       :1780][INFO    ][13896] Running state [/etc/salt/grains.d/sphinx] at time 20:56:26.623479
2019-04-26 20:56:26,623 [salt.state       :1813][INFO    ][13896] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-26 20:56:26,624 [salt.state       :300 ][INFO    ][13896] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-26 20:56:26,625 [salt.state       :1951][INFO    ][13896] Completed state [/etc/salt/grains.d/sphinx] at time 20:56:26.625079 duration_in_ms=1.6
2019-04-26 20:56:26,627 [salt.state       :1780][INFO    ][13896] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:56:26.627171
2019-04-26 20:56:26,627 [salt.state       :1813][INFO    ][13896] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-26 20:56:26,627 [salt.state       :300 ][INFO    ][13896] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-26 20:56:26,628 [salt.state       :1951][INFO    ][13896] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:56:26.627990 duration_in_ms=0.819
2019-04-26 20:56:26,628 [salt.state       :1780][INFO    ][13896] Running state [/etc/salt/grains.d/dns_records] at time 20:56:26.628489
2019-04-26 20:56:26,628 [salt.state       :1813][INFO    ][13896] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-26 20:56:26,629 [salt.state       :300 ][INFO    ][13896] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-26 20:56:26,629 [salt.state       :1951][INFO    ][13896] Completed state [/etc/salt/grains.d/dns_records] at time 20:56:26.629910 duration_in_ms=1.422
2019-04-26 20:56:26,632 [salt.state       :1780][INFO    ][13896] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:56:26.632554
2019-04-26 20:56:26,632 [salt.state       :1813][INFO    ][13896] 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-26 20:56:26,633 [salt.state       :300 ][INFO    ][13896] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-26 20:56:26,633 [salt.state       :1951][INFO    ][13896] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:56:26.633303 duration_in_ms=0.749
2019-04-26 20:56:26,633 [salt.state       :1780][INFO    ][13896] Running state [/etc/salt/grains.d/salt] at time 20:56:26.633761
2019-04-26 20:56:26,634 [salt.state       :1813][INFO    ][13896] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-26 20:56:26,634 [salt.state       :300 ][INFO    ][13896] File /etc/salt/grains.d/salt is in the correct state
2019-04-26 20:56:26,635 [salt.state       :1951][INFO    ][13896] Completed state [/etc/salt/grains.d/salt] at time 20:56:26.635091 duration_in_ms=1.329
2019-04-26 20:56:26,635 [salt.state       :1780][INFO    ][13896] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:56:26.635947
2019-04-26 20:56:26,636 [salt.state       :1813][INFO    ][13896] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-26 20:56:26,636 [salt.state       :300 ][INFO    ][13896] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-26 20:56:26,636 [salt.state       :1951][INFO    ][13896] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:56:26.636699 duration_in_ms=0.752
2019-04-26 20:56:26,638 [salt.state       :1780][INFO    ][13896] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:56:26.638562
2019-04-26 20:56:26,638 [salt.state       :1813][INFO    ][13896] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-26 20:56:26,639 [salt.state       :300 ][INFO    ][13896] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-26 20:56:26,639 [salt.state       :1951][INFO    ][13896] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:56:26.639337 duration_in_ms=0.775
2019-04-26 20:56:26,640 [salt.state       :1780][INFO    ][13896] Running state [mine.update] at time 20:56:26.639982
2019-04-26 20:56:26,640 [salt.state       :1813][INFO    ][13896] Executing state module.wait for [mine.update]
2019-04-26 20:56:26,640 [salt.state       :300 ][INFO    ][13896] No changes made for mine.update
2019-04-26 20:56:26,640 [salt.state       :1951][INFO    ][13896] Completed state [mine.update] at time 20:56:26.640675 duration_in_ms=0.693
2019-04-26 20:56:26,640 [salt.state       :1780][INFO    ][13896] Running state [ca-certificates] at time 20:56:26.640908
2019-04-26 20:56:26,641 [salt.state       :1813][INFO    ][13896] Executing state pkg.installed for [ca-certificates]
2019-04-26 20:56:26,651 [salt.state       :300 ][INFO    ][13896] All specified packages are already installed
2019-04-26 20:56:26,651 [salt.state       :1951][INFO    ][13896] Completed state [ca-certificates] at time 20:56:26.651357 duration_in_ms=10.449
2019-04-26 20:56:26,652 [salt.state       :1780][INFO    ][13896] Running state [update-ca-certificates] at time 20:56:26.652245
2019-04-26 20:56:26,652 [salt.state       :1813][INFO    ][13896] Executing state cmd.wait for [update-ca-certificates]
2019-04-26 20:56:26,652 [salt.state       :300 ][INFO    ][13896] No changes made for update-ca-certificates
2019-04-26 20:56:26,653 [salt.state       :1951][INFO    ][13896] Completed state [update-ca-certificates] at time 20:56:26.652972 duration_in_ms=0.727
2019-04-26 20:56:26,653 [salt.state       :1780][INFO    ][13896] Running state [iptables] at time 20:56:26.653208
2019-04-26 20:56:26,653 [salt.state       :1813][INFO    ][13896] Executing state pkg.installed for [iptables]
2019-04-26 20:56:26,662 [salt.state       :300 ][INFO    ][13896] All specified packages are already installed
2019-04-26 20:56:26,662 [salt.state       :1951][INFO    ][13896] Completed state [iptables] at time 20:56:26.662670 duration_in_ms=9.462
2019-04-26 20:56:26,662 [salt.state       :1780][INFO    ][13896] Running state [iptables-persistent] at time 20:56:26.662924
2019-04-26 20:56:26,663 [salt.state       :1813][INFO    ][13896] Executing state pkg.installed for [iptables-persistent]
2019-04-26 20:56:26,672 [salt.state       :300 ][INFO    ][13896] All specified packages are already installed
2019-04-26 20:56:26,672 [salt.state       :1951][INFO    ][13896] Completed state [iptables-persistent] at time 20:56:26.672746 duration_in_ms=9.822
2019-04-26 20:56:26,673 [salt.state       :1780][INFO    ][13896] Running state [iptables_modules_v4_load] at time 20:56:26.673683
2019-04-26 20:56:26,673 [salt.state       :1813][INFO    ][13896] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-26 20:56:26,674 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command 'lsmod' in directory '/root'
2019-04-26 20:56:26,690 [salt.state       :300 ][INFO    ][13896] Kernel modules iptable_filter, ip_tables are already present
2019-04-26 20:56:26,690 [salt.state       :1951][INFO    ][13896] Completed state [iptables_modules_v4_load] at time 20:56:26.690745 duration_in_ms=17.061
2019-04-26 20:56:26,693 [salt.state       :1780][INFO    ][13896] Running state [/etc/iptables/rules.v4] at time 20:56:26.693066
2019-04-26 20:56:26,693 [salt.state       :1813][INFO    ][13896] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-26 20:56:26,784 [salt.state       :300 ][INFO    ][13896] File /etc/iptables/rules.v4 is in the correct state
2019-04-26 20:56:26,785 [salt.state       :1951][INFO    ][13896] Completed state [/etc/iptables/rules.v4] at time 20:56:26.785090 duration_in_ms=92.024
2019-04-26 20:56:26,786 [salt.state       :1780][INFO    ][13896] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:56:26.786241
2019-04-26 20:56:26,786 [salt.state       :1813][INFO    ][13896] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-26 20:56:26,787 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-26 20:56:26,806 [salt.state       :300 ][INFO    ][13896] onlyif execution failed
2019-04-26 20:56:26,806 [salt.state       :1951][INFO    ][13896] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:56:26.806609 duration_in_ms=20.366
2019-04-26 20:56:26,808 [salt.state       :1780][INFO    ][13896] Running state [netfilter-persistent] at time 20:56:26.808706
2019-04-26 20:56:26,809 [salt.state       :1813][INFO    ][13896] Executing state service.running for [netfilter-persistent]
2019-04-26 20:56:26,810 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-26 20:56:26,838 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-26 20:56:26,853 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-26 20:56:26,872 [salt.state       :300 ][INFO    ][13896] The service netfilter-persistent is already running
2019-04-26 20:56:26,873 [salt.state       :1951][INFO    ][13896] Completed state [netfilter-persistent] at time 20:56:26.873344 duration_in_ms=64.639
2019-04-26 20:56:26,874 [salt.state       :1780][INFO    ][13896] Running state [iptables_extra.remove_stale_tables] at time 20:56:26.874573
2019-04-26 20:56:26,875 [salt.state       :1813][INFO    ][13896] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-26 20:56:26,875 [salt.state       :300 ][INFO    ][13896] No changes made for iptables_extra.remove_stale_tables
2019-04-26 20:56:26,875 [salt.state       :1951][INFO    ][13896] Completed state [iptables_extra.remove_stale_tables] at time 20:56:26.875807 duration_in_ms=1.234
2019-04-26 20:56:26,876 [salt.state       :1780][INFO    ][13896] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:56:26.876169
2019-04-26 20:56:26,876 [salt.state       :1813][INFO    ][13896] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-26 20:56:26,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13896] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-26 20:56:26,892 [salt.state       :300 ][INFO    ][13896] onlyif execution failed
2019-04-26 20:56:26,892 [salt.state       :1951][INFO    ][13896] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:56:26.892429 duration_in_ms=16.26
2019-04-26 20:56:26,893 [salt.state       :1780][INFO    ][13896] Running state [/etc/iptables/rules.v6] at time 20:56:26.893484
2019-04-26 20:56:26,893 [salt.state       :1813][INFO    ][13896] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-26 20:56:26,894 [salt.state       :300 ][INFO    ][13896] File /etc/iptables/rules.v6 is not present
2019-04-26 20:56:26,897 [salt.state       :1951][INFO    ][13896] Completed state [/etc/iptables/rules.v6] at time 20:56:26.897145 duration_in_ms=3.66
2019-04-26 20:56:26,898 [salt.state       :1780][INFO    ][13896] Running state [iptables_extra.flush_all] at time 20:56:26.898483
2019-04-26 20:56:26,899 [salt.state       :1813][INFO    ][13896] Executing state module.wait for [iptables_extra.flush_all]
2019-04-26 20:56:26,899 [salt.state       :300 ][INFO    ][13896] No changes made for iptables_extra.flush_all
2019-04-26 20:56:26,900 [salt.state       :1951][INFO    ][13896] Completed state [iptables_extra.flush_all] at time 20:56:26.900169 duration_in_ms=1.686
2019-04-26 20:56:26,903 [salt.minion      :1711][INFO    ][13896] Returning information for job: 20190426205614568463
2019-04-26 20:56:27,445 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command state.apply with jid 20190426205627433398
2019-04-26 20:56:27,466 [salt.minion      :1432][INFO    ][13977] Starting a new job with PID 13977
2019-04-26 20:56:28,490 [salt.state       :915 ][INFO    ][13977] Loading fresh modules for state activity
2019-04-26 20:56:29,346 [salt.state       :1780][INFO    ][13977] Running state [maas-rack-controller] at time 20:56:29.346109
2019-04-26 20:56:29,346 [salt.state       :1813][INFO    ][13977] Executing state pkg.installed for [maas-rack-controller]
2019-04-26 20:56:29,348 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13977] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-26 20:56:29,464 [salt.state       :300 ][INFO    ][13977] All specified packages are already installed
2019-04-26 20:56:29,465 [salt.state       :1951][INFO    ][13977] Completed state [maas-rack-controller] at time 20:56:29.465111 duration_in_ms=119.002
2019-04-26 20:56:29,465 [salt.state       :1780][INFO    ][13977] Running state [ipmitool] at time 20:56:29.465691
2019-04-26 20:56:29,466 [salt.state       :1813][INFO    ][13977] Executing state pkg.installed for [ipmitool]
2019-04-26 20:56:29,477 [salt.state       :300 ][INFO    ][13977] All specified packages are already installed
2019-04-26 20:56:29,477 [salt.state       :1951][INFO    ][13977] Completed state [ipmitool] at time 20:56:29.477544 duration_in_ms=11.854
2019-04-26 20:56:29,481 [salt.state       :1780][INFO    ][13977] Running state [/etc/maas/rackd.conf] at time 20:56:29.481761
2019-04-26 20:56:29,482 [salt.state       :1813][INFO    ][13977] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-26 20:56:29,483 [salt.state       :300 ][INFO    ][13977] No changes needed to be made
2019-04-26 20:56:29,483 [salt.state       :1951][INFO    ][13977] Completed state [/etc/maas/rackd.conf] at time 20:56:29.483528 duration_in_ms=1.767
2019-04-26 20:56:29,483 [salt.state       :1780][INFO    ][13977] Running state [/etc/maas/rackd.conf] at time 20:56:29.483768
2019-04-26 20:56:29,484 [salt.state       :1813][INFO    ][13977] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-26 20:56:29,484 [salt.loaded.int.states.file:2298][WARNING ][13977] 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-26 20:56:29,485 [salt.state       :300 ][INFO    ][13977] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-26 20:56:29,485 [salt.state       :1951][INFO    ][13977] Completed state [/etc/maas/rackd.conf] at time 20:56:29.485304 duration_in_ms=1.536
2019-04-26 20:56:29,486 [salt.state       :1780][INFO    ][13977] Running state [maas-rackd] at time 20:56:29.486320
2019-04-26 20:56:29,486 [salt.state       :1813][INFO    ][13977] Executing state service.running for [maas-rackd]
2019-04-26 20:56:29,489 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13977] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-26 20:56:29,530 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13977] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-26 20:56:29,551 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13977] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-26 20:56:29,569 [salt.state       :300 ][INFO    ][13977] The service maas-rackd is already running
2019-04-26 20:56:29,570 [salt.state       :1951][INFO    ][13977] Completed state [maas-rackd] at time 20:56:29.570173 duration_in_ms=83.853
2019-04-26 20:56:29,575 [salt.minion      :1711][INFO    ][13977] Returning information for job: 20190426205627433398
2019-04-26 20:56:30,104 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command state.apply with jid 20190426205630089905
2019-04-26 20:56:30,128 [salt.minion      :1432][INFO    ][14000] Starting a new job with PID 14000
2019-04-26 20:56:31,184 [salt.state       :915 ][INFO    ][14000] Loading fresh modules for state activity
2019-04-26 20:56:32,186 [salt.state       :1780][INFO    ][14000] Running state [maas-region-controller] at time 20:56:32.186633
2019-04-26 20:56:32,187 [salt.state       :1813][INFO    ][14000] Executing state pkg.installed for [maas-region-controller]
2019-04-26 20:56:32,189 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14000] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-26 20:56:32,295 [salt.state       :300 ][INFO    ][14000] All specified packages are already installed
2019-04-26 20:56:32,296 [salt.state       :1951][INFO    ][14000] Completed state [maas-region-controller] at time 20:56:32.296229 duration_in_ms=109.597
2019-04-26 20:56:32,296 [salt.state       :1780][INFO    ][14000] Running state [python-oauth] at time 20:56:32.296550
2019-04-26 20:56:32,296 [salt.state       :1813][INFO    ][14000] Executing state pkg.installed for [python-oauth]
2019-04-26 20:56:32,305 [salt.state       :300 ][INFO    ][14000] All specified packages are already installed
2019-04-26 20:56:32,305 [salt.state       :1951][INFO    ][14000] Completed state [python-oauth] at time 20:56:32.305643 duration_in_ms=9.093
2019-04-26 20:56:32,309 [salt.state       :1780][INFO    ][14000] Running state [/etc/maas/regiond.conf] at time 20:56:32.309558
2019-04-26 20:56:32,309 [salt.state       :1813][INFO    ][14000] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-26 20:56:32,314 [salt.state       :300 ][INFO    ][14000] No changes needed to be made
2019-04-26 20:56:32,314 [salt.state       :1951][INFO    ][14000] Completed state [/etc/maas/regiond.conf] at time 20:56:32.314264 duration_in_ms=4.706
2019-04-26 20:56:32,314 [salt.state       :1780][INFO    ][14000] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:56:32.314743
2019-04-26 20:56:32,315 [salt.state       :1813][INFO    ][14000] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-26 20:56:32,373 [salt.state       :300 ][INFO    ][14000] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-26 20:56:32,373 [salt.state       :1951][INFO    ][14000] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:56:32.373292 duration_in_ms=58.549
2019-04-26 20:56:32,373 [salt.state       :1780][INFO    ][14000] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:56:32.373822
2019-04-26 20:56:32,374 [salt.state       :1813][INFO    ][14000] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-26 20:56:32,378 [salt.state       :300 ][INFO    ][14000] No changes needed to be made
2019-04-26 20:56:32,378 [salt.state       :1951][INFO    ][14000] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:56:32.378292 duration_in_ms=4.471
2019-04-26 20:56:32,378 [salt.state       :1780][INFO    ][14000] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:56:32.378769
2019-04-26 20:56:32,379 [salt.state       :1813][INFO    ][14000] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-26 20:56:32,383 [salt.state       :300 ][INFO    ][14000] No changes needed to be made
2019-04-26 20:56:32,383 [salt.state       :1951][INFO    ][14000] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:56:32.383828 duration_in_ms=5.058
2019-04-26 20:56:32,384 [salt.state       :1780][INFO    ][14000] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:56:32.384339
2019-04-26 20:56:32,384 [salt.state       :1813][INFO    ][14000] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-26 20:56:32,409 [salt.state       :300 ][INFO    ][14000] No changes needed to be made
2019-04-26 20:56:32,409 [salt.state       :1951][INFO    ][14000] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:56:32.409474 duration_in_ms=25.135
2019-04-26 20:56:32,410 [salt.state       :1780][INFO    ][14000] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:56:32.409959
2019-04-26 20:56:32,410 [salt.state       :1813][INFO    ][14000] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-26 20:56:32,421 [salt.state       :300 ][INFO    ][14000] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-26 20:56:32,421 [salt.state       :1951][INFO    ][14000] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:56:32.421827 duration_in_ms=11.868
2019-04-26 20:56:32,423 [salt.state       :1780][INFO    ][14000] Running state [a2enmod headers] at time 20:56:32.423276
2019-04-26 20:56:32,423 [salt.state       :1813][INFO    ][14000] Executing state cmd.run for [a2enmod headers]
2019-04-26 20:56:32,424 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14000] Executing command 'a2enmod headers' in directory '/root'
2019-04-26 20:56:32,490 [salt.state       :300 ][INFO    ][14000] {'pid': 14019, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-26 20:56:32,490 [salt.state       :1951][INFO    ][14000] Completed state [a2enmod headers] at time 20:56:32.490826 duration_in_ms=67.548
2019-04-26 20:56:32,491 [salt.state       :1780][INFO    ][14000] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:56:32.491406
2019-04-26 20:56:32,492 [salt.state       :1813][INFO    ][14000] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-26 20:56:32,517 [salt.state       :300 ][INFO    ][14000] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-26 20:56:32,518 [salt.state       :1951][INFO    ][14000] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:56:32.518299 duration_in_ms=26.892
2019-04-26 20:56:32,519 [salt.state       :1780][INFO    ][14000] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:56:32.519547
2019-04-26 20:56:32,520 [salt.state       :1813][INFO    ][14000] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-26 20:56:32,574 [salt.state       :300 ][INFO    ][14000] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-26 20:56:32,575 [salt.state       :1951][INFO    ][14000] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:56:32.575283 duration_in_ms=55.737
2019-04-26 20:56:32,575 [salt.state       :1780][INFO    ][14000] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:56:32.575787
2019-04-26 20:56:32,576 [salt.state       :1813][INFO    ][14000] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-26 20:56:32,631 [salt.state       :300 ][INFO    ][14000] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-26 20:56:32,632 [salt.state       :1951][INFO    ][14000] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:56:32.632013 duration_in_ms=56.226
2019-04-26 20:56:32,632 [salt.state       :1780][INFO    ][14000] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:56:32.632537
2019-04-26 20:56:32,632 [salt.state       :1813][INFO    ][14000] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-26 20:56:32,700 [salt.state       :300 ][INFO    ][14000] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-26 20:56:32,700 [salt.state       :1951][INFO    ][14000] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:56:32.700586 duration_in_ms=68.049
2019-04-26 20:56:32,700 [salt.state       :1780][INFO    ][14000] Running state [/root/.pgpass] at time 20:56:32.700870
2019-04-26 20:56:32,701 [salt.state       :1813][INFO    ][14000] Executing state file.managed for [/root/.pgpass]
2019-04-26 20:56:32,743 [salt.state       :300 ][INFO    ][14000] File /root/.pgpass is in the correct state
2019-04-26 20:56:32,744 [salt.state       :1951][INFO    ][14000] Completed state [/root/.pgpass] at time 20:56:32.743992 duration_in_ms=43.121
2019-04-26 20:56:32,753 [salt.state       :1780][INFO    ][14000] Running state [maas-region syncdb --noinput] at time 20:56:32.753552
2019-04-26 20:56:32,753 [salt.state       :1813][INFO    ][14000] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-26 20:56:32,754 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14000] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-26 20:56:35,215 [salt.state       :300 ][INFO    ][14000] {'pid': 14032, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sites, auth, metadataserver, piston3, maasserver, contenttypes, 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-26 20:56:35,216 [salt.state       :1951][INFO    ][14000] Completed state [maas-region syncdb --noinput] at time 20:56:35.216596 duration_in_ms=2463.042
2019-04-26 20:56:35,217 [salt.state       :2022][WARNING ][14000] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-26 20:56:35,221 [salt.state       :1780][INFO    ][14000] Running state [maas-regiond] at time 20:56:35.221161
2019-04-26 20:56:35,221 [salt.state       :1813][INFO    ][14000] Executing state service.running for [maas-regiond]
2019-04-26 20:56:35,224 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14000] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-26 20:56:35,261 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14000] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-26 20:56:35,278 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14000] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-26 20:56:35,298 [salt.state       :300 ][INFO    ][14000] The service maas-regiond is already running
2019-04-26 20:56:35,299 [salt.state       :1951][INFO    ][14000] Completed state [maas-regiond] at time 20:56:35.299022 duration_in_ms=77.862
2019-04-26 20:56:35,302 [salt.state       :1780][INFO    ][14000] Running state [bind9] at time 20:56:35.302151
2019-04-26 20:56:35,302 [salt.state       :1813][INFO    ][14000] Executing state service.running for [bind9]
2019-04-26 20:56:35,303 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14000] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-26 20:56:35,326 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14000] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-26 20:56:35,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14000] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-26 20:56:35,360 [salt.state       :300 ][INFO    ][14000] The service bind9 is already running
2019-04-26 20:56:35,361 [salt.state       :1951][INFO    ][14000] Completed state [bind9] at time 20:56:35.361124 duration_in_ms=58.973
2019-04-26 20:56:35,363 [salt.state       :1780][INFO    ][14000] Running state [apache2] at time 20:56:35.363896
2019-04-26 20:56:35,364 [salt.state       :1813][INFO    ][14000] Executing state service.running for [apache2]
2019-04-26 20:56:35,365 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14000] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-26 20:56:35,386 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14000] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-26 20:56:35,405 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14000] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-26 20:56:35,429 [salt.state       :300 ][INFO    ][14000] The service apache2 is already running
2019-04-26 20:56:35,430 [salt.state       :1951][INFO    ][14000] Completed state [apache2] at time 20:56:35.430268 duration_in_ms=66.371
2019-04-26 20:56:35,432 [salt.state       :1780][INFO    ][14000] Running state [maasng.wait_for_http_code] at time 20:56:35.432238
2019-04-26 20:56:35,432 [salt.state       :1813][INFO    ][14000] Executing state module.run for [maasng.wait_for_http_code]
2019-04-26 20:56:35,433 [salt.utils.decorators:613 ][WARNING ][14000] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:56:35,532 [salt.state       :300 ][INFO    ][14000] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-26 20:56:35,532 [salt.state       :1951][INFO    ][14000] Completed state [maasng.wait_for_http_code] at time 20:56:35.532631 duration_in_ms=100.394
2019-04-26 20:56:35,533 [salt.state       :1780][INFO    ][14000] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:56:35.533687
2019-04-26 20:56:35,534 [salt.state       :1813][INFO    ][14000] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-26 20:56:35,534 [salt.state       :300 ][INFO    ][14000] /var/lib/maas/.setup_admin exists
2019-04-26 20:56:35,536 [salt.state       :1951][INFO    ][14000] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:56:35.536313 duration_in_ms=2.626
2019-04-26 20:56:35,537 [salt.state       :1780][INFO    ][14000] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:56:35.537249
2019-04-26 20:56:35,537 [salt.state       :1813][INFO    ][14000] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 20:56:35,538 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14000] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 20:56:37,212 [salt.state       :300 ][INFO    ][14000] {'pid': 14066, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 20:56:37,213 [salt.state       :1951][INFO    ][14000] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:56:37.213354 duration_in_ms=1676.104
2019-04-26 20:56:37,223 [salt.state       :1780][INFO    ][14000] Running state [maas_region_boot_source_resources_mirror] at time 20:56:37.222792
2019-04-26 20:56:37,223 [salt.state       :1813][INFO    ][14000] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-26 20:56:37,313 [salt.state       :300 ][INFO    ][14000] {'changes': {}}
2019-04-26 20:56:37,314 [salt.state       :1951][INFO    ][14000] Completed state [maas_region_boot_source_resources_mirror] at time 20:56:37.314164 duration_in_ms=91.373
2019-04-26 20:56:37,315 [salt.state       :1780][INFO    ][14000] Running state [maasng.boot_resources_import] at time 20:56:37.315079
2019-04-26 20:56:37,315 [salt.state       :1813][INFO    ][14000] Executing state module.run for [maasng.boot_resources_import]
2019-04-26 20:56:37,315 [salt.utils.decorators:613 ][WARNING ][14000] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:56:37,402 [salt.loaded.ext.module.maasng:1600][INFO    ][14000] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-26 20:56:42,452 [salt.loaded.ext.module.maasng:1600][INFO    ][14000] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-26 20:56:45,132 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205645124613
2019-04-26 20:56:45,151 [salt.minion      :1432][INFO    ][14221] Starting a new job with PID 14221
2019-04-26 20:56:45,175 [salt.minion      :1711][INFO    ][14221] Returning information for job: 20190426205645124613
2019-04-26 20:56:47,518 [salt.state       :300 ][INFO    ][14000] {'ret': True}
2019-04-26 20:56:47,518 [salt.state       :1951][INFO    ][14000] Completed state [maasng.boot_resources_import] at time 20:56:47.518709 duration_in_ms=10203.629
2019-04-26 20:56:47,520 [salt.state       :1780][INFO    ][14000] Running state [maas_region_boot_sources_selection_xenial] at time 20:56:47.520111
2019-04-26 20:56:47,520 [salt.state       :1813][INFO    ][14000] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-26 20:56:47,642 [salt.state       :300 ][INFO    ][14000] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-26 20:56:47,642 [salt.state       :1951][INFO    ][14000] Completed state [maas_region_boot_sources_selection_xenial] at time 20:56:47.642270 duration_in_ms=122.159
2019-04-26 20:56:47,645 [salt.state       :1780][INFO    ][14000] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 20:56:47.645320
2019-04-26 20:56:47,645 [salt.state       :1813][INFO    ][14000] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-26 20:56:47,645 [salt.utils.decorators:613 ][WARNING ][14000] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:56:47,646 [salt.loaded.ext.module.maasng:1771][INFO    ][14000] boot-sources sync initiated for ALL Rack's
2019-04-26 20:56:48,444 [salt.state       :300 ][INFO    ][14000] {'ret': True}
2019-04-26 20:56:48,444 [salt.state       :1951][INFO    ][14000] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 20:56:48.444295 duration_in_ms=798.975
2019-04-26 20:56:48,445 [salt.state       :1780][INFO    ][14000] Running state [maas.process_maas_config] at time 20:56:48.445600
2019-04-26 20:56:48,445 [salt.state       :1813][INFO    ][14000] Executing state module.run for [maas.process_maas_config]
2019-04-26 20:56:48,446 [salt.utils.decorators:613 ][WARNING ][14000] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:56:48,446 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=enable_http_proxy value=True
2019-04-26 20:56:48,482 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=upstream_dns value=8.8.8.8
2019-04-26 20:56:49,725 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=commissioning_distro_series value=xenial
2019-04-26 20:56:49,801 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=default_osystem value=ubuntu
2019-04-26 20:56:49,849 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=active_discovery_interval value=600
2019-04-26 20:56:49,885 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=dnssec_validation value=no
2019-04-26 20:56:49,918 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=maas_name value=mas01
2019-04-26 20:56:49,957 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=network_discovery value=enabled
2019-04-26 20:56:50,033 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=enable_third_party_drivers value=True
2019-04-26 20:56:50,072 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=default_storage_layout value=lvm
2019-04-26 20:56:50,108 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=ntp_external_only value=True
2019-04-26 20:56:50,144 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-26 20:56:50,182 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=default_distro_series value=xenial
2019-04-26 20:56:50,242 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-26 20:56:50,369 [salt.state       :300 ][INFO    ][14000] {'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-26 20:56:50,370 [salt.state       :1951][INFO    ][14000] Completed state [maas.process_maas_config] at time 20:56:50.370195 duration_in_ms=1924.594
2019-04-26 20:56:50,370 [salt.state       :1780][INFO    ][14000] Running state [pxe_admin] at time 20:56:50.370869
2019-04-26 20:56:50,371 [salt.state       :1813][INFO    ][14000] Executing state maasng.fabric_present for [pxe_admin]
2019-04-26 20:56:50,450 [salt.loaded.ext.module.maasng:945 ][INFO    ][14000] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'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'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-26 20:56:50,509 [salt.loaded.ext.module.maasng:1008][WARNING ][14000] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-26 20:56:50,509 [salt.loaded.ext.module.maasng:1011][WARNING ][14000] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-26 20:56:50,562 [salt.state       :300 ][INFO    ][14000] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-26 20:56:50,563 [salt.state       :1951][INFO    ][14000] Completed state [pxe_admin] at time 20:56:50.563717 duration_in_ms=192.848
2019-04-26 20:56:50,564 [salt.state       :1780][INFO    ][14000] Running state [vlan 0] at time 20:56:50.564016
2019-04-26 20:56:50,564 [salt.state       :1813][INFO    ][14000] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-26 20:56:50,616 [salt.loaded.ext.module.maasng:945 ][INFO    ][14000] [{u'id': 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': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, 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'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, 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'q3ea6g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-26 20:56:50,725 [salt.loaded.ext.module.maasng:945 ][INFO    ][14000] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'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'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-26 20:56:50,971 [salt.loaded.ext.module.maasng:945 ][INFO    ][14000] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'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'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-26 20:56:51,038 [salt.state       :300 ][INFO    ][14000] {'new': 'Vlan untagged was updated'}
2019-04-26 20:56:51,041 [salt.state       :1951][INFO    ][14000] Completed state [vlan 0] at time 20:56:51.041255 duration_in_ms=477.238
2019-04-26 20:56:51,042 [salt.state       :1780][INFO    ][14000] Running state [192.168.11.0/24] at time 20:56:51.042213
2019-04-26 20:56:51,042 [salt.state       :1813][INFO    ][14000] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-26 20:56:51,277 [salt.loaded.ext.module.maasng:945 ][INFO    ][14000] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'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'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-26 20:56:51,277 [salt.loaded.ext.module.maasng:1235][WARNING ][14000] Ignoring parameter vlan:0
2019-04-26 20:56:51,344 [salt.state       :300 ][INFO    ][14000] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-26 20:56:51,345 [salt.state       :1951][INFO    ][14000] Completed state [192.168.11.0/24] at time 20:56:51.345187 duration_in_ms=302.973
2019-04-26 20:56:51,346 [salt.state       :1780][INFO    ][14000] Running state [maas_create_iprange_1] at time 20:56:51.346018
2019-04-26 20:56:51,346 [salt.state       :1813][INFO    ][14000] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-26 20:56:51,397 [salt.state       :300 ][INFO    ][14000] Iprange maas_create_iprange_1 already exist.
2019-04-26 20:56:51,397 [salt.state       :1951][INFO    ][14000] Completed state [maas_create_iprange_1] at time 20:56:51.397533 duration_in_ms=51.515
2019-04-26 20:56:51,397 [salt.state       :1780][INFO    ][14000] Running state [vlan 0] at time 20:56:51.397826
2019-04-26 20:56:51,398 [salt.state       :1813][INFO    ][14000] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-26 20:56:51,453 [salt.loaded.ext.module.maasng:945 ][INFO    ][14000] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'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'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'q3ea6g', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-26 20:56:51,564 [salt.loaded.ext.module.maasng:945 ][INFO    ][14000] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'q3ea6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-04-26 20:56:51,816 [salt.loaded.ext.module.maasng:945 ][INFO    ][14000] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'q3ea6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-04-26 20:56:51,938 [salt.state       :300 ][INFO    ][14000] {'new': 'Vlan untagged was updated'}
2019-04-26 20:56:51,938 [salt.state       :1951][INFO    ][14000] Completed state [vlan 0] at time 20:56:51.938709 duration_in_ms=540.882
2019-04-26 20:56:51,943 [salt.state       :1780][INFO    ][14000] Running state [opnfv] at time 20:56:51.943653
2019-04-26 20:56:51,944 [salt.state       :1813][INFO    ][14000] Executing state maasng.sshkey_present for [opnfv]
2019-04-26 20:56:51,993 [salt.loaded.ext.module.maasng:1903][INFO    ][14000] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-04-26 20:56:51,994 [salt.state       :300 ][INFO    ][14000] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-26 20:56:51,994 [salt.state       :1951][INFO    ][14000] Completed state [opnfv] at time 20:56:51.994497 duration_in_ms=50.844
2019-04-26 20:56:51,995 [salt.state       :1780][INFO    ][14000] Running state [maas.process_tags] at time 20:56:51.995766
2019-04-26 20:56:51,996 [salt.state       :1813][INFO    ][14000] Executing state module.run for [maas.process_tags]
2019-04-26 20:56:51,996 [salt.utils.decorators:613 ][WARNING ][14000] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:56:52,054 [salt.loaded.ext.module.maas:92  ][INFO    ][14000] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-04-26 20:56:52,128 [salt.state       :300 ][INFO    ][14000] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-04-26 20:56:52,129 [salt.state       :1951][INFO    ][14000] Completed state [maas.process_tags] at time 20:56:52.128931 duration_in_ms=133.165
2019-04-26 20:56:52,133 [salt.minion      :1711][INFO    ][14000] Returning information for job: 20190426205630089905
2019-04-26 20:56:52,716 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command state.apply with jid 20190426205652707988
2019-04-26 20:56:52,738 [salt.minion      :1432][INFO    ][14493] Starting a new job with PID 14493
2019-04-26 20:57:00,756 [salt.state       :915 ][INFO    ][14493] Loading fresh modules for state activity
2019-04-26 20:57:00,852 [salt.state       :1780][INFO    ][14493] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:57:00.852100
2019-04-26 20:57:00,852 [salt.state       :1813][INFO    ][14493] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 20:57:00,854 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14493] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 20:57:02,549 [salt.state       :300 ][INFO    ][14493] {'pid': 14528, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 20:57:02,550 [salt.state       :1951][INFO    ][14493] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:57:02.550182 duration_in_ms=1698.082
2019-04-26 20:57:02,552 [salt.state       :1780][INFO    ][14493] Running state [maas.process_machines] at time 20:57:02.552175
2019-04-26 20:57:02,552 [salt.state       :1813][INFO    ][14493] Executing state module.run for [maas.process_machines]
2019-04-26 20:57:02,553 [salt.utils.decorators:613 ][WARNING ][14493] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:57:03,009 [salt.loaded.ext.module.maas:412 ][WARNING ][14493] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 20:57:03,010 [salt.loaded.ext.module.maas:92  ][INFO    ][14493] machine hostname=gtw01 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=fabhf6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-26 20:57:04,230 [salt.loaded.ext.module.maas:412 ][WARNING ][14493] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 20:57:04,232 [salt.loaded.ext.module.maas:92  ][INFO    ][14493] 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=7twrst architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-26 20:57:05,359 [salt.loaded.ext.module.maas:412 ][WARNING ][14493] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 20:57:05,360 [salt.loaded.ext.module.maas:92  ][INFO    ][14493] 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=e76a8b architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-26 20:57:06,458 [salt.loaded.ext.module.maas:412 ][WARNING ][14493] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 20:57:06,460 [salt.loaded.ext.module.maas:92  ][INFO    ][14493] machine hostname=ctl01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=bwfrxe architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-26 20:57:07,564 [salt.state       :300 ][INFO    ][14493] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-04-26 20:57:07,565 [salt.state       :1951][INFO    ][14493] Completed state [maas.process_machines] at time 20:57:07.564716 duration_in_ms=5012.539
2019-04-26 20:57:07,568 [salt.minion      :1711][INFO    ][14493] Returning information for job: 20190426205652707988
2019-04-26 20:57:40,002 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command state.apply with jid 20190426205739989306
2019-04-26 20:57:40,026 [salt.minion      :1432][INFO    ][14732] Starting a new job with PID 14732
2019-04-26 20:57:47,884 [salt.state       :915 ][INFO    ][14732] Loading fresh modules for state activity
2019-04-26 20:57:47,980 [salt.state       :1780][INFO    ][14732] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:57:47.980124
2019-04-26 20:57:47,980 [salt.state       :1813][INFO    ][14732] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 20:57:47,982 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14732] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 20:57:49,700 [salt.state       :300 ][INFO    ][14732] {'pid': 14745, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 20:57:49,701 [salt.state       :1951][INFO    ][14732] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:57:49.701565 duration_in_ms=1721.441
2019-04-26 20:57:49,704 [salt.state       :1780][INFO    ][14732] Running state [maas.wait_for_machine_status] at time 20:57:49.704849
2019-04-26 20:57:49,705 [salt.state       :1813][INFO    ][14732] Executing state module.run for [maas.wait_for_machine_status]
2019-04-26 20:57:49,706 [salt.utils.decorators:613 ][WARNING ][14732] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:57:51,728 [salt.state       :300 ][INFO    ][14732] {'ret': True}
2019-04-26 20:57:51,728 [salt.state       :1951][INFO    ][14732] Completed state [maas.wait_for_machine_status] at time 20:57:51.728740 duration_in_ms=2023.892
2019-04-26 20:57:51,732 [salt.minion      :1711][INFO    ][14732] Returning information for job: 20190426205739989306
2019-04-26 20:57:52,254 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command state.apply with jid 20190426205752242296
2019-04-26 20:57:52,272 [salt.minion      :1432][INFO    ][14800] Starting a new job with PID 14800
2019-04-26 20:57:53,303 [salt.state       :915 ][INFO    ][14800] Loading fresh modules for state activity
2019-04-26 20:57:53,434 [salt.state       :1780][INFO    ][14800] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:57:53.434490
2019-04-26 20:57:53,434 [salt.state       :1813][INFO    ][14800] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 20:57:53,437 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14800] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 20:57:55,127 [salt.state       :300 ][INFO    ][14800] {'pid': 14812, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 20:57:55,128 [salt.state       :1951][INFO    ][14800] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:57:55.128102 duration_in_ms=1693.612
2019-04-26 20:57:55,129 [salt.state       :1780][INFO    ][14800] Running state [maas_machines_storage_cmp002_lvm] at time 20:57:55.129945
2019-04-26 20:57:55,130 [salt.state       :1813][INFO    ][14800] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-26 20:57:55,528 [salt.state       :300 ][INFO    ][14800] Machine cmp002 is not in Ready state.
2019-04-26 20:57:55,528 [salt.state       :1951][INFO    ][14800] Completed state [maas_machines_storage_cmp002_lvm] at time 20:57:55.528889 duration_in_ms=398.945
2019-04-26 20:57:55,529 [salt.state       :1780][INFO    ][14800] Running state [maas_machines_storage_cmp001_lvm] at time 20:57:55.529250
2019-04-26 20:57:55,529 [salt.state       :1813][INFO    ][14800] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-26 20:57:55,917 [salt.state       :300 ][INFO    ][14800] Machine cmp001 is not in Ready state.
2019-04-26 20:57:55,917 [salt.state       :1951][INFO    ][14800] Completed state [maas_machines_storage_cmp001_lvm] at time 20:57:55.917479 duration_in_ms=388.227
2019-04-26 20:57:55,920 [salt.minion      :1711][INFO    ][14800] Returning information for job: 20190426205752242296
2019-04-26 20:57:56,479 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command state.apply with jid 20190426205756428483
2019-04-26 20:57:56,497 [salt.minion      :1432][INFO    ][14827] Starting a new job with PID 14827
2019-04-26 20:57:57,560 [salt.state       :915 ][INFO    ][14827] Loading fresh modules for state activity
2019-04-26 20:57:57,652 [salt.state       :1780][INFO    ][14827] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:57:57.652501
2019-04-26 20:57:57,652 [salt.state       :1813][INFO    ][14827] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 20:57:57,654 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14827] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 20:57:59,334 [salt.state       :300 ][INFO    ][14827] {'pid': 14847, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 20:57:59,334 [salt.state       :1951][INFO    ][14827] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:57:59.334603 duration_in_ms=1682.102
2019-04-26 20:57:59,336 [salt.state       :1780][INFO    ][14827] Running state [maas.deploy_machines] at time 20:57:59.336908
2019-04-26 20:57:59,337 [salt.state       :1813][INFO    ][14827] Executing state module.run for [maas.deploy_machines]
2019-04-26 20:57:59,337 [salt.utils.decorators:613 ][WARNING ][14827] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:57:59,803 [salt.loaded.ext.module.maas:684 ][INFO    ][14827] deploymachines hwe_kernel=hwe-16.04 system_id=fabhf6 distro_series=xenial
2019-04-26 20:58:02,058 [salt.state       :300 ][INFO    ][14827] {'ret': {'updated': ['cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': ['gtw01']}}
2019-04-26 20:58:02,059 [salt.state       :1951][INFO    ][14827] Completed state [maas.deploy_machines] at time 20:58:02.059001 duration_in_ms=2722.092
2019-04-26 20:58:02,061 [salt.minion      :1711][INFO    ][14827] Returning information for job: 20190426205756428483
2019-04-26 20:58:02,611 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command state.apply with jid 20190426205802597097
2019-04-26 20:58:02,634 [salt.minion      :1432][INFO    ][14908] Starting a new job with PID 14908
2019-04-26 20:58:10,504 [salt.state       :915 ][INFO    ][14908] Loading fresh modules for state activity
2019-04-26 20:58:10,607 [salt.state       :1780][INFO    ][14908] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:58:10.607196
2019-04-26 20:58:10,607 [salt.state       :1813][INFO    ][14908] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 20:58:10,609 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14908] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 20:58:12,276 [salt.state       :300 ][INFO    ][14908] {'pid': 14924, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 20:58:12,277 [salt.state       :1951][INFO    ][14908] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:58:12.277374 duration_in_ms=1670.177
2019-04-26 20:58:12,281 [salt.state       :1780][INFO    ][14908] Running state [maas.wait_for_machine_status] at time 20:58:12.281451
2019-04-26 20:58:12,282 [salt.state       :1813][INFO    ][14908] Executing state module.run for [maas.wait_for_machine_status]
2019-04-26 20:58:12,283 [salt.utils.decorators:613 ][WARNING ][14908] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 20:58:14,096 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2248.202456s left)
2019-04-26 20:58:17,697 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205817684346
2019-04-26 20:58:17,718 [salt.minion      :1432][INFO    ][14934] Starting a new job with PID 14934
2019-04-26 20:58:17,741 [salt.minion      :1711][INFO    ][14934] Returning information for job: 20190426205817684346
2019-04-26 20:58:45,913 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2216.38511992s left)
2019-04-26 20:58:47,889 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205847876301
2019-04-26 20:58:47,913 [salt.minion      :1432][INFO    ][14965] Starting a new job with PID 14965
2019-04-26 20:58:47,938 [salt.minion      :1711][INFO    ][14965] Returning information for job: 20190426205847876301
2019-04-26 20:59:17,708 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2184.58986998s left)
2019-04-26 20:59:17,921 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205917911861
2019-04-26 20:59:17,940 [salt.minion      :1432][INFO    ][15040] Starting a new job with PID 15040
2019-04-26 20:59:17,961 [salt.minion      :1711][INFO    ][15040] Returning information for job: 20190426205917911861
2019-04-26 20:59:47,952 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426205947943865
2019-04-26 20:59:47,966 [salt.minion      :1432][INFO    ][15066] Starting a new job with PID 15066
2019-04-26 20:59:47,992 [salt.minion      :1711][INFO    ][15066] Returning information for job: 20190426205947943865
2019-04-26 20:59:49,669 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2152.62928987s left)
2019-04-26 21:00:18,145 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210018137791
2019-04-26 21:00:18,167 [salt.minion      :1432][INFO    ][15136] Starting a new job with PID 15136
2019-04-26 21:00:18,189 [salt.minion      :1711][INFO    ][15136] Returning information for job: 20190426210018137791
2019-04-26 21:00:21,492 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2120.80569887s left)
2019-04-26 21:00:48,215 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210048205821
2019-04-26 21:00:48,229 [salt.minion      :1432][INFO    ][15165] Starting a new job with PID 15165
2019-04-26 21:00:48,251 [salt.minion      :1711][INFO    ][15165] Returning information for job: 20190426210048205821
2019-04-26 21:00:53,476 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2088.82161903s left)
2019-04-26 21:01:18,266 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210118259696
2019-04-26 21:01:18,286 [salt.minion      :1432][INFO    ][15238] Starting a new job with PID 15238
2019-04-26 21:01:18,310 [salt.minion      :1711][INFO    ][15238] Returning information for job: 20190426210118259696
2019-04-26 21:01:25,265 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2057.03275394s left)
2019-04-26 21:01:48,336 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210148328303
2019-04-26 21:01:48,355 [salt.minion      :1432][INFO    ][15267] Starting a new job with PID 15267
2019-04-26 21:01:48,381 [salt.minion      :1711][INFO    ][15267] Returning information for job: 20190426210148328303
2019-04-26 21:01:57,256 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2025.04210997s left)
2019-04-26 21:02:18,426 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210218418153
2019-04-26 21:02:18,440 [salt.minion      :1432][INFO    ][15363] Starting a new job with PID 15363
2019-04-26 21:02:18,462 [salt.minion      :1711][INFO    ][15363] Returning information for job: 20190426210218418153
2019-04-26 21:02:29,133 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1993.16497493s left)
2019-04-26 21:02:48,474 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210248466518
2019-04-26 21:02:48,490 [salt.minion      :1432][INFO    ][15396] Starting a new job with PID 15396
2019-04-26 21:02:48,517 [salt.minion      :1711][INFO    ][15396] Returning information for job: 20190426210248466518
2019-04-26 21:03:00,784 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1961.51352286s left)
2019-04-26 21:03:18,485 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210318477381
2019-04-26 21:03:18,498 [salt.minion      :1432][INFO    ][15515] Starting a new job with PID 15515
2019-04-26 21:03:18,518 [salt.minion      :1711][INFO    ][15515] Returning information for job: 20190426210318477381
2019-04-26 21:03:32,469 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1929.82929397s left)
2019-04-26 21:03:48,668 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210348660653
2019-04-26 21:03:48,681 [salt.minion      :1432][INFO    ][15547] Starting a new job with PID 15547
2019-04-26 21:03:48,700 [salt.minion      :1711][INFO    ][15547] Returning information for job: 20190426210348660653
2019-04-26 21:04:04,134 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1898.16424394s left)
2019-04-26 21:04:18,886 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210418879240
2019-04-26 21:04:18,907 [salt.minion      :1432][INFO    ][15762] Starting a new job with PID 15762
2019-04-26 21:04:18,932 [salt.minion      :1711][INFO    ][15762] Returning information for job: 20190426210418879240
2019-04-26 21:04:36,170 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1866.12796593s left)
2019-04-26 21:04:48,979 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210448964180
2019-04-26 21:04:49,002 [salt.minion      :1432][INFO    ][15791] Starting a new job with PID 15791
2019-04-26 21:04:49,027 [salt.minion      :1711][INFO    ][15791] Returning information for job: 20190426210448964180
2019-04-26 21:05:08,111 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1834.18701291s left)
2019-04-26 21:05:19,105 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210519092231
2019-04-26 21:05:19,127 [salt.minion      :1432][INFO    ][15874] Starting a new job with PID 15874
2019-04-26 21:05:19,153 [salt.minion      :1711][INFO    ][15874] Returning information for job: 20190426210519092231
2019-04-26 21:05:39,942 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1802.3556459s left)
2019-04-26 21:05:49,222 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210549212431
2019-04-26 21:05:49,244 [salt.minion      :1432][INFO    ][15904] Starting a new job with PID 15904
2019-04-26 21:05:49,266 [salt.minion      :1711][INFO    ][15904] Returning information for job: 20190426210549212431
2019-04-26 21:06:11,814 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1770.48428798s left)
2019-04-26 21:06:19,385 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210619368061
2019-04-26 21:06:19,403 [salt.minion      :1432][INFO    ][16111] Starting a new job with PID 16111
2019-04-26 21:06:19,427 [salt.minion      :1711][INFO    ][16111] Returning information for job: 20190426210619368061
2019-04-26 21:06:43,661 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1738.63663387s left)
2019-04-26 21:06:49,501 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210649492083
2019-04-26 21:06:49,522 [salt.minion      :1432][INFO    ][16141] Starting a new job with PID 16141
2019-04-26 21:06:49,548 [salt.minion      :1711][INFO    ][16141] Returning information for job: 20190426210649492083
2019-04-26 21:07:15,518 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1706.78020287s left)
2019-04-26 21:07:19,662 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210719648574
2019-04-26 21:07:19,686 [salt.minion      :1432][INFO    ][16265] Starting a new job with PID 16265
2019-04-26 21:07:19,710 [salt.minion      :1711][INFO    ][16265] Returning information for job: 20190426210719648574
2019-04-26 21:07:47,456 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1674.84177494s left)
2019-04-26 21:07:49,809 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210749792927
2019-04-26 21:07:49,832 [salt.minion      :1432][INFO    ][16309] Starting a new job with PID 16309
2019-04-26 21:07:49,857 [salt.minion      :1711][INFO    ][16309] Returning information for job: 20190426210749792927
2019-04-26 21:08:19,398 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1642.90029597s left)
2019-04-26 21:08:19,974 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210819960914
2019-04-26 21:08:19,997 [salt.minion      :1432][INFO    ][16536] Starting a new job with PID 16536
2019-04-26 21:08:20,021 [salt.minion      :1711][INFO    ][16536] Returning information for job: 20190426210819960914
2019-04-26 21:08:50,130 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210850118176
2019-04-26 21:08:50,146 [salt.minion      :1432][INFO    ][16562] Starting a new job with PID 16562
2019-04-26 21:08:50,168 [salt.minion      :1711][INFO    ][16562] Returning information for job: 20190426210850118176
2019-04-26 21:08:51,394 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1610.90445399s left)
2019-04-26 21:09:16,021 [salt.utils.schedule:1377][INFO    ][5512] Running scheduled job: __mine_interval
2019-04-26 21:09:20,290 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210920276575
2019-04-26 21:09:20,313 [salt.minion      :1432][INFO    ][16639] Starting a new job with PID 16639
2019-04-26 21:09:20,338 [salt.minion      :1711][INFO    ][16639] Returning information for job: 20190426210920276575
2019-04-26 21:09:23,406 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1578.89194894s left)
2019-04-26 21:09:50,472 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426210950462151
2019-04-26 21:09:50,492 [salt.minion      :1432][INFO    ][16674] Starting a new job with PID 16674
2019-04-26 21:09:50,515 [salt.minion      :1711][INFO    ][16674] Returning information for job: 20190426210950462151
2019-04-26 21:09:55,013 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1547.28489995s left)
2019-04-26 21:10:20,638 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426211020628756
2019-04-26 21:10:20,659 [salt.minion      :1432][INFO    ][16763] Starting a new job with PID 16763
2019-04-26 21:10:20,684 [salt.minion      :1711][INFO    ][16763] Returning information for job: 20190426211020628756
2019-04-26 21:10:26,999 [salt.loaded.ext.module.maas:1023][INFO    ][14908] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1515.29850793s left)
2019-04-26 21:10:50,744 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426211050732528
2019-04-26 21:10:50,762 [salt.minion      :1432][INFO    ][16842] Starting a new job with PID 16842
2019-04-26 21:10:50,797 [salt.minion      :1711][INFO    ][16842] Returning information for job: 20190426211050732528
2019-04-26 21:10:58,728 [salt.state       :300 ][INFO    ][14908] {'ret': True}
2019-04-26 21:10:58,728 [salt.state       :1951][INFO    ][14908] Completed state [maas.wait_for_machine_status] at time 21:10:58.728528 duration_in_ms=766447.077
2019-04-26 21:10:58,730 [salt.minion      :1711][INFO    ][14908] Returning information for job: 20190426205802597097
2019-04-26 22:01:55,847 [salt.minion      :1308][INFO    ][5512] User sudo_ubuntu Executing command cp.push_dir with jid 20190426220155838429
2019-04-26 22:01:55,869 [salt.minion      :1432][INFO    ][22062] Starting a new job with PID 22062
