2019-04-19 20:04:58,153 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-19 20:05:48,209 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-19 20:06:38,249 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-19 20:07:28,284 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-19 20:08:18,330 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-19 20:10:31,783 [salt.utils.decorators:613 ][WARNING ][2892] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:10:32,486 [salt.utils.decorators:613 ][WARNING ][2892] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:10:35,107 [salt.loaded.int.states.file:2298][WARNING ][3026] 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-19 20:10:47,142 [salt.state       :2022][WARNING ][3141] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-19 20:10:50,095 [salt.utils.decorators:613 ][WARNING ][3141] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:11:01,032 [salt.utils.decorators:613 ][WARNING ][3141] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:11:27,886 [salt.utils.decorators:613 ][WARNING ][3141] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:11:28,749 [salt.utils.decorators:613 ][WARNING ][3141] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:11:30,558 [salt.loaded.ext.module.maasng:1008][WARNING ][3141] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-19 20:11:30,559 [salt.loaded.ext.module.maasng:1011][WARNING ][3141] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-19 20:11:31,105 [salt.loaded.ext.module.maasng:1235][WARNING ][3141] Ignoring parameter vlan:0
2019-04-19 20:11:31,867 [salt.utils.decorators:613 ][WARNING ][3141] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:11:40,792 [salt.utils.decorators:613 ][WARNING ][5618] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:11:40,848 [salt.loaded.ext.module.maas:412 ][WARNING ][5618] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-19 20:11:42,093 [salt.loaded.ext.module.maas:412 ][WARNING ][5618] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-19 20:11:43,195 [salt.loaded.ext.module.maas:412 ][WARNING ][5618] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-19 20:11:44,240 [salt.loaded.ext.module.maas:412 ][WARNING ][5618] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-19 20:11:48,442 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6133] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-19 20:11:48,474 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6133] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-19 20:11:48,512 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-04-19 20:11:49,496 [salt.cli.daemons :293 ][INFO    ][6196] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2019-04-19 20:11:49,612 [salt.cli.daemons :82  ][INFO    ][6196] Starting up the Salt Minion
2019-04-19 20:11:49,613 [salt.utils.event :1017][INFO    ][6196] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2019-04-19 20:11:50,796 [salt.minion      :976 ][INFO    ][6196] Creating minion process manager
2019-04-19 20:11:52,623 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6196] Executing command ['date', '+%z'] in directory '/root'
2019-04-19 20:11:52,648 [salt.utils.schedule:568 ][INFO    ][6196] Updating job settings for scheduled job: __mine_interval
2019-04-19 20:11:52,650 [salt.minion      :1108][INFO    ][6196] Added mine.update to scheduler
2019-04-19 20:11:52,654 [salt.minion      :1975][INFO    ][6196] Minion is starting as user 'root'
2019-04-19 20:11:52,670 [salt.minion      :2336][INFO    ][6196] Minion is ready to receive requests!
2019-04-19 20:12:16,429 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command state.apply with jid 20190419201216420861
2019-04-19 20:12:16,448 [salt.minion      :1432][INFO    ][6317] Starting a new job with PID 6317
2019-04-19 20:12:22,182 [salt.state       :915 ][INFO    ][6317] Loading fresh modules for state activity
2019-04-19 20:12:22,234 [salt.fileclient  :1219][INFO    ][6317] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-19 20:12:22,283 [salt.state       :1780][INFO    ][6317] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:12:22.283736
2019-04-19 20:12:22,284 [salt.state       :1813][INFO    ][6317] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-19 20:12:22,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6317] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-19 20:12:24,082 [salt.state       :300 ][INFO    ][6317] {'pid': 6326, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-19 20:12:24,083 [salt.state       :1951][INFO    ][6317] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:12:24.083396 duration_in_ms=1799.661
2019-04-19 20:12:24,086 [salt.state       :1780][INFO    ][6317] Running state [maas.wait_for_machine_status] at time 20:12:24.086054
2019-04-19 20:12:24,086 [salt.state       :1813][INFO    ][6317] Executing state module.run for [maas.wait_for_machine_status]
2019-04-19 20:12:24,088 [salt.utils.decorators:613 ][WARNING ][6317] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:12:24,673 [salt.loaded.ext.module.maas:1023][INFO    ][6317] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.4280138s left)
2019-04-19 20:12:31,474 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201231467872
2019-04-19 20:12:31,496 [salt.minion      :1432][INFO    ][6337] Starting a new job with PID 6337
2019-04-19 20:12:31,516 [salt.minion      :1711][INFO    ][6337] Returning information for job: 20190419201231467872
2019-04-19 20:12:55,413 [salt.loaded.ext.module.maas:1023][INFO    ][6317] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.68766499s left)
2019-04-19 20:13:01,669 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201301653860
2019-04-19 20:13:01,690 [salt.minion      :1432][INFO    ][6413] Starting a new job with PID 6413
2019-04-19 20:13:01,711 [salt.minion      :1711][INFO    ][6413] Returning information for job: 20190419201301653860
2019-04-19 20:13:25,989 [salt.loaded.ext.module.maas:1023][INFO    ][6317] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1438.11180592s left)
2019-04-19 20:13:31,854 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201331844932
2019-04-19 20:13:31,871 [salt.minion      :1432][INFO    ][6433] Starting a new job with PID 6433
2019-04-19 20:13:31,892 [salt.minion      :1711][INFO    ][6433] Returning information for job: 20190419201331844932
2019-04-19 20:13:56,681 [salt.loaded.ext.module.maas:1023][INFO    ][6317] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.420398s left)
2019-04-19 20:14:01,928 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201401913808
2019-04-19 20:14:01,949 [salt.minion      :1432][INFO    ][6511] Starting a new job with PID 6511
2019-04-19 20:14:01,970 [salt.minion      :1711][INFO    ][6511] Returning information for job: 20190419201401913808
2019-04-19 20:14:27,444 [salt.loaded.ext.module.maas:1023][INFO    ][6317] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.65662503s left)
2019-04-19 20:14:31,980 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201431970220
2019-04-19 20:14:32,000 [salt.minion      :1432][INFO    ][6557] Starting a new job with PID 6557
2019-04-19 20:14:32,021 [salt.minion      :1711][INFO    ][6557] Returning information for job: 20190419201431970220
2019-04-19 20:14:58,228 [salt.loaded.ext.module.maas:1023][INFO    ][6317] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1345.87279201s left)
2019-04-19 20:15:02,054 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201502037517
2019-04-19 20:15:02,073 [salt.minion      :1432][INFO    ][6758] Starting a new job with PID 6758
2019-04-19 20:15:02,098 [salt.minion      :1711][INFO    ][6758] Returning information for job: 20190419201502037517
2019-04-19 20:15:29,017 [salt.loaded.ext.module.maas:1023][INFO    ][6317] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1315.08401489s left)
2019-04-19 20:15:32,125 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201532116316
2019-04-19 20:15:32,144 [salt.minion      :1432][INFO    ][6826] Starting a new job with PID 6826
2019-04-19 20:15:32,166 [salt.minion      :1711][INFO    ][6826] Returning information for job: 20190419201532116316
2019-04-19 20:15:59,900 [salt.loaded.ext.module.maas:1023][INFO    ][6317] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1284.20053196s left)
2019-04-19 20:16:02,230 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201602222624
2019-04-19 20:16:02,245 [salt.minion      :1432][INFO    ][7162] Starting a new job with PID 7162
2019-04-19 20:16:02,266 [salt.minion      :1711][INFO    ][7162] Returning information for job: 20190419201602222624
2019-04-19 20:16:30,781 [salt.loaded.ext.module.maas:1023][INFO    ][6317] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1253.31975293s left)
2019-04-19 20:16:32,281 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201632274817
2019-04-19 20:16:32,300 [salt.minion      :1432][INFO    ][7227] Starting a new job with PID 7227
2019-04-19 20:16:32,324 [salt.minion      :1711][INFO    ][7227] Returning information for job: 20190419201632274817
2019-04-19 20:17:02,248 [salt.loaded.ext.module.maas:1023][INFO    ][6317] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1221.85254002s left)
2019-04-19 20:17:02,324 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201702313826
2019-04-19 20:17:02,339 [salt.minion      :1432][INFO    ][7571] Starting a new job with PID 7571
2019-04-19 20:17:02,363 [salt.minion      :1711][INFO    ][7571] Returning information for job: 20190419201702313826
2019-04-19 20:17:32,514 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201732505928
2019-04-19 20:17:32,530 [salt.minion      :1432][INFO    ][7680] Starting a new job with PID 7680
2019-04-19 20:17:32,552 [salt.minion      :1711][INFO    ][7680] Returning information for job: 20190419201732505928
2019-04-19 20:17:33,780 [salt.loaded.ext.module.maas:1023][INFO    ][6317] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1190.321033s left)
2019-04-19 20:18:02,722 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201802709577
2019-04-19 20:18:02,736 [salt.minion      :1432][INFO    ][7852] Starting a new job with PID 7852
2019-04-19 20:18:02,761 [salt.minion      :1711][INFO    ][7852] Returning information for job: 20190419201802709577
2019-04-19 20:18:05,341 [salt.loaded.ext.module.maas:1023][INFO    ][6317] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1158.76006794s left)
2019-04-19 20:18:32,745 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201832737911
2019-04-19 20:18:32,770 [salt.minion      :1432][INFO    ][7958] Starting a new job with PID 7958
2019-04-19 20:18:32,803 [salt.minion      :1711][INFO    ][7958] Returning information for job: 20190419201832737911
2019-04-19 20:18:37,474 [salt.state       :300 ][INFO    ][6317] {'ret': True}
2019-04-19 20:18:37,474 [salt.state       :1951][INFO    ][6317] Completed state [maas.wait_for_machine_status] at time 20:18:37.474375 duration_in_ms=373388.321
2019-04-19 20:18:37,477 [salt.minion      :1711][INFO    ][6317] Returning information for job: 20190419201216420861
2019-04-19 20:18:38,039 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command state.apply with jid 20190419201838028061
2019-04-19 20:18:38,061 [salt.minion      :1432][INFO    ][8004] Starting a new job with PID 8004
2019-04-19 20:18:43,818 [salt.state       :915 ][INFO    ][8004] Loading fresh modules for state activity
2019-04-19 20:18:43,868 [salt.fileclient  :1219][INFO    ][8004] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-19 20:18:43,960 [salt.state       :1780][INFO    ][8004] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:18:43.960081
2019-04-19 20:18:43,960 [salt.state       :1813][INFO    ][8004] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-19 20:18:43,962 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8004] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-19 20:18:45,835 [salt.state       :300 ][INFO    ][8004] {'pid': 8012, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-19 20:18:45,836 [salt.state       :1951][INFO    ][8004] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:18:45.835953 duration_in_ms=1875.873
2019-04-19 20:18:45,837 [salt.state       :1780][INFO    ][8004] Running state [maas_machines_storage_cmp002_lvm] at time 20:18:45.837774
2019-04-19 20:18:45,838 [salt.state       :1813][INFO    ][8004] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-19 20:18:46,905 [salt.loaded.ext.module.maasng:610 ][INFO    ][8004] 7m7f38
2019-04-19 20:18:46,905 [salt.loaded.ext.module.maasng:626 ][INFO    ][8004] sda
2019-04-19 20:18:47,381 [salt.loaded.ext.module.maasng:361 ][INFO    ][8004] 7m7f38
2019-04-19 20:18:47,468 [salt.loaded.ext.module.maasng:367 ][INFO    ][8004] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'3ada94e8-7fb5-441c-b1cb-a9ce35528638', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7m7f38', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'93fd0bfc-ea38-4439-8cb5-75316b4f2ed8', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/blockdevices/1/partition/1'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'7m7f38', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/blockdevices/3/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'4f9fa724-e7d7-4b73-9914-cea72bf2677e', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7m7f38', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'c1f2d712-cca4-4f65-8bd5-f482e25341bf', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'size': 800097042432}]
2019-04-19 20:18:47,468 [salt.loaded.ext.module.maasng:632 ][INFO    ][8004] vgroot
2019-04-19 20:18:47,469 [salt.loaded.ext.module.maasng:635 ][INFO    ][8004] lvroot
2019-04-19 20:18:47,469 [salt.loaded.ext.module.maasng:639 ][INFO    ][8004] 107374182400
2019-04-19 20:18:48,041 [salt.loaded.ext.module.maasng:645 ][INFO    ][8004] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'children': [], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 16, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', 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'params': u'', u'effective_mtu': 1500, u'system_id': u'7m7f38', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'owner_data': {}, u'system_id': u'7m7f38', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/blockdevices/9/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'05e34463-6837-46bf-a169-74e307b2cfe2', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7m7f38', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'5f038297-8a00-4206-88c1-427d5c8426dd', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 9, u'size': 107374182400}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'5ae2f102-5da3-47c3-82ec-c4c3bff32d8f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7m7f38', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'b84d7a37-7fb5-4ff2-abf9-88f9176ffffc', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/blockdevices/1/partition/5'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'7m7f38', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'7m7f38', u'id': 5}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'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'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'5ae2f102-5da3-47c3-82ec-c4c3bff32d8f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7m7f38', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'b84d7a37-7fb5-4ff2-abf9-88f9176ffffc', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/blockdevices/1/partition/5'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'7m7f38', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'size': 800109715456}, u'interface_set': [{u'children': [], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 16, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', 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'params': u'', u'effective_mtu': 1500, u'system_id': u'7m7f38', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/interfaces/5/'}, {u'children': [], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 17, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', 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'params': u'', u'effective_mtu': 1500, u'system_id': u'7m7f38', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/interfaces/14/'}, {u'children': [], u'name': u'ens2f0', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'system_id': u'7m7f38', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/interfaces/11/'}, {u'children': [], u'name': u'ens2f1', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'system_id': u'7m7f38', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/interfaces/12/'}, {u'children': [], u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'system_id': u'7m7f38', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/interfaces/10/'}, {u'children': [], u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'system_id': u'7m7f38', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/interfaces/13/'}], u'address_ttl': None, u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'5ae2f102-5da3-47c3-82ec-c4c3bff32d8f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7m7f38', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'b84d7a37-7fb5-4ff2-abf9-88f9176ffffc', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/blockdevices/1/partition/5'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'7m7f38', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7m7f38/blockdevices/9/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'05e34463-6837-46bf-a169-74e307b2cfe2', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7m7f38', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'5f038297-8a00-4206-88c1-427d5c8426dd', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 9, u'size': 107374182400}], u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/7m7f38/'}
2019-04-19 20:18:48,043 [salt.state       :300 ][INFO    ][8004] {'new': {'storage_layout': 'lvm'}}
2019-04-19 20:18:48,044 [salt.state       :1951][INFO    ][8004] Completed state [maas_machines_storage_cmp002_lvm] at time 20:18:48.043981 duration_in_ms=2206.206
2019-04-19 20:18:48,044 [salt.state       :1780][INFO    ][8004] Running state [maas_machines_storage_cmp001_lvm] at time 20:18:48.044607
2019-04-19 20:18:48,045 [salt.state       :1813][INFO    ][8004] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-19 20:18:48,986 [salt.loaded.ext.module.maasng:610 ][INFO    ][8004] hbyp7h
2019-04-19 20:18:48,988 [salt.loaded.ext.module.maasng:626 ][INFO    ][8004] sda
2019-04-19 20:18:49,445 [salt.loaded.ext.module.maasng:361 ][INFO    ][8004] hbyp7h
2019-04-19 20:18:49,533 [salt.loaded.ext.module.maasng:367 ][INFO    ][8004] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'4a3d3c80-11e6-41d6-b404-107497cac420', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hbyp7h', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'eefc3056-6ddb-4025-9229-2a4310a9f183', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/blockdevices/2/partition/2'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hbyp7h', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/blockdevices/4/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'90a4ae7b-d570-4644-8c28-21be282c6b1a', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hbyp7h', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'117a14c4-6c77-46f4-abc1-88c808b41115', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'size': 800097042432}]
2019-04-19 20:18:49,534 [salt.loaded.ext.module.maasng:632 ][INFO    ][8004] vgroot
2019-04-19 20:18:49,534 [salt.loaded.ext.module.maasng:635 ][INFO    ][8004] lvroot
2019-04-19 20:18:49,534 [salt.loaded.ext.module.maasng:639 ][INFO    ][8004] 107374182400
2019-04-19 20:18:50,142 [salt.loaded.ext.module.maasng:645 ][INFO    ][8004] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'children': [], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 22, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', 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'params': u'', u'effective_mtu': 1500, u'system_id': u'hbyp7h', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/interfaces/6/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'current_commissioning_result_id': 6, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'owner_data': {}, u'system_id': u'hbyp7h', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/blockdevices/10/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'8b0ffa2c-0f5e-4e6c-a8d6-af78ffcc83cf', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hbyp7h', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'7468ecba-ebdc-437f-b43f-6cf5898e6e06', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 10, u'size': 107374182400}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'a5576dce-99b0-4d6c-8947-f1c600b4a473', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hbyp7h', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'889bcb90-cb5f-4535-8cbb-632ee0827e2e', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/blockdevices/2/partition/6'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hbyp7h', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hbyp7h', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 7, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'a5576dce-99b0-4d6c-8947-f1c600b4a473', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hbyp7h', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'889bcb90-cb5f-4535-8cbb-632ee0827e2e', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/blockdevices/2/partition/6'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hbyp7h', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, u'interface_set': [{u'children': [], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 22, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', 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'params': u'', u'effective_mtu': 1500, u'system_id': u'hbyp7h', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/interfaces/6/'}, {u'children': [], u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'effective_mtu': 1500, u'system_id': u'hbyp7h', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/interfaces/15/'}, {u'children': [], u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'effective_mtu': 1500, u'system_id': u'hbyp7h', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/interfaces/17/'}, {u'children': [], u'name': u'ens2f0', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'effective_mtu': 1500, u'system_id': u'hbyp7h', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/interfaces/16/'}, {u'children': [], u'name': u'ens2f1', u'links': [{u'id': 24, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'effective_mtu': 1500, u'system_id': u'hbyp7h', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/interfaces/18/'}, {u'children': [], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', 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.43'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'effective_mtu': 1500, u'system_id': u'hbyp7h', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/interfaces/19/'}], u'address_ttl': None, u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'a5576dce-99b0-4d6c-8947-f1c600b4a473', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hbyp7h', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'889bcb90-cb5f-4535-8cbb-632ee0827e2e', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/blockdevices/2/partition/6'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hbyp7h', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hbyp7h/blockdevices/10/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'8b0ffa2c-0f5e-4e6c-a8d6-af78ffcc83cf', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hbyp7h', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'7468ecba-ebdc-437f-b43f-6cf5898e6e06', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 10, u'size': 107374182400}], u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/hbyp7h/'}
2019-04-19 20:18:50,145 [salt.state       :300 ][INFO    ][8004] {'new': {'storage_layout': 'lvm'}}
2019-04-19 20:18:50,145 [salt.state       :1951][INFO    ][8004] Completed state [maas_machines_storage_cmp001_lvm] at time 20:18:50.145371 duration_in_ms=2100.764
2019-04-19 20:18:50,148 [salt.minion      :1711][INFO    ][8004] Returning information for job: 20190419201838028061
2019-04-19 20:18:50,808 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command state.apply with jid 20190419201850796456
2019-04-19 20:18:50,832 [salt.minion      :1432][INFO    ][8105] Starting a new job with PID 8105
2019-04-19 20:18:51,677 [salt.state       :915 ][INFO    ][8105] Loading fresh modules for state activity
2019-04-19 20:18:51,727 [salt.fileclient  :1219][INFO    ][8105] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-19 20:18:51,772 [salt.state       :1780][INFO    ][8105] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:18:51.772258
2019-04-19 20:18:51,772 [salt.state       :1813][INFO    ][8105] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-19 20:18:51,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8105] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-19 20:18:53,600 [salt.state       :300 ][INFO    ][8105] {'pid': 8112, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-19 20:18:53,601 [salt.state       :1951][INFO    ][8105] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:18:53.601799 duration_in_ms=1829.541
2019-04-19 20:18:53,605 [salt.state       :1780][INFO    ][8105] Running state [maas.deploy_machines] at time 20:18:53.605336
2019-04-19 20:18:53,605 [salt.state       :1813][INFO    ][8105] Executing state module.run for [maas.deploy_machines]
2019-04-19 20:18:53,606 [salt.utils.decorators:613 ][WARNING ][8105] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:18:54,088 [salt.loaded.ext.module.maas:684 ][INFO    ][8105] deploymachines hwe_kernel=hwe-16.04 system_id=tbqckf distro_series=xenial
2019-04-19 20:18:56,457 [salt.loaded.ext.module.maas:684 ][INFO    ][8105] deploymachines hwe_kernel=hwe-16.04 system_id=7m7f38 distro_series=xenial
2019-04-19 20:18:58,776 [salt.loaded.ext.module.maas:684 ][INFO    ][8105] deploymachines hwe_kernel=hwe-16.04 system_id=hbyp7h distro_series=xenial
2019-04-19 20:19:01,136 [salt.loaded.ext.module.maas:684 ][INFO    ][8105] deploymachines hwe_kernel=hwe-16.04 system_id=nsxrga distro_series=xenial
2019-04-19 20:19:03,488 [salt.state       :300 ][INFO    ][8105] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-04-19 20:19:03,489 [salt.state       :1951][INFO    ][8105] Completed state [maas.deploy_machines] at time 20:19:03.489121 duration_in_ms=9883.785
2019-04-19 20:19:03,492 [salt.minion      :1711][INFO    ][8105] Returning information for job: 20190419201850796456
2019-04-19 20:19:04,056 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command state.apply with jid 20190419201904048877
2019-04-19 20:19:04,075 [salt.minion      :1432][INFO    ][8329] Starting a new job with PID 8329
2019-04-19 20:19:09,932 [salt.state       :915 ][INFO    ][8329] Loading fresh modules for state activity
2019-04-19 20:19:09,984 [salt.fileclient  :1219][INFO    ][8329] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-19 20:19:10,029 [salt.state       :1780][INFO    ][8329] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:10.029453
2019-04-19 20:19:10,029 [salt.state       :1813][INFO    ][8329] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-19 20:19:10,031 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8329] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-19 20:19:11,829 [salt.state       :300 ][INFO    ][8329] {'pid': 8351, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-19 20:19:11,830 [salt.state       :1951][INFO    ][8329] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:11.830556 duration_in_ms=1801.103
2019-04-19 20:19:11,833 [salt.state       :1780][INFO    ][8329] Running state [maas.wait_for_machine_status] at time 20:19:11.833356
2019-04-19 20:19:11,834 [salt.state       :1813][INFO    ][8329] Executing state module.run for [maas.wait_for_machine_status]
2019-04-19 20:19:11,835 [salt.utils.decorators:613 ][WARNING ][8329] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:19:13,754 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2248.09433007s left)
2019-04-19 20:19:19,089 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201919081941
2019-04-19 20:19:19,112 [salt.minion      :1432][INFO    ][8361] Starting a new job with PID 8361
2019-04-19 20:19:19,134 [salt.minion      :1711][INFO    ][8361] Returning information for job: 20190419201919081941
2019-04-19 20:19:45,525 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2216.32337904s left)
2019-04-19 20:19:49,147 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419201949135758
2019-04-19 20:19:49,162 [salt.minion      :1432][INFO    ][8416] Starting a new job with PID 8416
2019-04-19 20:19:49,181 [salt.minion      :1711][INFO    ][8416] Returning information for job: 20190419201949135758
2019-04-19 20:20:17,563 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2184.28573108s left)
2019-04-19 20:20:19,199 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202019191910
2019-04-19 20:20:19,219 [salt.minion      :1432][INFO    ][8472] Starting a new job with PID 8472
2019-04-19 20:20:19,240 [salt.minion      :1711][INFO    ][8472] Returning information for job: 20190419202019191910
2019-04-19 20:20:49,258 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202049248380
2019-04-19 20:20:49,278 [salt.minion      :1432][INFO    ][8536] Starting a new job with PID 8536
2019-04-19 20:20:49,298 [salt.minion      :1711][INFO    ][8536] Returning information for job: 20190419202049248380
2019-04-19 20:20:49,456 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2152.39209414s left)
2019-04-19 20:21:19,439 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202119431502
2019-04-19 20:21:19,462 [salt.minion      :1432][INFO    ][8588] Starting a new job with PID 8588
2019-04-19 20:21:19,482 [salt.minion      :1711][INFO    ][8588] Returning information for job: 20190419202119431502
2019-04-19 20:21:21,521 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2120.32795s left)
2019-04-19 20:21:49,501 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202149486050
2019-04-19 20:21:49,522 [salt.minion      :1432][INFO    ][8660] Starting a new job with PID 8660
2019-04-19 20:21:49,544 [salt.minion      :1711][INFO    ][8660] Returning information for job: 20190419202149486050
2019-04-19 20:21:53,540 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2088.3086071s left)
2019-04-19 20:22:19,711 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202219695620
2019-04-19 20:22:19,736 [salt.minion      :1432][INFO    ][8753] Starting a new job with PID 8753
2019-04-19 20:22:19,758 [salt.minion      :1711][INFO    ][8753] Returning information for job: 20190419202219695620
2019-04-19 20:22:25,577 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2056.27201509s left)
2019-04-19 20:22:49,907 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202249897218
2019-04-19 20:22:49,930 [salt.minion      :1432][INFO    ][8839] Starting a new job with PID 8839
2019-04-19 20:22:49,951 [salt.minion      :1711][INFO    ][8839] Returning information for job: 20190419202249897218
2019-04-19 20:22:57,421 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2024.4271071s left)
2019-04-19 20:23:19,936 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202319928690
2019-04-19 20:23:19,951 [salt.minion      :1432][INFO    ][9123] Starting a new job with PID 9123
2019-04-19 20:23:19,970 [salt.minion      :1711][INFO    ][9123] Returning information for job: 20190419202319928690
2019-04-19 20:23:29,326 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1992.52281713s left)
2019-04-19 20:23:50,121 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202350112426
2019-04-19 20:23:50,146 [salt.minion      :1432][INFO    ][9210] Starting a new job with PID 9210
2019-04-19 20:23:50,170 [salt.minion      :1711][INFO    ][9210] Returning information for job: 20190419202350112426
2019-04-19 20:24:01,307 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1960.54117703s left)
2019-04-19 20:24:20,232 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202420218996
2019-04-19 20:24:20,253 [salt.minion      :1432][INFO    ][9392] Starting a new job with PID 9392
2019-04-19 20:24:20,280 [salt.minion      :1711][INFO    ][9392] Returning information for job: 20190419202420218996
2019-04-19 20:24:33,382 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1928.46670699s left)
2019-04-19 20:24:50,274 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202450258751
2019-04-19 20:24:50,294 [salt.minion      :1432][INFO    ][9500] Starting a new job with PID 9500
2019-04-19 20:24:50,325 [salt.minion      :1711][INFO    ][9500] Returning information for job: 20190419202450258751
2019-04-19 20:25:05,409 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1896.43991113s left)
2019-04-19 20:25:20,329 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202520316618
2019-04-19 20:25:20,349 [salt.minion      :1432][INFO    ][9790] Starting a new job with PID 9790
2019-04-19 20:25:20,372 [salt.minion      :1711][INFO    ][9790] Returning information for job: 20190419202520316618
2019-04-19 20:25:37,482 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1864.36683893s left)
2019-04-19 20:25:50,449 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202550437045
2019-04-19 20:25:50,474 [salt.minion      :1432][INFO    ][9906] Starting a new job with PID 9906
2019-04-19 20:25:50,506 [salt.minion      :1711][INFO    ][9906] Returning information for job: 20190419202550437045
2019-04-19 20:26:09,408 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1832.44042993s left)
2019-04-19 20:26:20,626 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202620616241
2019-04-19 20:26:20,641 [salt.minion      :1432][INFO    ][10225] Starting a new job with PID 10225
2019-04-19 20:26:20,664 [salt.minion      :1711][INFO    ][10225] Returning information for job: 20190419202620616241
2019-04-19 20:26:41,457 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1800.391186s left)
2019-04-19 20:26:50,736 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202650722429
2019-04-19 20:26:50,762 [salt.minion      :1432][INFO    ][10357] Starting a new job with PID 10357
2019-04-19 20:26:50,797 [salt.minion      :1711][INFO    ][10357] Returning information for job: 20190419202650722429
2019-04-19 20:27:13,385 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1768.46395206s left)
2019-04-19 20:27:20,853 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202720839484
2019-04-19 20:27:20,876 [salt.minion      :1432][INFO    ][10489] Starting a new job with PID 10489
2019-04-19 20:27:20,896 [salt.minion      :1711][INFO    ][10489] Returning information for job: 20190419202720839484
2019-04-19 20:27:45,261 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1736.58793306s left)
2019-04-19 20:27:50,983 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202750970139
2019-04-19 20:27:51,002 [salt.minion      :1432][INFO    ][10639] Starting a new job with PID 10639
2019-04-19 20:27:51,026 [salt.minion      :1711][INFO    ][10639] Returning information for job: 20190419202750970139
2019-04-19 20:28:17,102 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1704.74699211s left)
2019-04-19 20:28:21,136 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202821121364
2019-04-19 20:28:21,155 [salt.minion      :1432][INFO    ][10699] Starting a new job with PID 10699
2019-04-19 20:28:21,177 [salt.minion      :1711][INFO    ][10699] Returning information for job: 20190419202821121364
2019-04-19 20:28:49,033 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1672.81519103s left)
2019-04-19 20:28:51,239 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202851226751
2019-04-19 20:28:51,264 [salt.minion      :1432][INFO    ][10898] Starting a new job with PID 10898
2019-04-19 20:28:51,300 [salt.minion      :1711][INFO    ][10898] Returning information for job: 20190419202851226751
2019-04-19 20:29:21,086 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1640.7622931s left)
2019-04-19 20:29:21,391 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202921378588
2019-04-19 20:29:21,413 [salt.minion      :1432][INFO    ][10963] Starting a new job with PID 10963
2019-04-19 20:29:21,438 [salt.minion      :1711][INFO    ][10963] Returning information for job: 20190419202921378588
2019-04-19 20:29:51,562 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419202951551929
2019-04-19 20:29:51,597 [salt.minion      :1432][INFO    ][11154] Starting a new job with PID 11154
2019-04-19 20:29:51,620 [salt.minion      :1711][INFO    ][11154] Returning information for job: 20190419202951551929
2019-04-19 20:29:53,260 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1608.58898091s left)
2019-04-19 20:30:21,741 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203021728119
2019-04-19 20:30:21,764 [salt.minion      :1432][INFO    ][11175] Starting a new job with PID 11175
2019-04-19 20:30:21,786 [salt.minion      :1711][INFO    ][11175] Returning information for job: 20190419203021728119
2019-04-19 20:30:25,449 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1576.39942503s left)
2019-04-19 20:30:51,888 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203051877220
2019-04-19 20:30:51,910 [salt.minion      :1432][INFO    ][11394] Starting a new job with PID 11394
2019-04-19 20:30:51,934 [salt.minion      :1711][INFO    ][11394] Returning information for job: 20190419203051877220
2019-04-19 20:30:57,416 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1544.43258595s left)
2019-04-19 20:31:22,057 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203122044809
2019-04-19 20:31:22,078 [salt.minion      :1432][INFO    ][11422] Starting a new job with PID 11422
2019-04-19 20:31:22,101 [salt.minion      :1711][INFO    ][11422] Returning information for job: 20190419203122044809
2019-04-19 20:31:29,304 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1512.54447103s left)
2019-04-19 20:31:52,153 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203152145620
2019-04-19 20:31:52,173 [salt.minion      :1432][INFO    ][11592] Starting a new job with PID 11592
2019-04-19 20:31:52,194 [salt.minion      :1711][INFO    ][11592] Returning information for job: 20190419203152145620
2019-04-19 20:32:01,446 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1480.4025321s left)
2019-04-19 20:32:22,354 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203222338757
2019-04-19 20:32:22,376 [salt.minion      :1432][INFO    ][11625] Starting a new job with PID 11625
2019-04-19 20:32:22,401 [salt.minion      :1711][INFO    ][11625] Returning information for job: 20190419203222338757
2019-04-19 20:32:33,464 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1448.3841331s left)
2019-04-19 20:32:52,572 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203252564609
2019-04-19 20:32:52,586 [salt.minion      :1432][INFO    ][11786] Starting a new job with PID 11786
2019-04-19 20:32:52,607 [salt.minion      :1711][INFO    ][11786] Returning information for job: 20190419203252564609
2019-04-19 20:33:05,372 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1416.47651005s left)
2019-04-19 20:33:22,777 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203322764323
2019-04-19 20:33:22,798 [salt.minion      :1432][INFO    ][11805] Starting a new job with PID 11805
2019-04-19 20:33:22,819 [salt.minion      :1711][INFO    ][11805] Returning information for job: 20190419203322764323
2019-04-19 20:33:37,328 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1384.52074099s left)
2019-04-19 20:33:52,797 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203352789771
2019-04-19 20:33:52,819 [salt.minion      :1432][INFO    ][11888] Starting a new job with PID 11888
2019-04-19 20:33:52,842 [salt.minion      :1711][INFO    ][11888] Returning information for job: 20190419203352789771
2019-04-19 20:34:09,228 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1352.62058091s left)
2019-04-19 20:34:22,816 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203422802816
2019-04-19 20:34:22,837 [salt.minion      :1432][INFO    ][11907] Starting a new job with PID 11907
2019-04-19 20:34:22,860 [salt.minion      :1711][INFO    ][11907] Returning information for job: 20190419203422802816
2019-04-19 20:34:41,286 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1320.56275201s left)
2019-04-19 20:34:52,855 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203452840088
2019-04-19 20:34:52,875 [salt.minion      :1432][INFO    ][11997] Starting a new job with PID 11997
2019-04-19 20:34:52,901 [salt.minion      :1711][INFO    ][11997] Returning information for job: 20190419203452840088
2019-04-19 20:35:13,125 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1288.72315407s left)
2019-04-19 20:35:23,002 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203522985284
2019-04-19 20:35:23,025 [salt.minion      :1432][INFO    ][12016] Starting a new job with PID 12016
2019-04-19 20:35:23,048 [salt.minion      :1711][INFO    ][12016] Returning information for job: 20190419203522985284
2019-04-19 20:35:44,982 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1256.86667609s left)
2019-04-19 20:35:53,082 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203553067711
2019-04-19 20:35:53,101 [salt.minion      :1432][INFO    ][12098] Starting a new job with PID 12098
2019-04-19 20:35:53,123 [salt.minion      :1711][INFO    ][12098] Returning information for job: 20190419203553067711
2019-04-19 20:36:17,000 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1224.84807014s left)
2019-04-19 20:36:23,136 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203623121679
2019-04-19 20:36:23,158 [salt.minion      :1432][INFO    ][12124] Starting a new job with PID 12124
2019-04-19 20:36:23,184 [salt.minion      :1711][INFO    ][12124] Returning information for job: 20190419203623121679
2019-04-19 20:36:48,818 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1193.03027391s left)
2019-04-19 20:36:53,240 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203653225721
2019-04-19 20:36:53,264 [salt.minion      :1432][INFO    ][12205] Starting a new job with PID 12205
2019-04-19 20:36:53,288 [salt.minion      :1711][INFO    ][12205] Returning information for job: 20190419203653225721
2019-04-19 20:37:20,658 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1161.19072413s left)
2019-04-19 20:37:23,323 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203723308795
2019-04-19 20:37:23,342 [salt.minion      :1432][INFO    ][12229] Starting a new job with PID 12229
2019-04-19 20:37:23,366 [salt.minion      :1711][INFO    ][12229] Returning information for job: 20190419203723308795
2019-04-19 20:37:52,477 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1129.37125492s left)
2019-04-19 20:37:53,448 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203753432548
2019-04-19 20:37:53,467 [salt.minion      :1432][INFO    ][12311] Starting a new job with PID 12311
2019-04-19 20:37:53,489 [salt.minion      :1711][INFO    ][12311] Returning information for job: 20190419203753432548
2019-04-19 20:38:23,561 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203823551744
2019-04-19 20:38:23,577 [salt.minion      :1432][INFO    ][12330] Starting a new job with PID 12330
2019-04-19 20:38:23,599 [salt.minion      :1711][INFO    ][12330] Returning information for job: 20190419203823551744
2019-04-19 20:38:24,126 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1097.72225904s left)
2019-04-19 20:38:53,600 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203853592634
2019-04-19 20:38:53,626 [salt.minion      :1432][INFO    ][12408] Starting a new job with PID 12408
2019-04-19 20:38:53,650 [salt.minion      :1711][INFO    ][12408] Returning information for job: 20190419203853592634
2019-04-19 20:38:55,756 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1066.0927341s left)
2019-04-19 20:39:23,665 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203923659201
2019-04-19 20:39:23,678 [salt.minion      :1432][INFO    ][12428] Starting a new job with PID 12428
2019-04-19 20:39:23,701 [salt.minion      :1711][INFO    ][12428] Returning information for job: 20190419203923659201
2019-04-19 20:39:27,321 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1034.52724314s left)
2019-04-19 20:39:53,802 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419203953794799
2019-04-19 20:39:53,816 [salt.minion      :1432][INFO    ][12508] Starting a new job with PID 12508
2019-04-19 20:39:53,842 [salt.minion      :1711][INFO    ][12508] Returning information for job: 20190419203953794799
2019-04-19 20:39:59,167 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1002.68156505s left)
2019-04-19 20:40:23,902 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204023894235
2019-04-19 20:40:23,924 [salt.minion      :1432][INFO    ][12528] Starting a new job with PID 12528
2019-04-19 20:40:23,947 [salt.minion      :1711][INFO    ][12528] Returning information for job: 20190419204023894235
2019-04-19 20:40:30,770 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (971.079003096s left)
2019-04-19 20:40:54,092 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204054079785
2019-04-19 20:40:54,116 [salt.minion      :1432][INFO    ][12628] Starting a new job with PID 12628
2019-04-19 20:40:54,142 [salt.minion      :1711][INFO    ][12628] Returning information for job: 20190419204054079785
2019-04-19 20:41:02,863 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (938.985310078s left)
2019-04-19 20:41:24,120 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204124109196
2019-04-19 20:41:24,142 [salt.minion      :1432][INFO    ][12656] Starting a new job with PID 12656
2019-04-19 20:41:24,166 [salt.minion      :1711][INFO    ][12656] Returning information for job: 20190419204124109196
2019-04-19 20:41:34,573 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (907.275808096s left)
2019-04-19 20:41:54,317 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204154308828
2019-04-19 20:41:54,334 [salt.minion      :1432][INFO    ][12735] Starting a new job with PID 12735
2019-04-19 20:41:54,359 [salt.minion      :1711][INFO    ][12735] Returning information for job: 20190419204154308828
2019-04-19 20:42:06,641 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (875.208040953s left)
2019-04-19 20:42:24,366 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204224354537
2019-04-19 20:42:24,384 [salt.minion      :1432][INFO    ][12755] Starting a new job with PID 12755
2019-04-19 20:42:24,410 [salt.minion      :1711][INFO    ][12755] Returning information for job: 20190419204224354537
2019-04-19 20:42:38,539 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (843.309508085s left)
2019-04-19 20:42:54,384 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204254369502
2019-04-19 20:42:54,403 [salt.minion      :1432][INFO    ][12834] Starting a new job with PID 12834
2019-04-19 20:42:54,428 [salt.minion      :1711][INFO    ][12834] Returning information for job: 20190419204254369502
2019-04-19 20:43:10,333 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (811.515424013s left)
2019-04-19 20:43:24,458 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204324443502
2019-04-19 20:43:24,479 [salt.minion      :1432][INFO    ][12854] Starting a new job with PID 12854
2019-04-19 20:43:24,502 [salt.minion      :1711][INFO    ][12854] Returning information for job: 20190419204324443502
2019-04-19 20:43:42,160 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (779.688769102s left)
2019-04-19 20:43:54,512 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204354497100
2019-04-19 20:43:54,534 [salt.minion      :1432][INFO    ][12937] Starting a new job with PID 12937
2019-04-19 20:43:54,556 [salt.minion      :1711][INFO    ][12937] Returning information for job: 20190419204354497100
2019-04-19 20:44:14,002 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (747.84644413s left)
2019-04-19 20:44:24,624 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204424610016
2019-04-19 20:44:24,644 [salt.minion      :1432][INFO    ][12957] Starting a new job with PID 12957
2019-04-19 20:44:24,669 [salt.minion      :1711][INFO    ][12957] Returning information for job: 20190419204424610016
2019-04-19 20:44:45,813 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (716.035372019s left)
2019-04-19 20:44:54,701 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204454693903
2019-04-19 20:44:54,715 [salt.minion      :1432][INFO    ][13038] Starting a new job with PID 13038
2019-04-19 20:44:54,737 [salt.minion      :1711][INFO    ][13038] Returning information for job: 20190419204454693903
2019-04-19 20:45:17,795 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (684.053935051s left)
2019-04-19 20:45:24,834 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204524820059
2019-04-19 20:45:24,856 [salt.minion      :1432][INFO    ][13060] Starting a new job with PID 13060
2019-04-19 20:45:24,880 [salt.minion      :1711][INFO    ][13060] Returning information for job: 20190419204524820059
2019-04-19 20:45:49,602 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (652.246895075s left)
2019-04-19 20:45:54,996 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204554987354
2019-04-19 20:45:55,018 [salt.minion      :1432][INFO    ][13145] Starting a new job with PID 13145
2019-04-19 20:45:55,043 [salt.minion      :1711][INFO    ][13145] Returning information for job: 20190419204554987354
2019-04-19 20:46:21,556 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (620.292246103s left)
2019-04-19 20:46:25,125 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204625117137
2019-04-19 20:46:25,141 [salt.minion      :1432][INFO    ][13165] Starting a new job with PID 13165
2019-04-19 20:46:25,168 [salt.minion      :1711][INFO    ][13165] Returning information for job: 20190419204625117137
2019-04-19 20:46:53,505 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (588.343727112s left)
2019-04-19 20:46:55,340 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204655328058
2019-04-19 20:46:55,361 [salt.minion      :1432][INFO    ][13247] Starting a new job with PID 13247
2019-04-19 20:46:55,386 [salt.minion      :1711][INFO    ][13247] Returning information for job: 20190419204655328058
2019-04-19 20:47:25,317 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (556.531312943s left)
2019-04-19 20:47:25,518 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204725504400
2019-04-19 20:47:25,538 [salt.minion      :1432][INFO    ][13267] Starting a new job with PID 13267
2019-04-19 20:47:25,564 [salt.minion      :1711][INFO    ][13267] Returning information for job: 20190419204725504400
2019-04-19 20:47:55,558 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204755550196
2019-04-19 20:47:55,573 [salt.minion      :1432][INFO    ][13344] Starting a new job with PID 13344
2019-04-19 20:47:55,597 [salt.minion      :1711][INFO    ][13344] Returning information for job: 20190419204755550196
2019-04-19 20:47:57,285 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (524.563863993s left)
2019-04-19 20:48:25,781 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204825766427
2019-04-19 20:48:25,799 [salt.minion      :1432][INFO    ][13363] Starting a new job with PID 13363
2019-04-19 20:48:25,825 [salt.minion      :1711][INFO    ][13363] Returning information for job: 20190419204825766427
2019-04-19 20:48:29,061 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (492.787532091s left)
2019-04-19 20:48:55,866 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204855852348
2019-04-19 20:48:55,888 [salt.minion      :1432][INFO    ][13444] Starting a new job with PID 13444
2019-04-19 20:48:55,913 [salt.minion      :1711][INFO    ][13444] Returning information for job: 20190419204855852348
2019-04-19 20:49:00,905 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (460.943711996s left)
2019-04-19 20:49:25,897 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204925883095
2019-04-19 20:49:25,919 [salt.minion      :1432][INFO    ][13464] Starting a new job with PID 13464
2019-04-19 20:49:25,944 [salt.minion      :1711][INFO    ][13464] Returning information for job: 20190419204925883095
2019-04-19 20:49:32,840 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (429.008546114s left)
2019-04-19 20:49:56,002 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419204955988274
2019-04-19 20:49:56,024 [salt.minion      :1432][INFO    ][13551] Starting a new job with PID 13551
2019-04-19 20:49:56,050 [salt.minion      :1711][INFO    ][13551] Returning information for job: 20190419204955988274
2019-04-19 20:50:04,612 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (397.236953974s left)
2019-04-19 20:50:26,078 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205026069778
2019-04-19 20:50:26,097 [salt.minion      :1432][INFO    ][13571] Starting a new job with PID 13571
2019-04-19 20:50:26,122 [salt.minion      :1711][INFO    ][13571] Returning information for job: 20190419205026069778
2019-04-19 20:50:36,537 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (365.311521053s left)
2019-04-19 20:50:56,216 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205056205890
2019-04-19 20:50:56,238 [salt.minion      :1432][INFO    ][13792] Starting a new job with PID 13792
2019-04-19 20:50:56,264 [salt.minion      :1711][INFO    ][13792] Returning information for job: 20190419205056205890
2019-04-19 20:51:08,373 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (333.475485086s left)
2019-04-19 20:51:26,359 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205126352315
2019-04-19 20:51:26,373 [salt.minion      :1432][INFO    ][13819] Starting a new job with PID 13819
2019-04-19 20:51:26,395 [salt.minion      :1711][INFO    ][13819] Returning information for job: 20190419205126352315
2019-04-19 20:51:40,204 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (301.644264936s left)
2019-04-19 20:51:56,513 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205156499319
2019-04-19 20:51:56,527 [salt.minion      :1432][INFO    ][13899] Starting a new job with PID 13899
2019-04-19 20:51:56,557 [salt.minion      :1711][INFO    ][13899] Returning information for job: 20190419205156499319
2019-04-19 20:52:12,148 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (269.700169086s left)
2019-04-19 20:52:26,745 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205226731496
2019-04-19 20:52:26,765 [salt.minion      :1432][INFO    ][13919] Starting a new job with PID 13919
2019-04-19 20:52:26,791 [salt.minion      :1711][INFO    ][13919] Returning information for job: 20190419205226731496
2019-04-19 20:52:43,973 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (237.875957966s left)
2019-04-19 20:52:56,908 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205256893550
2019-04-19 20:52:56,928 [salt.minion      :1432][INFO    ][14001] Starting a new job with PID 14001
2019-04-19 20:52:56,955 [salt.minion      :1711][INFO    ][14001] Returning information for job: 20190419205256893550
2019-04-19 20:53:15,897 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (205.95203805s left)
2019-04-19 20:53:27,004 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205326988203
2019-04-19 20:53:27,024 [salt.minion      :1432][INFO    ][14021] Starting a new job with PID 14021
2019-04-19 20:53:27,050 [salt.minion      :1711][INFO    ][14021] Returning information for job: 20190419205326988203
2019-04-19 20:53:47,714 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (174.135046005s left)
2019-04-19 20:53:57,194 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205357186219
2019-04-19 20:53:57,214 [salt.minion      :1432][INFO    ][14107] Starting a new job with PID 14107
2019-04-19 20:53:57,239 [salt.minion      :1711][INFO    ][14107] Returning information for job: 20190419205357186219
2019-04-19 20:54:19,516 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (142.332415104s left)
2019-04-19 20:54:27,278 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205427269716
2019-04-19 20:54:27,297 [salt.minion      :1432][INFO    ][14127] Starting a new job with PID 14127
2019-04-19 20:54:27,321 [salt.minion      :1711][INFO    ][14127] Returning information for job: 20190419205427269716
2019-04-19 20:54:51,536 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (110.312848091s left)
2019-04-19 20:54:57,297 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205457283636
2019-04-19 20:54:57,318 [salt.minion      :1432][INFO    ][14206] Starting a new job with PID 14206
2019-04-19 20:54:57,343 [salt.minion      :1711][INFO    ][14206] Returning information for job: 20190419205457283636
2019-04-19 20:55:23,376 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (78.472974062s left)
2019-04-19 20:55:27,418 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205527409772
2019-04-19 20:55:27,442 [salt.minion      :1432][INFO    ][14226] Starting a new job with PID 14226
2019-04-19 20:55:27,467 [salt.minion      :1711][INFO    ][14226] Returning information for job: 20190419205527409772
2019-04-19 20:55:55,459 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (46.389332056s left)
2019-04-19 20:55:57,514 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205557501685
2019-04-19 20:55:57,536 [salt.minion      :1432][INFO    ][14308] Starting a new job with PID 14308
2019-04-19 20:55:57,559 [salt.minion      :1711][INFO    ][14308] Returning information for job: 20190419205557501685
2019-04-19 20:56:27,253 [salt.loaded.ext.module.maas:1023][INFO    ][8329] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (14.595842123s left)
2019-04-19 20:56:27,684 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205627675725
2019-04-19 20:56:27,703 [salt.minion      :1432][INFO    ][14328] Starting a new job with PID 14328
2019-04-19 20:56:27,727 [salt.minion      :1711][INFO    ][14328] Returning information for job: 20190419205627675725
2019-04-19 20:56:57,792 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205657785142
2019-04-19 20:56:57,813 [salt.minion      :1432][INFO    ][14404] Starting a new job with PID 14404
2019-04-19 20:56:57,839 [salt.minion      :1711][INFO    ][14404] Returning information for job: 20190419205657785142
2019-04-19 20:56:59,153 [salt.state       :302 ][ERROR   ][8329] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['gtw01']not in Deployed state
2019-04-19 20:56:59,153 [salt.state       :1951][INFO    ][8329] Completed state [maas.wait_for_machine_status] at time 20:56:59.153505 duration_in_ms=2267320.146
2019-04-19 20:56:59,159 [salt.minion      :1711][INFO    ][8329] Returning information for job: 20190419201904048877
2019-04-19 20:57:09,930 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command pillar.get with jid 20190419205709922219
2019-04-19 20:57:09,948 [salt.minion      :1432][INFO    ][14422] Starting a new job with PID 14422
2019-04-19 20:57:09,961 [salt.minion      :1711][INFO    ][14422] Returning information for job: 20190419205709922219
2019-04-19 20:57:10,520 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command service.status with jid 20190419205710507790
2019-04-19 20:57:10,538 [salt.minion      :1432][INFO    ][14427] Starting a new job with PID 14427
2019-04-19 20:57:11,047 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14427] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-19 20:57:11,086 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14427] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-19 20:57:11,106 [salt.minion      :1711][INFO    ][14427] Returning information for job: 20190419205710507790
2019-04-19 20:57:11,653 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command state.apply with jid 20190419205711643882
2019-04-19 20:57:11,672 [salt.minion      :1432][INFO    ][14438] Starting a new job with PID 14438
2019-04-19 20:57:17,431 [salt.state       :915 ][INFO    ][14438] Loading fresh modules for state activity
2019-04-19 20:57:17,982 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command 'salt-minion --version' in directory '/root'
2019-04-19 20:57:18,278 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command 'salt-minion --version' in directory '/root'
2019-04-19 20:57:19,218 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command 'salt-minion --version' in directory '/root'
2019-04-19 20:57:19,519 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command 'salt-minion --version' in directory '/root'
2019-04-19 20:57:21,238 [salt.state       :1780][INFO    ][14438] Running state [salt-minion] at time 20:57:21.237925
2019-04-19 20:57:21,238 [salt.state       :1813][INFO    ][14438] Executing state pkg.installed for [salt-minion]
2019-04-19 20:57:21,240 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-19 20:57:21,342 [salt.state       :300 ][INFO    ][14438] All specified packages are already installed
2019-04-19 20:57:21,343 [salt.state       :1951][INFO    ][14438] Completed state [salt-minion] at time 20:57:21.343056 duration_in_ms=105.133
2019-04-19 20:57:21,343 [salt.state       :1780][INFO    ][14438] Running state [salt_minion_dependency_packages] at time 20:57:21.343380
2019-04-19 20:57:21,343 [salt.state       :1813][INFO    ][14438] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-19 20:57:21,353 [salt.state       :300 ][INFO    ][14438] All specified packages are already installed
2019-04-19 20:57:21,353 [salt.state       :1951][INFO    ][14438] Completed state [salt_minion_dependency_packages] at time 20:57:21.353669 duration_in_ms=10.289
2019-04-19 20:57:21,356 [salt.state       :1780][INFO    ][14438] Running state [/etc/salt/minion.d/minion.conf] at time 20:57:21.356650
2019-04-19 20:57:21,356 [salt.state       :1813][INFO    ][14438] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-19 20:57:21,624 [salt.state       :300 ][INFO    ][14438] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-19 20:57:21,624 [salt.state       :1951][INFO    ][14438] Completed state [/etc/salt/minion.d/minion.conf] at time 20:57:21.624570 duration_in_ms=267.92
2019-04-19 20:57:21,628 [salt.state       :1780][INFO    ][14438] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:57:21.628248
2019-04-19 20:57:21,628 [salt.state       :1813][INFO    ][14438] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-19 20:57:21,641 [salt.state       :300 ][INFO    ][14438] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-19 20:57:21,642 [salt.state       :1951][INFO    ][14438] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:57:21.642178 duration_in_ms=13.929
2019-04-19 20:57:21,644 [salt.state       :1780][INFO    ][14438] Running state [salt-minion] at time 20:57:21.644150
2019-04-19 20:57:21,644 [salt.state       :1813][INFO    ][14438] Executing state service.running for [salt-minion]
2019-04-19 20:57:21,645 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-19 20:57:21,683 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-19 20:57:21,702 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-19 20:57:21,719 [salt.state       :300 ][INFO    ][14438] The service salt-minion is already running
2019-04-19 20:57:21,719 [salt.state       :1951][INFO    ][14438] Completed state [salt-minion] at time 20:57:21.719585 duration_in_ms=75.435
2019-04-19 20:57:21,722 [salt.state       :1780][INFO    ][14438] Running state [/etc/salt/grains.d] at time 20:57:21.722016
2019-04-19 20:57:21,722 [salt.state       :1813][INFO    ][14438] Executing state file.directory for [/etc/salt/grains.d]
2019-04-19 20:57:21,725 [salt.state       :300 ][INFO    ][14438] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-19 20:57:21,725 [salt.state       :1951][INFO    ][14438] Completed state [/etc/salt/grains.d] at time 20:57:21.725419 duration_in_ms=3.403
2019-04-19 20:57:21,726 [salt.state       :1780][INFO    ][14438] Running state [/etc/salt/grains] at time 20:57:21.726329
2019-04-19 20:57:21,726 [salt.state       :1813][INFO    ][14438] Executing state file.managed for [/etc/salt/grains]
2019-04-19 20:57:21,727 [salt.state       :300 ][INFO    ][14438] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-19 20:57:21,727 [salt.state       :1951][INFO    ][14438] Completed state [/etc/salt/grains] at time 20:57:21.727673 duration_in_ms=1.344
2019-04-19 20:57:21,728 [salt.state       :1780][INFO    ][14438] Running state [/etc/salt/grains.d/placeholder] at time 20:57:21.728303
2019-04-19 20:57:21,728 [salt.state       :1813][INFO    ][14438] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-19 20:57:21,729 [salt.state       :300 ][INFO    ][14438] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-19 20:57:21,729 [salt.state       :1951][INFO    ][14438] Completed state [/etc/salt/grains.d/placeholder] at time 20:57:21.729591 duration_in_ms=1.288
2019-04-19 20:57:21,730 [salt.state       :1780][INFO    ][14438] Running state [/etc/salt/grains.d/sphinx] at time 20:57:21.730237
2019-04-19 20:57:21,730 [salt.state       :1813][INFO    ][14438] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-19 20:57:21,732 [salt.state       :300 ][INFO    ][14438] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-19 20:57:21,732 [salt.state       :1951][INFO    ][14438] Completed state [/etc/salt/grains.d/sphinx] at time 20:57:21.732330 duration_in_ms=2.092
2019-04-19 20:57:21,736 [salt.state       :1780][INFO    ][14438] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:57:21.736374
2019-04-19 20:57:21,736 [salt.state       :1813][INFO    ][14438] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-19 20:57:21,736 [salt.state       :300 ][INFO    ][14438] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-19 20:57:21,737 [salt.state       :1951][INFO    ][14438] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:57:21.737132 duration_in_ms=0.757
2019-04-19 20:57:21,737 [salt.state       :1780][INFO    ][14438] Running state [/etc/salt/grains.d/dns_records] at time 20:57:21.737597
2019-04-19 20:57:21,737 [salt.state       :1813][INFO    ][14438] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-19 20:57:21,738 [salt.state       :300 ][INFO    ][14438] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-19 20:57:21,739 [salt.state       :1951][INFO    ][14438] Completed state [/etc/salt/grains.d/dns_records] at time 20:57:21.739221 duration_in_ms=1.622
2019-04-19 20:57:21,740 [salt.state       :1780][INFO    ][14438] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:57:21.740130
2019-04-19 20:57:21,740 [salt.state       :1813][INFO    ][14438] 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-19 20:57:21,740 [salt.state       :300 ][INFO    ][14438] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-19 20:57:21,740 [salt.state       :1951][INFO    ][14438] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:57:21.740907 duration_in_ms=0.777
2019-04-19 20:57:21,741 [salt.state       :1780][INFO    ][14438] Running state [/etc/salt/grains.d/salt] at time 20:57:21.741371
2019-04-19 20:57:21,741 [salt.state       :1813][INFO    ][14438] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-19 20:57:21,742 [salt.state       :300 ][INFO    ][14438] File /etc/salt/grains.d/salt is in the correct state
2019-04-19 20:57:21,742 [salt.state       :1951][INFO    ][14438] Completed state [/etc/salt/grains.d/salt] at time 20:57:21.742685 duration_in_ms=1.314
2019-04-19 20:57:21,743 [salt.state       :1780][INFO    ][14438] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:57:21.743845
2019-04-19 20:57:21,744 [salt.state       :1813][INFO    ][14438] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-19 20:57:21,744 [salt.state       :300 ][INFO    ][14438] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-19 20:57:21,744 [salt.state       :1951][INFO    ][14438] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:57:21.744578 duration_in_ms=0.732
2019-04-19 20:57:21,746 [salt.state       :1780][INFO    ][14438] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:57:21.746461
2019-04-19 20:57:21,746 [salt.state       :1813][INFO    ][14438] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-19 20:57:21,747 [salt.state       :300 ][INFO    ][14438] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-19 20:57:21,747 [salt.state       :1951][INFO    ][14438] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:57:21.747801 duration_in_ms=1.34
2019-04-19 20:57:21,748 [salt.state       :1780][INFO    ][14438] Running state [mine.update] at time 20:57:21.748450
2019-04-19 20:57:21,748 [salt.state       :1813][INFO    ][14438] Executing state module.wait for [mine.update]
2019-04-19 20:57:21,748 [salt.state       :300 ][INFO    ][14438] No changes made for mine.update
2019-04-19 20:57:21,749 [salt.state       :1951][INFO    ][14438] Completed state [mine.update] at time 20:57:21.749132 duration_in_ms=0.681
2019-04-19 20:57:21,749 [salt.state       :1780][INFO    ][14438] Running state [ca-certificates] at time 20:57:21.749360
2019-04-19 20:57:21,749 [salt.state       :1813][INFO    ][14438] Executing state pkg.installed for [ca-certificates]
2019-04-19 20:57:21,758 [salt.state       :300 ][INFO    ][14438] All specified packages are already installed
2019-04-19 20:57:21,759 [salt.state       :1951][INFO    ][14438] Completed state [ca-certificates] at time 20:57:21.759581 duration_in_ms=10.22
2019-04-19 20:57:21,760 [salt.state       :1780][INFO    ][14438] Running state [update-ca-certificates] at time 20:57:21.760482
2019-04-19 20:57:21,760 [salt.state       :1813][INFO    ][14438] Executing state cmd.wait for [update-ca-certificates]
2019-04-19 20:57:21,761 [salt.state       :300 ][INFO    ][14438] No changes made for update-ca-certificates
2019-04-19 20:57:21,761 [salt.state       :1951][INFO    ][14438] Completed state [update-ca-certificates] at time 20:57:21.761203 duration_in_ms=0.722
2019-04-19 20:57:21,761 [salt.state       :1780][INFO    ][14438] Running state [iptables] at time 20:57:21.761439
2019-04-19 20:57:21,761 [salt.state       :1813][INFO    ][14438] Executing state pkg.installed for [iptables]
2019-04-19 20:57:21,770 [salt.state       :300 ][INFO    ][14438] All specified packages are already installed
2019-04-19 20:57:21,770 [salt.state       :1951][INFO    ][14438] Completed state [iptables] at time 20:57:21.770787 duration_in_ms=9.348
2019-04-19 20:57:21,771 [salt.state       :1780][INFO    ][14438] Running state [iptables-persistent] at time 20:57:21.771330
2019-04-19 20:57:21,771 [salt.state       :1813][INFO    ][14438] Executing state pkg.installed for [iptables-persistent]
2019-04-19 20:57:21,780 [salt.state       :300 ][INFO    ][14438] All specified packages are already installed
2019-04-19 20:57:21,780 [salt.state       :1951][INFO    ][14438] Completed state [iptables-persistent] at time 20:57:21.780209 duration_in_ms=8.878
2019-04-19 20:57:21,781 [salt.state       :1780][INFO    ][14438] Running state [iptables_modules_v4_load] at time 20:57:21.781145
2019-04-19 20:57:21,781 [salt.state       :1813][INFO    ][14438] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-19 20:57:21,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command 'lsmod' in directory '/root'
2019-04-19 20:57:21,800 [salt.state       :300 ][INFO    ][14438] Kernel modules iptable_filter, ip_tables are already present
2019-04-19 20:57:21,801 [salt.state       :1951][INFO    ][14438] Completed state [iptables_modules_v4_load] at time 20:57:21.801079 duration_in_ms=19.933
2019-04-19 20:57:21,802 [salt.state       :1780][INFO    ][14438] Running state [/etc/iptables/rules.v4] at time 20:57:21.802308
2019-04-19 20:57:21,802 [salt.state       :1813][INFO    ][14438] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-19 20:57:21,904 [salt.state       :300 ][INFO    ][14438] File /etc/iptables/rules.v4 is in the correct state
2019-04-19 20:57:21,904 [salt.state       :1951][INFO    ][14438] Completed state [/etc/iptables/rules.v4] at time 20:57:21.904794 duration_in_ms=102.486
2019-04-19 20:57:21,905 [salt.state       :1780][INFO    ][14438] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:57:21.905937
2019-04-19 20:57:21,906 [salt.state       :1813][INFO    ][14438] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-19 20:57:21,906 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-19 20:57:21,924 [salt.state       :300 ][INFO    ][14438] onlyif execution failed
2019-04-19 20:57:21,924 [salt.state       :1951][INFO    ][14438] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:57:21.924847 duration_in_ms=18.909
2019-04-19 20:57:21,926 [salt.state       :1780][INFO    ][14438] Running state [netfilter-persistent] at time 20:57:21.926144
2019-04-19 20:57:21,926 [salt.state       :1813][INFO    ][14438] Executing state service.running for [netfilter-persistent]
2019-04-19 20:57:21,929 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-19 20:57:21,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-19 20:57:21,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-19 20:57:21,986 [salt.state       :300 ][INFO    ][14438] The service netfilter-persistent is already running
2019-04-19 20:57:21,989 [salt.state       :1951][INFO    ][14438] Completed state [netfilter-persistent] at time 20:57:21.988956 duration_in_ms=62.812
2019-04-19 20:57:21,990 [salt.state       :1780][INFO    ][14438] Running state [iptables_extra.remove_stale_tables] at time 20:57:21.990195
2019-04-19 20:57:21,990 [salt.state       :1813][INFO    ][14438] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-19 20:57:21,991 [salt.state       :300 ][INFO    ][14438] No changes made for iptables_extra.remove_stale_tables
2019-04-19 20:57:21,991 [salt.state       :1951][INFO    ][14438] Completed state [iptables_extra.remove_stale_tables] at time 20:57:21.991445 duration_in_ms=1.25
2019-04-19 20:57:21,991 [salt.state       :1780][INFO    ][14438] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:57:21.991804
2019-04-19 20:57:21,992 [salt.state       :1813][INFO    ][14438] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-19 20:57:21,993 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-19 20:57:22,008 [salt.state       :300 ][INFO    ][14438] onlyif execution failed
2019-04-19 20:57:22,008 [salt.state       :1951][INFO    ][14438] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:57:22.008611 duration_in_ms=16.807
2019-04-19 20:57:22,009 [salt.state       :1780][INFO    ][14438] Running state [/etc/iptables/rules.v6] at time 20:57:22.009657
2019-04-19 20:57:22,010 [salt.state       :1813][INFO    ][14438] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-19 20:57:22,010 [salt.state       :300 ][INFO    ][14438] File /etc/iptables/rules.v6 is not present
2019-04-19 20:57:22,010 [salt.state       :1951][INFO    ][14438] Completed state [/etc/iptables/rules.v6] at time 20:57:22.010702 duration_in_ms=1.045
2019-04-19 20:57:22,014 [salt.state       :1780][INFO    ][14438] Running state [iptables_extra.flush_all] at time 20:57:22.014239
2019-04-19 20:57:22,014 [salt.state       :1813][INFO    ][14438] Executing state module.wait for [iptables_extra.flush_all]
2019-04-19 20:57:22,015 [salt.state       :300 ][INFO    ][14438] No changes made for iptables_extra.flush_all
2019-04-19 20:57:22,015 [salt.state       :1951][INFO    ][14438] Completed state [iptables_extra.flush_all] at time 20:57:22.015583 duration_in_ms=1.343
2019-04-19 20:57:22,019 [salt.minion      :1711][INFO    ][14438] Returning information for job: 20190419205711643882
2019-04-19 20:57:22,565 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command state.apply with jid 20190419205722554525
2019-04-19 20:57:22,583 [salt.minion      :1432][INFO    ][14515] Starting a new job with PID 14515
2019-04-19 20:57:23,466 [salt.state       :915 ][INFO    ][14515] Loading fresh modules for state activity
2019-04-19 20:57:24,332 [salt.state       :1780][INFO    ][14515] Running state [maas-rack-controller] at time 20:57:24.332887
2019-04-19 20:57:24,333 [salt.state       :1813][INFO    ][14515] Executing state pkg.installed for [maas-rack-controller]
2019-04-19 20:57:24,335 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14515] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-19 20:57:24,446 [salt.state       :300 ][INFO    ][14515] All specified packages are already installed
2019-04-19 20:57:24,448 [salt.state       :1951][INFO    ][14515] Completed state [maas-rack-controller] at time 20:57:24.448313 duration_in_ms=115.425
2019-04-19 20:57:24,448 [salt.state       :1780][INFO    ][14515] Running state [ipmitool] at time 20:57:24.448732
2019-04-19 20:57:24,449 [salt.state       :1813][INFO    ][14515] Executing state pkg.installed for [ipmitool]
2019-04-19 20:57:24,457 [salt.state       :300 ][INFO    ][14515] All specified packages are already installed
2019-04-19 20:57:24,457 [salt.state       :1951][INFO    ][14515] Completed state [ipmitool] at time 20:57:24.457894 duration_in_ms=9.162
2019-04-19 20:57:24,463 [salt.state       :1780][INFO    ][14515] Running state [/etc/maas/rackd.conf] at time 20:57:24.463633
2019-04-19 20:57:24,463 [salt.state       :1813][INFO    ][14515] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-19 20:57:24,464 [salt.state       :300 ][INFO    ][14515] No changes needed to be made
2019-04-19 20:57:24,465 [salt.state       :1951][INFO    ][14515] Completed state [/etc/maas/rackd.conf] at time 20:57:24.465101 duration_in_ms=1.468
2019-04-19 20:57:24,465 [salt.state       :1780][INFO    ][14515] Running state [/etc/maas/rackd.conf] at time 20:57:24.465348
2019-04-19 20:57:24,465 [salt.state       :1813][INFO    ][14515] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-19 20:57:24,465 [salt.loaded.int.states.file:2298][WARNING ][14515] 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-19 20:57:24,466 [salt.state       :300 ][INFO    ][14515] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-19 20:57:24,466 [salt.state       :1951][INFO    ][14515] Completed state [/etc/maas/rackd.conf] at time 20:57:24.466871 duration_in_ms=1.523
2019-04-19 20:57:24,467 [salt.state       :1780][INFO    ][14515] Running state [maas-rackd] at time 20:57:24.467918
2019-04-19 20:57:24,468 [salt.state       :1813][INFO    ][14515] Executing state service.running for [maas-rackd]
2019-04-19 20:57:24,468 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14515] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-19 20:57:24,504 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14515] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-19 20:57:24,520 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14515] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-19 20:57:24,536 [salt.state       :300 ][INFO    ][14515] The service maas-rackd is already running
2019-04-19 20:57:24,536 [salt.state       :1951][INFO    ][14515] Completed state [maas-rackd] at time 20:57:24.536406 duration_in_ms=68.488
2019-04-19 20:57:24,538 [salt.minion      :1711][INFO    ][14515] Returning information for job: 20190419205722554525
2019-04-19 20:57:25,075 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command state.apply with jid 20190419205725066208
2019-04-19 20:57:25,094 [salt.minion      :1432][INFO    ][14539] Starting a new job with PID 14539
2019-04-19 20:57:25,957 [salt.state       :915 ][INFO    ][14539] Loading fresh modules for state activity
2019-04-19 20:57:26,966 [salt.state       :1780][INFO    ][14539] Running state [maas-region-controller] at time 20:57:26.966567
2019-04-19 20:57:26,967 [salt.state       :1813][INFO    ][14539] Executing state pkg.installed for [maas-region-controller]
2019-04-19 20:57:26,968 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14539] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-19 20:57:27,074 [salt.state       :300 ][INFO    ][14539] All specified packages are already installed
2019-04-19 20:57:27,074 [salt.state       :1951][INFO    ][14539] Completed state [maas-region-controller] at time 20:57:27.074614 duration_in_ms=108.048
2019-04-19 20:57:27,075 [salt.state       :1780][INFO    ][14539] Running state [python-oauth] at time 20:57:27.075519
2019-04-19 20:57:27,075 [salt.state       :1813][INFO    ][14539] Executing state pkg.installed for [python-oauth]
2019-04-19 20:57:27,084 [salt.state       :300 ][INFO    ][14539] All specified packages are already installed
2019-04-19 20:57:27,084 [salt.state       :1951][INFO    ][14539] Completed state [python-oauth] at time 20:57:27.084434 duration_in_ms=8.915
2019-04-19 20:57:27,088 [salt.state       :1780][INFO    ][14539] Running state [/etc/maas/regiond.conf] at time 20:57:27.088387
2019-04-19 20:57:27,088 [salt.state       :1813][INFO    ][14539] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-19 20:57:27,092 [salt.state       :300 ][INFO    ][14539] No changes needed to be made
2019-04-19 20:57:27,093 [salt.state       :1951][INFO    ][14539] Completed state [/etc/maas/regiond.conf] at time 20:57:27.093118 duration_in_ms=4.731
2019-04-19 20:57:27,093 [salt.state       :1780][INFO    ][14539] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:57:27.093598
2019-04-19 20:57:27,093 [salt.state       :1813][INFO    ][14539] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-19 20:57:27,151 [salt.state       :300 ][INFO    ][14539] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-19 20:57:27,151 [salt.state       :1951][INFO    ][14539] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:57:27.151824 duration_in_ms=58.226
2019-04-19 20:57:27,152 [salt.state       :1780][INFO    ][14539] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:57:27.152321
2019-04-19 20:57:27,152 [salt.state       :1813][INFO    ][14539] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-19 20:57:27,156 [salt.state       :300 ][INFO    ][14539] No changes needed to be made
2019-04-19 20:57:27,156 [salt.state       :1951][INFO    ][14539] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:57:27.156232 duration_in_ms=3.91
2019-04-19 20:57:27,156 [salt.state       :1780][INFO    ][14539] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:57:27.156708
2019-04-19 20:57:27,156 [salt.state       :1813][INFO    ][14539] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-19 20:57:27,160 [salt.state       :300 ][INFO    ][14539] No changes needed to be made
2019-04-19 20:57:27,161 [salt.state       :1951][INFO    ][14539] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:57:27.161084 duration_in_ms=4.376
2019-04-19 20:57:27,161 [salt.state       :1780][INFO    ][14539] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:57:27.161566
2019-04-19 20:57:27,161 [salt.state       :1813][INFO    ][14539] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-19 20:57:27,185 [salt.state       :300 ][INFO    ][14539] No changes needed to be made
2019-04-19 20:57:27,185 [salt.state       :1951][INFO    ][14539] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:57:27.185697 duration_in_ms=24.131
2019-04-19 20:57:27,186 [salt.state       :1780][INFO    ][14539] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:57:27.186178
2019-04-19 20:57:27,186 [salt.state       :1813][INFO    ][14539] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-19 20:57:27,199 [salt.state       :300 ][INFO    ][14539] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-19 20:57:27,199 [salt.state       :1951][INFO    ][14539] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:57:27.199915 duration_in_ms=13.737
2019-04-19 20:57:27,201 [salt.state       :1780][INFO    ][14539] Running state [a2enmod headers] at time 20:57:27.201377
2019-04-19 20:57:27,201 [salt.state       :1813][INFO    ][14539] Executing state cmd.run for [a2enmod headers]
2019-04-19 20:57:27,202 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14539] Executing command 'a2enmod headers' in directory '/root'
2019-04-19 20:57:27,270 [salt.state       :300 ][INFO    ][14539] {'pid': 14558, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-19 20:57:27,270 [salt.state       :1951][INFO    ][14539] Completed state [a2enmod headers] at time 20:57:27.270759 duration_in_ms=69.381
2019-04-19 20:57:27,271 [salt.state       :1780][INFO    ][14539] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:57:27.271279
2019-04-19 20:57:27,271 [salt.state       :1813][INFO    ][14539] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-19 20:57:27,290 [salt.state       :300 ][INFO    ][14539] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-19 20:57:27,290 [salt.state       :1951][INFO    ][14539] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:57:27.290343 duration_in_ms=19.064
2019-04-19 20:57:27,294 [salt.state       :1780][INFO    ][14539] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:57:27.294176
2019-04-19 20:57:27,294 [salt.state       :1813][INFO    ][14539] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-19 20:57:27,345 [salt.state       :300 ][INFO    ][14539] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-19 20:57:27,345 [salt.state       :1951][INFO    ][14539] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:57:27.345302 duration_in_ms=51.126
2019-04-19 20:57:27,346 [salt.state       :1780][INFO    ][14539] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:57:27.345813
2019-04-19 20:57:27,346 [salt.state       :1813][INFO    ][14539] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-19 20:57:27,404 [salt.state       :300 ][INFO    ][14539] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-19 20:57:27,404 [salt.state       :1951][INFO    ][14539] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:57:27.404202 duration_in_ms=58.389
2019-04-19 20:57:27,404 [salt.state       :1780][INFO    ][14539] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:57:27.404698
2019-04-19 20:57:27,404 [salt.state       :1813][INFO    ][14539] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-19 20:57:27,472 [salt.state       :300 ][INFO    ][14539] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-19 20:57:27,472 [salt.state       :1951][INFO    ][14539] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:57:27.472936 duration_in_ms=68.237
2019-04-19 20:57:27,473 [salt.state       :1780][INFO    ][14539] Running state [/root/.pgpass] at time 20:57:27.473190
2019-04-19 20:57:27,473 [salt.state       :1813][INFO    ][14539] Executing state file.managed for [/root/.pgpass]
2019-04-19 20:57:27,514 [salt.state       :300 ][INFO    ][14539] File /root/.pgpass is in the correct state
2019-04-19 20:57:27,514 [salt.state       :1951][INFO    ][14539] Completed state [/root/.pgpass] at time 20:57:27.514579 duration_in_ms=41.389
2019-04-19 20:57:27,521 [salt.state       :1780][INFO    ][14539] Running state [maas-region syncdb --noinput] at time 20:57:27.521381
2019-04-19 20:57:27,521 [salt.state       :1813][INFO    ][14539] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-19 20:57:27,522 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14539] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-19 20:57:30,144 [salt.state       :300 ][INFO    ][14539] {'pid': 14571, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: maasserver, metadataserver, piston3, auth, sessions, contenttypes, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-19 20:57:30,145 [salt.state       :1951][INFO    ][14539] Completed state [maas-region syncdb --noinput] at time 20:57:30.145563 duration_in_ms=2624.18
2019-04-19 20:57:30,146 [salt.state       :2022][WARNING ][14539] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-19 20:57:30,150 [salt.state       :1780][INFO    ][14539] Running state [maas-regiond] at time 20:57:30.150323
2019-04-19 20:57:30,151 [salt.state       :1813][INFO    ][14539] Executing state service.running for [maas-regiond]
2019-04-19 20:57:30,152 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14539] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-19 20:57:30,198 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14539] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-19 20:57:30,215 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14539] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-19 20:57:30,235 [salt.state       :300 ][INFO    ][14539] The service maas-regiond is already running
2019-04-19 20:57:30,236 [salt.state       :1951][INFO    ][14539] Completed state [maas-regiond] at time 20:57:30.236415 duration_in_ms=86.093
2019-04-19 20:57:30,239 [salt.state       :1780][INFO    ][14539] Running state [bind9] at time 20:57:30.239550
2019-04-19 20:57:30,240 [salt.state       :1813][INFO    ][14539] Executing state service.running for [bind9]
2019-04-19 20:57:30,241 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14539] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-19 20:57:30,263 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14539] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-19 20:57:30,282 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14539] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-19 20:57:30,299 [salt.state       :300 ][INFO    ][14539] The service bind9 is already running
2019-04-19 20:57:30,299 [salt.state       :1951][INFO    ][14539] Completed state [bind9] at time 20:57:30.299596 duration_in_ms=60.046
2019-04-19 20:57:30,301 [salt.state       :1780][INFO    ][14539] Running state [apache2] at time 20:57:30.301933
2019-04-19 20:57:30,302 [salt.state       :1813][INFO    ][14539] Executing state service.running for [apache2]
2019-04-19 20:57:30,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14539] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-19 20:57:30,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14539] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-19 20:57:30,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14539] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-19 20:57:30,364 [salt.state       :300 ][INFO    ][14539] The service apache2 is already running
2019-04-19 20:57:30,365 [salt.state       :1951][INFO    ][14539] Completed state [apache2] at time 20:57:30.365083 duration_in_ms=63.149
2019-04-19 20:57:30,366 [salt.state       :1780][INFO    ][14539] Running state [maasng.wait_for_http_code] at time 20:57:30.366572
2019-04-19 20:57:30,367 [salt.state       :1813][INFO    ][14539] Executing state module.run for [maasng.wait_for_http_code]
2019-04-19 20:57:30,367 [salt.utils.decorators:613 ][WARNING ][14539] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:57:30,493 [salt.state       :300 ][INFO    ][14539] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-19 20:57:30,494 [salt.state       :1951][INFO    ][14539] Completed state [maasng.wait_for_http_code] at time 20:57:30.494242 duration_in_ms=127.67
2019-04-19 20:57:30,496 [salt.state       :1780][INFO    ][14539] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:57:30.496372
2019-04-19 20:57:30,496 [salt.state       :1813][INFO    ][14539] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-19 20:57:30,497 [salt.state       :300 ][INFO    ][14539] /var/lib/maas/.setup_admin exists
2019-04-19 20:57:30,497 [salt.state       :1951][INFO    ][14539] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:57:30.497526 duration_in_ms=1.156
2019-04-19 20:57:30,498 [salt.state       :1780][INFO    ][14539] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:57:30.498378
2019-04-19 20:57:30,498 [salt.state       :1813][INFO    ][14539] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-19 20:57:30,499 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14539] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-19 20:57:32,296 [salt.state       :300 ][INFO    ][14539] {'pid': 14592, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-19 20:57:32,297 [salt.state       :1951][INFO    ][14539] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:57:32.297205 duration_in_ms=1798.826
2019-04-19 20:57:32,308 [salt.state       :1780][INFO    ][14539] Running state [maas_region_boot_source_resources_mirror] at time 20:57:32.308487
2019-04-19 20:57:32,308 [salt.state       :1813][INFO    ][14539] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-19 20:57:32,388 [salt.state       :300 ][INFO    ][14539] {'changes': {}}
2019-04-19 20:57:32,389 [salt.state       :1951][INFO    ][14539] Completed state [maas_region_boot_source_resources_mirror] at time 20:57:32.389139 duration_in_ms=80.652
2019-04-19 20:57:32,390 [salt.state       :1780][INFO    ][14539] Running state [maasng.boot_resources_import] at time 20:57:32.390355
2019-04-19 20:57:32,392 [salt.state       :1813][INFO    ][14539] Executing state module.run for [maasng.boot_resources_import]
2019-04-19 20:57:32,392 [salt.utils.decorators:613 ][WARNING ][14539] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:57:32,474 [salt.loaded.ext.module.maasng:1600][INFO    ][14539] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-19 20:57:37,512 [salt.loaded.ext.module.maasng:1600][INFO    ][14539] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-19 20:57:40,120 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205740111102
2019-04-19 20:57:40,137 [salt.minion      :1432][INFO    ][14643] Starting a new job with PID 14643
2019-04-19 20:57:40,161 [salt.minion      :1711][INFO    ][14643] Returning information for job: 20190419205740111102
2019-04-19 20:57:42,562 [salt.loaded.ext.module.maasng:1600][INFO    ][14539] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-04-19 20:57:47,614 [salt.loaded.ext.module.maasng:1600][INFO    ][14539] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-04-19 20:57:52,667 [salt.loaded.ext.module.maasng:1600][INFO    ][14539] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-04-19 20:57:57,731 [salt.state       :300 ][INFO    ][14539] {'ret': True}
2019-04-19 20:57:57,731 [salt.state       :1951][INFO    ][14539] Completed state [maasng.boot_resources_import] at time 20:57:57.731389 duration_in_ms=25341.034
2019-04-19 20:57:57,732 [salt.state       :1780][INFO    ][14539] Running state [maas_region_boot_sources_selection_xenial] at time 20:57:57.732148
2019-04-19 20:57:57,732 [salt.state       :1813][INFO    ][14539] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-19 20:57:57,850 [salt.state       :300 ][INFO    ][14539] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-19 20:57:57,850 [salt.state       :1951][INFO    ][14539] Completed state [maas_region_boot_sources_selection_xenial] at time 20:57:57.850636 duration_in_ms=118.487
2019-04-19 20:57:57,852 [salt.state       :1780][INFO    ][14539] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 20:57:57.852057
2019-04-19 20:57:57,852 [salt.state       :1813][INFO    ][14539] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-19 20:57:57,852 [salt.utils.decorators:613 ][WARNING ][14539] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:57:57,853 [salt.loaded.ext.module.maasng:1771][INFO    ][14539] boot-sources sync initiated for ALL Rack's
2019-04-19 20:57:58,650 [salt.state       :300 ][INFO    ][14539] {'ret': True}
2019-04-19 20:57:58,650 [salt.state       :1951][INFO    ][14539] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 20:57:58.650552 duration_in_ms=798.493
2019-04-19 20:57:58,652 [salt.state       :1780][INFO    ][14539] Running state [maas.process_maas_config] at time 20:57:58.652466
2019-04-19 20:57:58,652 [salt.state       :1813][INFO    ][14539] Executing state module.run for [maas.process_maas_config]
2019-04-19 20:57:58,653 [salt.utils.decorators:613 ][WARNING ][14539] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:57:58,653 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=enable_http_proxy value=True
2019-04-19 20:57:58,696 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=upstream_dns value=8.8.8.8
2019-04-19 20:58:07,650 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=commissioning_distro_series value=xenial
2019-04-19 20:58:07,751 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=default_osystem value=ubuntu
2019-04-19 20:58:07,808 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=active_discovery_interval value=600
2019-04-19 20:58:07,855 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=dnssec_validation value=no
2019-04-19 20:58:07,900 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=maas_name value=mas01
2019-04-19 20:58:07,941 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=network_discovery value=enabled
2019-04-19 20:58:08,032 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=enable_third_party_drivers value=True
2019-04-19 20:58:08,074 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=default_storage_layout value=lvm
2019-04-19 20:58:08,132 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=ntp_external_only value=True
2019-04-19 20:58:08,186 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-19 20:58:08,232 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=default_distro_series value=xenial
2019-04-19 20:58:08,293 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-19 20:58:08,422 [salt.state       :300 ][INFO    ][14539] {'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-19 20:58:08,423 [salt.state       :1951][INFO    ][14539] Completed state [maas.process_maas_config] at time 20:58:08.423291 duration_in_ms=9770.824
2019-04-19 20:58:08,424 [salt.state       :1780][INFO    ][14539] Running state [pxe_admin] at time 20:58:08.424031
2019-04-19 20:58:08,424 [salt.state       :1813][INFO    ][14539] Executing state maasng.fabric_present for [pxe_admin]
2019-04-19 20:58:08,480 [salt.loaded.ext.module.maasng:945 ][INFO    ][14539] [{u'class_type': None, 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'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin'}]
2019-04-19 20:58:08,535 [salt.loaded.ext.module.maasng:1008][WARNING ][14539] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-19 20:58:08,535 [salt.loaded.ext.module.maasng:1011][WARNING ][14539] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-19 20:58:08,587 [salt.state       :300 ][INFO    ][14539] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-19 20:58:08,587 [salt.state       :1951][INFO    ][14539] Completed state [pxe_admin] at time 20:58:08.587463 duration_in_ms=163.431
2019-04-19 20:58:08,587 [salt.state       :1780][INFO    ][14539] Running state [vlan 0] at time 20:58:08.587787
2019-04-19 20:58:08,588 [salt.state       :1813][INFO    ][14539] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-19 20:58:08,638 [salt.loaded.ext.module.maasng:945 ][INFO    ][14539] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-04-19 20:58:08,764 [salt.loaded.ext.module.maasng:945 ][INFO    ][14539] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-04-19 20:58:08,997 [salt.loaded.ext.module.maasng:945 ][INFO    ][14539] [{u'class_type': None, 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'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin'}]
2019-04-19 20:58:09,072 [salt.state       :300 ][INFO    ][14539] {'new': 'Vlan untagged was updated'}
2019-04-19 20:58:09,072 [salt.state       :1951][INFO    ][14539] Completed state [vlan 0] at time 20:58:09.072257 duration_in_ms=484.468
2019-04-19 20:58:09,073 [salt.state       :1780][INFO    ][14539] Running state [192.168.11.0/24] at time 20:58:09.073338
2019-04-19 20:58:09,073 [salt.state       :1813][INFO    ][14539] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-19 20:58:09,271 [salt.loaded.ext.module.maasng:945 ][INFO    ][14539] [{u'class_type': None, 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'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2'}, {u'class_type': u'', 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'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin'}]
2019-04-19 20:58:09,271 [salt.loaded.ext.module.maasng:1235][WARNING ][14539] Ignoring parameter vlan:0
2019-04-19 20:58:09,329 [salt.state       :300 ][INFO    ][14539] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-19 20:58:09,329 [salt.state       :1951][INFO    ][14539] Completed state [192.168.11.0/24] at time 20:58:09.329585 duration_in_ms=256.246
2019-04-19 20:58:09,330 [salt.state       :1780][INFO    ][14539] Running state [maas_create_iprange_1] at time 20:58:09.330426
2019-04-19 20:58:09,330 [salt.state       :1813][INFO    ][14539] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-19 20:58:09,383 [salt.state       :300 ][INFO    ][14539] Iprange maas_create_iprange_1 already exist.
2019-04-19 20:58:09,383 [salt.state       :1951][INFO    ][14539] Completed state [maas_create_iprange_1] at time 20:58:09.383574 duration_in_ms=53.147
2019-04-19 20:58:09,383 [salt.state       :1780][INFO    ][14539] Running state [vlan 0] at time 20:58:09.383844
2019-04-19 20:58:09,384 [salt.state       :1813][INFO    ][14539] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-19 20:58:09,426 [salt.loaded.ext.module.maasng:945 ][INFO    ][14539] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-04-19 20:58:09,505 [salt.loaded.ext.module.maasng:945 ][INFO    ][14539] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-04-19 20:58:09,718 [salt.loaded.ext.module.maasng:945 ][INFO    ][14539] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'gwk7ba', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-04-19 20:58:09,816 [salt.state       :300 ][INFO    ][14539] {'new': 'Vlan untagged was updated'}
2019-04-19 20:58:09,816 [salt.state       :1951][INFO    ][14539] Completed state [vlan 0] at time 20:58:09.816460 duration_in_ms=432.615
2019-04-19 20:58:09,817 [salt.state       :1780][INFO    ][14539] Running state [opnfv] at time 20:58:09.817071
2019-04-19 20:58:09,817 [salt.state       :1813][INFO    ][14539] Executing state maasng.sshkey_present for [opnfv]
2019-04-19 20:58:09,861 [salt.loaded.ext.module.maasng:1903][INFO    ][14539] [{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-19 20:58:09,861 [salt.state       :300 ][INFO    ][14539] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-19 20:58:09,862 [salt.state       :1951][INFO    ][14539] Completed state [opnfv] at time 20:58:09.862163 duration_in_ms=45.091
2019-04-19 20:58:09,863 [salt.state       :1780][INFO    ][14539] Running state [maas.process_tags] at time 20:58:09.863468
2019-04-19 20:58:09,863 [salt.state       :1813][INFO    ][14539] Executing state module.run for [maas.process_tags]
2019-04-19 20:58:09,864 [salt.utils.decorators:613 ][WARNING ][14539] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:58:09,916 [salt.loaded.ext.module.maas:92  ][INFO    ][14539] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-04-19 20:58:09,982 [salt.state       :300 ][INFO    ][14539] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-04-19 20:58:09,982 [salt.state       :1951][INFO    ][14539] Completed state [maas.process_tags] at time 20:58:09.982526 duration_in_ms=119.057
2019-04-19 20:58:09,985 [salt.minion      :1711][INFO    ][14539] Returning information for job: 20190419205725066208
2019-04-19 20:58:10,592 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command state.apply with jid 20190419205810584500
2019-04-19 20:58:10,606 [salt.minion      :1432][INFO    ][15061] Starting a new job with PID 15061
2019-04-19 20:58:16,377 [salt.state       :915 ][INFO    ][15061] Loading fresh modules for state activity
2019-04-19 20:58:16,484 [salt.state       :1780][INFO    ][15061] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:58:16.484852
2019-04-19 20:58:16,485 [salt.state       :1813][INFO    ][15061] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-19 20:58:16,486 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15061] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-19 20:58:18,282 [salt.state       :300 ][INFO    ][15061] {'pid': 15084, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-19 20:58:18,284 [salt.state       :1951][INFO    ][15061] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:58:18.284129 duration_in_ms=1799.276
2019-04-19 20:58:18,287 [salt.state       :1780][INFO    ][15061] Running state [maas.process_machines] at time 20:58:18.287043
2019-04-19 20:58:18,287 [salt.state       :1813][INFO    ][15061] Executing state module.run for [maas.process_machines]
2019-04-19 20:58:18,289 [salt.utils.decorators:613 ][WARNING ][15061] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:58:18,769 [salt.loaded.ext.module.maas:412 ][WARNING ][15061] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-19 20:58:18,770 [salt.loaded.ext.module.maas:92  ][INFO    ][15061] 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=tbqckf architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-19 20:58:19,868 [salt.loaded.ext.module.maas:412 ][WARNING ][15061] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-19 20:58:19,869 [salt.loaded.ext.module.maas:92  ][INFO    ][15061] 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=7m7f38 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-19 20:58:21,009 [salt.loaded.ext.module.maas:412 ][WARNING ][15061] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-19 20:58:21,010 [salt.loaded.ext.module.maas:92  ][INFO    ][15061] 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=hbyp7h architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-19 20:58:22,252 [salt.loaded.ext.module.maas:412 ][WARNING ][15061] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-19 20:58:22,253 [salt.loaded.ext.module.maas:92  ][INFO    ][15061] 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=nsxrga architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-19 20:58:23,378 [salt.state       :300 ][INFO    ][15061] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-04-19 20:58:23,379 [salt.state       :1951][INFO    ][15061] Completed state [maas.process_machines] at time 20:58:23.378800 duration_in_ms=5091.755
2019-04-19 20:58:23,382 [salt.minion      :1711][INFO    ][15061] Returning information for job: 20190419205810584500
2019-04-19 20:58:55,784 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command state.apply with jid 20190419205855773806
2019-04-19 20:58:55,807 [salt.minion      :1432][INFO    ][15332] Starting a new job with PID 15332
2019-04-19 20:59:01,540 [salt.state       :915 ][INFO    ][15332] Loading fresh modules for state activity
2019-04-19 20:59:01,631 [salt.state       :1780][INFO    ][15332] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:01.631339
2019-04-19 20:59:01,631 [salt.state       :1813][INFO    ][15332] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-19 20:59:01,633 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15332] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-19 20:59:03,388 [salt.state       :300 ][INFO    ][15332] {'pid': 15339, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-19 20:59:03,389 [salt.state       :1951][INFO    ][15332] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:03.388929 duration_in_ms=1757.59
2019-04-19 20:59:03,391 [salt.state       :1780][INFO    ][15332] Running state [maas.wait_for_machine_status] at time 20:59:03.391499
2019-04-19 20:59:03,391 [salt.state       :1813][INFO    ][15332] Executing state module.run for [maas.wait_for_machine_status]
2019-04-19 20:59:03,392 [salt.utils.decorators:613 ][WARNING ][15332] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:59:04,948 [salt.state       :300 ][INFO    ][15332] {'ret': True}
2019-04-19 20:59:04,948 [salt.state       :1951][INFO    ][15332] Completed state [maas.wait_for_machine_status] at time 20:59:04.948931 duration_in_ms=1557.431
2019-04-19 20:59:04,951 [salt.minion      :1711][INFO    ][15332] Returning information for job: 20190419205855773806
2019-04-19 20:59:05,466 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command state.apply with jid 20190419205905457681
2019-04-19 20:59:05,482 [salt.minion      :1432][INFO    ][15352] Starting a new job with PID 15352
2019-04-19 20:59:06,366 [salt.state       :915 ][INFO    ][15352] Loading fresh modules for state activity
2019-04-19 20:59:06,504 [salt.state       :1780][INFO    ][15352] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:06.504327
2019-04-19 20:59:06,504 [salt.state       :1813][INFO    ][15352] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-19 20:59:06,506 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15352] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-19 20:59:08,314 [salt.state       :300 ][INFO    ][15352] {'pid': 15359, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-19 20:59:08,314 [salt.state       :1951][INFO    ][15352] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:08.314588 duration_in_ms=1810.261
2019-04-19 20:59:08,316 [salt.state       :1780][INFO    ][15352] Running state [maas_machines_storage_cmp002_lvm] at time 20:59:08.316867
2019-04-19 20:59:08,317 [salt.state       :1813][INFO    ][15352] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-19 20:59:08,777 [salt.state       :300 ][INFO    ][15352] Machine cmp002 is not in Ready state.
2019-04-19 20:59:08,777 [salt.state       :1951][INFO    ][15352] Completed state [maas_machines_storage_cmp002_lvm] at time 20:59:08.777496 duration_in_ms=460.629
2019-04-19 20:59:08,777 [salt.state       :1780][INFO    ][15352] Running state [maas_machines_storage_cmp001_lvm] at time 20:59:08.777944
2019-04-19 20:59:08,778 [salt.state       :1813][INFO    ][15352] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-19 20:59:09,182 [salt.state       :300 ][INFO    ][15352] Machine cmp001 is not in Ready state.
2019-04-19 20:59:09,182 [salt.state       :1951][INFO    ][15352] Completed state [maas_machines_storage_cmp001_lvm] at time 20:59:09.182426 duration_in_ms=404.482
2019-04-19 20:59:09,187 [salt.minion      :1711][INFO    ][15352] Returning information for job: 20190419205905457681
2019-04-19 20:59:09,776 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command state.apply with jid 20190419205909730688
2019-04-19 20:59:09,791 [salt.minion      :1432][INFO    ][15378] Starting a new job with PID 15378
2019-04-19 20:59:10,654 [salt.state       :915 ][INFO    ][15378] Loading fresh modules for state activity
2019-04-19 20:59:10,746 [salt.state       :1780][INFO    ][15378] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:10.745998
2019-04-19 20:59:10,746 [salt.state       :1813][INFO    ][15378] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-19 20:59:10,748 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15378] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-19 20:59:12,545 [salt.state       :300 ][INFO    ][15378] {'pid': 15385, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-19 20:59:12,546 [salt.state       :1951][INFO    ][15378] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:12.546220 duration_in_ms=1800.221
2019-04-19 20:59:12,549 [salt.state       :1780][INFO    ][15378] Running state [maas.deploy_machines] at time 20:59:12.549222
2019-04-19 20:59:12,549 [salt.state       :1813][INFO    ][15378] Executing state module.run for [maas.deploy_machines]
2019-04-19 20:59:12,551 [salt.utils.decorators:613 ][WARNING ][15378] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:59:13,014 [salt.loaded.ext.module.maas:684 ][INFO    ][15378] deploymachines hwe_kernel=hwe-16.04 system_id=tbqckf distro_series=xenial
2019-04-19 20:59:15,394 [salt.state       :300 ][INFO    ][15378] {'ret': {'updated': ['cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': ['gtw01']}}
2019-04-19 20:59:15,395 [salt.state       :1951][INFO    ][15378] Completed state [maas.deploy_machines] at time 20:59:15.395464 duration_in_ms=2846.241
2019-04-19 20:59:15,402 [salt.minion      :1711][INFO    ][15378] Returning information for job: 20190419205909730688
2019-04-19 20:59:15,958 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command state.apply with jid 20190419205915947775
2019-04-19 20:59:15,980 [salt.minion      :1432][INFO    ][15445] Starting a new job with PID 15445
2019-04-19 20:59:16,844 [salt.state       :915 ][INFO    ][15445] Loading fresh modules for state activity
2019-04-19 20:59:16,933 [salt.state       :1780][INFO    ][15445] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:16.933465
2019-04-19 20:59:16,933 [salt.state       :1813][INFO    ][15445] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-19 20:59:16,936 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15445] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-19 20:59:18,724 [salt.state       :300 ][INFO    ][15445] {'pid': 15452, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-19 20:59:18,726 [salt.state       :1951][INFO    ][15445] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:18.725840 duration_in_ms=1792.373
2019-04-19 20:59:18,729 [salt.state       :1780][INFO    ][15445] Running state [maas.wait_for_machine_status] at time 20:59:18.729777
2019-04-19 20:59:18,730 [salt.state       :1813][INFO    ][15445] Executing state module.run for [maas.wait_for_machine_status]
2019-04-19 20:59:18,731 [salt.utils.decorators:613 ][WARNING ][15445] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-19 20:59:20,585 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2248.15939593s left)
2019-04-19 20:59:31,009 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419205930995642
2019-04-19 20:59:31,031 [salt.minion      :1432][INFO    ][15468] Starting a new job with PID 15468
2019-04-19 20:59:31,055 [salt.minion      :1711][INFO    ][15468] Returning information for job: 20190419205930995642
2019-04-19 20:59:52,459 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2216.2851088s left)
2019-04-19 21:00:01,090 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210001073839
2019-04-19 21:00:01,111 [salt.minion      :1432][INFO    ][15548] Starting a new job with PID 15548
2019-04-19 21:00:01,136 [salt.minion      :1711][INFO    ][15548] Returning information for job: 20190419210001073839
2019-04-19 21:00:24,272 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2184.47230983s left)
2019-04-19 21:00:31,148 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210031133217
2019-04-19 21:00:31,170 [salt.minion      :1432][INFO    ][15568] Starting a new job with PID 15568
2019-04-19 21:00:31,193 [salt.minion      :1711][INFO    ][15568] Returning information for job: 20190419210031133217
2019-04-19 21:00:56,129 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2152.6149888s left)
2019-04-19 21:01:01,221 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210101210802
2019-04-19 21:01:01,243 [salt.minion      :1432][INFO    ][15667] Starting a new job with PID 15667
2019-04-19 21:01:01,267 [salt.minion      :1711][INFO    ][15667] Returning information for job: 20190419210101210802
2019-04-19 21:01:28,027 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2120.71656299s left)
2019-04-19 21:01:31,272 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210131261667
2019-04-19 21:01:31,294 [salt.minion      :1432][INFO    ][15691] Starting a new job with PID 15691
2019-04-19 21:01:31,319 [salt.minion      :1711][INFO    ][15691] Returning information for job: 20190419210131261667
2019-04-19 21:01:59,782 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2088.96222591s left)
2019-04-19 21:02:01,490 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210201483669
2019-04-19 21:02:01,510 [salt.minion      :1432][INFO    ][15776] Starting a new job with PID 15776
2019-04-19 21:02:01,534 [salt.minion      :1711][INFO    ][15776] Returning information for job: 20190419210201483669
2019-04-19 21:02:31,382 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2057.36195183s left)
2019-04-19 21:02:31,553 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210231540158
2019-04-19 21:02:31,572 [salt.minion      :1432][INFO    ][15796] Starting a new job with PID 15796
2019-04-19 21:02:31,606 [salt.minion      :1711][INFO    ][15796] Returning information for job: 20190419210231540158
2019-04-19 21:03:01,757 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210301748692
2019-04-19 21:03:01,774 [salt.minion      :1432][INFO    ][15872] Starting a new job with PID 15872
2019-04-19 21:03:01,794 [salt.minion      :1711][INFO    ][15872] Returning information for job: 20190419210301748692
2019-04-19 21:03:03,249 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2025.49487495s left)
2019-04-19 21:03:31,964 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210331956896
2019-04-19 21:03:31,979 [salt.minion      :1432][INFO    ][15914] Starting a new job with PID 15914
2019-04-19 21:03:32,003 [salt.minion      :1711][INFO    ][15914] Returning information for job: 20190419210331956896
2019-04-19 21:03:35,003 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1993.74066782s left)
2019-04-19 21:04:02,037 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210402022758
2019-04-19 21:04:02,056 [salt.minion      :1432][INFO    ][16001] Starting a new job with PID 16001
2019-04-19 21:04:02,080 [salt.minion      :1711][INFO    ][16001] Returning information for job: 20190419210402022758
2019-04-19 21:04:06,946 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1961.79789901s left)
2019-04-19 21:04:32,109 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210432102615
2019-04-19 21:04:32,121 [salt.minion      :1432][INFO    ][16046] Starting a new job with PID 16046
2019-04-19 21:04:32,144 [salt.minion      :1711][INFO    ][16046] Returning information for job: 20190419210432102615
2019-04-19 21:04:38,830 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1929.91400695s left)
2019-04-19 21:05:02,212 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210502196210
2019-04-19 21:05:02,234 [salt.minion      :1432][INFO    ][16274] Starting a new job with PID 16274
2019-04-19 21:05:02,261 [salt.minion      :1711][INFO    ][16274] Returning information for job: 20190419210502196210
2019-04-19 21:05:10,734 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1898.00999999s left)
2019-04-19 21:05:32,309 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210532296232
2019-04-19 21:05:32,328 [salt.minion      :1432][INFO    ][16294] Starting a new job with PID 16294
2019-04-19 21:05:32,351 [salt.minion      :1711][INFO    ][16294] Returning information for job: 20190419210532296232
2019-04-19 21:05:42,629 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1866.11495495s left)
2019-04-19 21:06:02,404 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210602390260
2019-04-19 21:06:02,426 [salt.minion      :1432][INFO    ][16395] Starting a new job with PID 16395
2019-04-19 21:06:02,450 [salt.minion      :1711][INFO    ][16395] Returning information for job: 20190419210602390260
2019-04-19 21:06:14,552 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1834.19207883s left)
2019-04-19 21:06:32,526 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210632514716
2019-04-19 21:06:32,546 [salt.minion      :1432][INFO    ][16421] Starting a new job with PID 16421
2019-04-19 21:06:32,570 [salt.minion      :1711][INFO    ][16421] Returning information for job: 20190419210632514716
2019-04-19 21:06:46,544 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1802.19965887s left)
2019-04-19 21:07:02,653 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210702641021
2019-04-19 21:07:02,676 [salt.minion      :1432][INFO    ][16620] Starting a new job with PID 16620
2019-04-19 21:07:02,698 [salt.minion      :1711][INFO    ][16620] Returning information for job: 20190419210702641021
2019-04-19 21:07:18,490 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1770.25439286s left)
2019-04-19 21:07:32,779 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210732764190
2019-04-19 21:07:32,802 [salt.minion      :1432][INFO    ][16646] Starting a new job with PID 16646
2019-04-19 21:07:32,826 [salt.minion      :1711][INFO    ][16646] Returning information for job: 20190419210732764190
2019-04-19 21:07:50,186 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1738.55782485s left)
2019-04-19 21:08:02,916 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210802903710
2019-04-19 21:08:02,939 [salt.minion      :1432][INFO    ][16793] Starting a new job with PID 16793
2019-04-19 21:08:02,962 [salt.minion      :1711][INFO    ][16793] Returning information for job: 20190419210802903710
2019-04-19 21:08:22,316 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1706.42779398s left)
2019-04-19 21:08:33,080 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210833066022
2019-04-19 21:08:33,103 [salt.minion      :1432][INFO    ][16813] Starting a new job with PID 16813
2019-04-19 21:08:33,124 [salt.minion      :1711][INFO    ][16813] Returning information for job: 20190419210833066022
2019-04-19 21:08:53,933 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1674.81064987s left)
2019-04-19 21:09:03,223 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210903208203
2019-04-19 21:09:03,247 [salt.minion      :1432][INFO    ][16893] Starting a new job with PID 16893
2019-04-19 21:09:03,270 [salt.minion      :1711][INFO    ][16893] Returning information for job: 20190419210903208203
2019-04-19 21:09:25,816 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1642.9276979s left)
2019-04-19 21:09:33,381 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419210933373163
2019-04-19 21:09:33,404 [salt.minion      :1432][INFO    ][16913] Starting a new job with PID 16913
2019-04-19 21:09:33,427 [salt.minion      :1711][INFO    ][16913] Returning information for job: 20190419210933373163
2019-04-19 21:09:57,549 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1611.19479585s left)
2019-04-19 21:10:03,517 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419211003505108
2019-04-19 21:10:03,537 [salt.minion      :1432][INFO    ][16993] Starting a new job with PID 16993
2019-04-19 21:10:03,561 [salt.minion      :1711][INFO    ][16993] Returning information for job: 20190419211003505108
2019-04-19 21:10:29,418 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1579.32581687s left)
2019-04-19 21:10:33,684 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419211033669631
2019-04-19 21:10:33,704 [salt.minion      :1432][INFO    ][17017] Starting a new job with PID 17017
2019-04-19 21:10:33,728 [salt.minion      :1711][INFO    ][17017] Returning information for job: 20190419211033669631
2019-04-19 21:11:02,339 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1546.40508986s left)
2019-04-19 21:11:03,854 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419211103846108
2019-04-19 21:11:03,874 [salt.minion      :1432][INFO    ][17131] Starting a new job with PID 17131
2019-04-19 21:11:03,896 [salt.minion      :1711][INFO    ][17131] Returning information for job: 20190419211103846108
2019-04-19 21:11:34,025 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419211134017032
2019-04-19 21:11:34,045 [salt.minion      :1432][INFO    ][17187] Starting a new job with PID 17187
2019-04-19 21:11:34,067 [salt.minion      :1711][INFO    ][17187] Returning information for job: 20190419211134017032
2019-04-19 21:11:34,465 [salt.loaded.ext.module.maas:1023][INFO    ][15445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1514.27849388s left)
2019-04-19 21:11:53,675 [salt.utils.schedule:1377][INFO    ][6196] Running scheduled job: __mine_interval
2019-04-19 21:12:04,048 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command saltutil.find_job with jid 20190419211204033403
2019-04-19 21:12:04,068 [salt.minion      :1432][INFO    ][17495] Starting a new job with PID 17495
2019-04-19 21:12:04,093 [salt.minion      :1711][INFO    ][17495] Returning information for job: 20190419211204033403
2019-04-19 21:12:06,500 [salt.state       :300 ][INFO    ][15445] {'ret': True}
2019-04-19 21:12:06,500 [salt.state       :1951][INFO    ][15445] Completed state [maas.wait_for_machine_status] at time 21:12:06.500519 duration_in_ms=767770.741
2019-04-19 21:12:06,503 [salt.minion      :1711][INFO    ][15445] Returning information for job: 20190419205915947775
2019-04-19 22:02:10,317 [salt.minion      :1308][INFO    ][6196] User sudo_ubuntu Executing command cp.push_dir with jid 20190419220210309944
2019-04-19 22:02:10,339 [salt.minion      :1432][INFO    ][22528] Starting a new job with PID 22528
