2019-04-20 20:08:02,225 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-20 20:08:52,272 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-20 20:09:42,321 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-20 20:10:32,368 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-20 20:11:22,413 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-20 20:13:34,930 [salt.utils.decorators:613 ][WARNING ][2900] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 20:13:35,588 [salt.utils.decorators:613 ][WARNING ][2900] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 20:13:38,196 [salt.loaded.int.states.file:2298][WARNING ][3034] 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-20 20:13:50,226 [salt.state       :2022][WARNING ][3148] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-20 20:13:53,135 [salt.utils.decorators:613 ][WARNING ][3148] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 20:14:03,648 [salt.utils.decorators:613 ][WARNING ][3148] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 20:14:26,957 [salt.utils.decorators:613 ][WARNING ][3148] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 20:14:27,886 [salt.utils.decorators:613 ][WARNING ][3148] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 20:14:30,086 [salt.loaded.ext.module.maasng:1008][WARNING ][3148] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-20 20:14:30,086 [salt.loaded.ext.module.maasng:1011][WARNING ][3148] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-20 20:14:30,792 [salt.loaded.ext.module.maasng:1235][WARNING ][3148] Ignoring parameter vlan:0
2019-04-20 20:14:40,289 [salt.utils.decorators:613 ][WARNING ][5409] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 20:14:40,346 [salt.loaded.ext.module.maas:412 ][WARNING ][5409] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 20:14:41,594 [salt.loaded.ext.module.maas:412 ][WARNING ][5409] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 20:14:42,614 [salt.loaded.ext.module.maas:412 ][WARNING ][5409] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 20:14:43,701 [salt.loaded.ext.module.maas:412 ][WARNING ][5409] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 20:14:44,861 [salt.loaded.ext.module.maas:412 ][WARNING ][5409] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 20:14:50,345 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6018] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-20 20:14:50,377 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6018] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-20 20:14:50,418 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-04-20 20:14:51,412 [salt.cli.daemons :293 ][INFO    ][6085] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-04-20 20:14:51,533 [salt.cli.daemons :82  ][INFO    ][6085] Starting up the Salt Minion
2019-04-20 20:14:51,534 [salt.utils.event :1017][INFO    ][6085] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-04-20 20:14:52,680 [salt.minion      :976 ][INFO    ][6085] Creating minion process manager
2019-04-20 20:14:54,639 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6085] Executing command ['date', '+%z'] in directory '/root'
2019-04-20 20:14:54,665 [salt.utils.schedule:568 ][INFO    ][6085] Updating job settings for scheduled job: __mine_interval
2019-04-20 20:14:54,667 [salt.minion      :1108][INFO    ][6085] Added mine.update to scheduler
2019-04-20 20:14:54,672 [salt.minion      :1975][INFO    ][6085] Minion is starting as user 'root'
2019-04-20 20:14:54,686 [salt.minion      :2336][INFO    ][6085] Minion is ready to receive requests!
2019-04-20 20:15:16,906 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command state.apply with jid 20190420201516897292
2019-04-20 20:15:16,925 [salt.minion      :1432][INFO    ][6196] Starting a new job with PID 6196
2019-04-20 20:15:22,816 [salt.state       :915 ][INFO    ][6196] Loading fresh modules for state activity
2019-04-20 20:15:22,873 [salt.fileclient  :1219][INFO    ][6196] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-20 20:15:22,920 [salt.state       :1780][INFO    ][6196] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:22.920080
2019-04-20 20:15:22,920 [salt.state       :1813][INFO    ][6196] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 20:15:22,922 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6196] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 20:15:24,606 [salt.state       :300 ][INFO    ][6196] {'pid': 6203, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 20:15:24,607 [salt.state       :1951][INFO    ][6196] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:24.607402 duration_in_ms=1687.32
2019-04-20 20:15:24,611 [salt.state       :1780][INFO    ][6196] Running state [maas.wait_for_machine_status] at time 20:15:24.611630
2019-04-20 20:15:24,612 [salt.state       :1813][INFO    ][6196] Executing state module.run for [maas.wait_for_machine_status]
2019-04-20 20:15:24,612 [salt.utils.decorators:613 ][WARNING ][6196] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 20:15:25,421 [salt.loaded.ext.module.maas:1023][INFO    ][6196] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.20094609s left)
2019-04-20 20:15:31,967 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420201531959797
2019-04-20 20:15:31,989 [salt.minion      :1432][INFO    ][6217] Starting a new job with PID 6217
2019-04-20 20:15:32,015 [salt.minion      :1711][INFO    ][6217] Returning information for job: 20190420201531959797
2019-04-20 20:15:56,375 [salt.loaded.ext.module.maas:1023][INFO    ][6196] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.24662113s left)
2019-04-20 20:16:02,160 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420201602150333
2019-04-20 20:16:02,174 [salt.minion      :1432][INFO    ][6297] Starting a new job with PID 6297
2019-04-20 20:16:02,200 [salt.minion      :1711][INFO    ][6297] Returning information for job: 20190420201602150333
2019-04-20 20:16:27,141 [salt.loaded.ext.module.maas:1023][INFO    ][6196] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.48093891s left)
2019-04-20 20:16:32,206 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420201632197940
2019-04-20 20:16:32,226 [salt.minion      :1432][INFO    ][6317] Starting a new job with PID 6317
2019-04-20 20:16:32,248 [salt.minion      :1711][INFO    ][6317] Returning information for job: 20190420201632197940
2019-04-20 20:16:57,957 [salt.loaded.ext.module.maas:1023][INFO    ][6196] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.66547704s left)
2019-04-20 20:17:02,277 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420201702267099
2019-04-20 20:17:02,298 [salt.minion      :1432][INFO    ][6401] Starting a new job with PID 6401
2019-04-20 20:17:02,323 [salt.minion      :1711][INFO    ][6401] Returning information for job: 20190420201702267099
2019-04-20 20:17:28,973 [salt.loaded.ext.module.maas:1023][INFO    ][6196] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.64910507s left)
2019-04-20 20:17:32,328 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420201732316941
2019-04-20 20:17:32,349 [salt.minion      :1432][INFO    ][6449] Starting a new job with PID 6449
2019-04-20 20:17:32,371 [salt.minion      :1711][INFO    ][6449] Returning information for job: 20190420201732316941
2019-04-20 20:18:00,030 [salt.loaded.ext.module.maas:1023][INFO    ][6196] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.59233403s left)
2019-04-20 20:18:02,410 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420201802394596
2019-04-20 20:18:02,429 [salt.minion      :1432][INFO    ][6660] Starting a new job with PID 6660
2019-04-20 20:18:02,449 [salt.minion      :1711][INFO    ][6660] Returning information for job: 20190420201802394596
2019-04-20 20:18:30,930 [salt.loaded.ext.module.maas:1023][INFO    ][6196] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.69230795s left)
2019-04-20 20:18:32,471 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420201832458697
2019-04-20 20:18:32,489 [salt.minion      :1432][INFO    ][6731] Starting a new job with PID 6731
2019-04-20 20:18:32,509 [salt.minion      :1711][INFO    ][6731] Returning information for job: 20190420201832458697
2019-04-20 20:19:02,128 [salt.loaded.ext.module.maas:1023][INFO    ][6196] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.49372005s left)
2019-04-20 20:19:02,570 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420201902561352
2019-04-20 20:19:02,582 [salt.minion      :1432][INFO    ][7073] Starting a new job with PID 7073
2019-04-20 20:19:02,603 [salt.minion      :1711][INFO    ][7073] Returning information for job: 20190420201902561352
2019-04-20 20:19:32,608 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420201932600539
2019-04-20 20:19:32,623 [salt.minion      :1432][INFO    ][7159] Starting a new job with PID 7159
2019-04-20 20:19:32,642 [salt.minion      :1711][INFO    ][7159] Returning information for job: 20190420201932600539
2019-04-20 20:19:33,794 [salt.loaded.ext.module.maas:1023][INFO    ][6196] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.82815313s left)
2019-04-20 20:20:02,744 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202002730906
2019-04-20 20:20:02,765 [salt.minion      :1432][INFO    ][7544] Starting a new job with PID 7544
2019-04-20 20:20:02,783 [salt.minion      :1711][INFO    ][7544] Returning information for job: 20190420202002730906
2019-04-20 20:20:05,844 [salt.loaded.ext.module.maas:1023][INFO    ][6196] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.77837896s left)
2019-04-20 20:20:32,832 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202032824291
2019-04-20 20:20:32,853 [salt.minion      :1432][INFO    ][7680] Starting a new job with PID 7680
2019-04-20 20:20:32,881 [salt.minion      :1711][INFO    ][7680] Returning information for job: 20190420202032824291
2019-04-20 20:20:37,994 [salt.loaded.ext.module.maas:1023][INFO    ][6196] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.62842894s left)
2019-04-20 20:21:02,945 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202102930276
2019-04-20 20:21:02,970 [salt.minion      :1432][INFO    ][7910] Starting a new job with PID 7910
2019-04-20 20:21:02,998 [salt.minion      :1711][INFO    ][7910] Returning information for job: 20190420202102930276
2019-04-20 20:21:10,068 [salt.loaded.ext.module.maas:1023][INFO    ][6196] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1154.55413198s left)
2019-04-20 20:21:33,081 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202133067155
2019-04-20 20:21:33,101 [salt.minion      :1432][INFO    ][8060] Starting a new job with PID 8060
2019-04-20 20:21:33,129 [salt.minion      :1711][INFO    ][8060] Returning information for job: 20190420202133067155
2019-04-20 20:21:42,851 [salt.state       :300 ][INFO    ][6196] {'ret': True}
2019-04-20 20:21:42,852 [salt.state       :1951][INFO    ][6196] Completed state [maas.wait_for_machine_status] at time 20:21:42.852203 duration_in_ms=378240.572
2019-04-20 20:21:42,854 [salt.minion      :1711][INFO    ][6196] Returning information for job: 20190420201516897292
2019-04-20 20:21:43,535 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command state.apply with jid 20190420202143525587
2019-04-20 20:21:43,547 [salt.minion      :1432][INFO    ][8109] Starting a new job with PID 8109
2019-04-20 20:21:49,477 [salt.state       :915 ][INFO    ][8109] Loading fresh modules for state activity
2019-04-20 20:21:49,534 [salt.fileclient  :1219][INFO    ][8109] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-20 20:21:49,644 [salt.state       :1780][INFO    ][8109] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:49.644437
2019-04-20 20:21:49,644 [salt.state       :1813][INFO    ][8109] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 20:21:49,646 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8109] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 20:21:51,512 [salt.state       :300 ][INFO    ][8109] {'pid': 8145, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 20:21:51,513 [salt.state       :1951][INFO    ][8109] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:51.513459 duration_in_ms=1869.02
2019-04-20 20:21:51,518 [salt.state       :1780][INFO    ][8109] Running state [maas_machines_storage_cmp002_lvm] at time 20:21:51.517992
2019-04-20 20:21:51,518 [salt.state       :1813][INFO    ][8109] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-20 20:21:52,638 [salt.loaded.ext.module.maasng:610 ][INFO    ][8109] qa7csc
2019-04-20 20:21:52,639 [salt.loaded.ext.module.maasng:626 ][INFO    ][8109] sda
2019-04-20 20:21:53,400 [salt.loaded.ext.module.maasng:361 ][INFO    ][8109] qa7csc
2019-04-20 20:21:53,539 [salt.loaded.ext.module.maasng:367 ][INFO    ][8109] [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qa7csc', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'aaf3f597-47bf-4363-b418-bb78a5402b7b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qa7csc', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'dea7e588-5f80-4b78-83b9-d9524207371c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/blockdevices/1/'}, {u'size': 800097042432, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'38ab9b97-5eff-4841-9dc0-a5b22d9aa9a1', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qa7csc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'17811a1e-7236-4b7f-8203-b111c7b8a867', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'used_size': 800097042432, u'id': 3, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/blockdevices/3/'}]
2019-04-20 20:21:53,540 [salt.loaded.ext.module.maasng:632 ][INFO    ][8109] vgroot
2019-04-20 20:21:53,540 [salt.loaded.ext.module.maasng:635 ][INFO    ][8109] lvroot
2019-04-20 20:21:53,541 [salt.loaded.ext.module.maasng:639 ][INFO    ][8109] 107374182400
2019-04-20 20:21:54,205 [salt.loaded.ext.module.maasng:645 ][INFO    ][8109] {u'hwe_kernel': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'owner_data': {}, 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'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nx667k', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nx667k', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nx667k', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'system_id': u'qa7csc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/interfaces/4/'}, u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qa7csc', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'928484bd-ca33-4e20-a3b7-69c38a822460', u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qa7csc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f3039f50-a2e2-4f72-aabe-94f15f507cd2', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'qa7csc', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'38f17d81-f5de-46f1-a009-c1170fb50b55', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'qa7csc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'cc308315-89c4-4030-b24e-c84d4d0b56b8', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qa7csc', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'928484bd-ca33-4e20-a3b7-69c38a822460', u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qa7csc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f3039f50-a2e2-4f72-aabe-94f15f507cd2', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}]}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'cc308315-89c4-4030-b24e-c84d4d0b56b8', u'fstype': u'ext4'}, u'uuid': u'38f17d81-f5de-46f1-a009-c1170fb50b55', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qa7csc', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qa7csc', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'928484bd-ca33-4e20-a3b7-69c38a822460', u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qa7csc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f3039f50-a2e2-4f72-aabe-94f15f507cd2', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qa7csc', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nx667k', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nx667k', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nx667k', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'system_id': u'qa7csc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/interfaces/4/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'params': u'', u'system_id': u'qa7csc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/interfaces/13/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nx667k', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nx667k', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 14, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nx667k', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'params': u'', u'system_id': u'qa7csc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/interfaces/14/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 15, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'params': u'', u'system_id': u'qa7csc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/interfaces/15/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'params': u'', u'system_id': u'qa7csc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/interfaces/11/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 12, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'params': u'', u'system_id': u'qa7csc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qa7csc/interfaces/12/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/qa7csc/', u'distro_series': u'', u'memory_test_status': -1}
2019-04-20 20:21:54,209 [salt.state       :300 ][INFO    ][8109] {'new': {'storage_layout': 'lvm'}}
2019-04-20 20:21:54,209 [salt.state       :1951][INFO    ][8109] Completed state [maas_machines_storage_cmp002_lvm] at time 20:21:54.209303 duration_in_ms=2691.311
2019-04-20 20:21:54,209 [salt.state       :1780][INFO    ][8109] Running state [maas_machines_storage_cmp001_lvm] at time 20:21:54.209820
2019-04-20 20:21:54,210 [salt.state       :1813][INFO    ][8109] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-20 20:21:55,439 [salt.loaded.ext.module.maasng:610 ][INFO    ][8109] s6fkg7
2019-04-20 20:21:55,440 [salt.loaded.ext.module.maasng:626 ][INFO    ][8109] sda
2019-04-20 20:21:56,004 [salt.loaded.ext.module.maasng:361 ][INFO    ][8109] s6fkg7
2019-04-20 20:21:56,100 [salt.loaded.ext.module.maasng:367 ][INFO    ][8109] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'9d3b480f-023b-49e7-9683-4811989804db', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u's6fkg7', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5ea19caa-7d8d-4608-819c-0c881ba815bc', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/blockdevices/2/partition/2'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u's6fkg7', 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'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'7511af55-04cb-4bbe-a3bd-3683cda65a32', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u's6fkg7', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'89c949e1-0599-4391-a6e2-5aecea555b74', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/blockdevices/4/', u'used_size': 800097042432, u'id': 4, u'size': 800097042432}]
2019-04-20 20:21:56,101 [salt.loaded.ext.module.maasng:632 ][INFO    ][8109] vgroot
2019-04-20 20:21:56,101 [salt.loaded.ext.module.maasng:635 ][INFO    ][8109] lvroot
2019-04-20 20:21:56,102 [salt.loaded.ext.module.maasng:639 ][INFO    ][8109] 107374182400
2019-04-20 20:21:56,691 [salt.loaded.ext.module.maasng:645 ][INFO    ][8109] {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'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'vlan': {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'nx667k', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'nx667k', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 26}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'nx667k', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u's6fkg7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/s6fkg7/', u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u's6fkg7', 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'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u's6fkg7', u'partition_table_type': u'MBR', u'available_size': 0, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'30a6974f-ffdf-4571-857b-0cbc1916cbd1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u's6fkg7', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'c1dbe596-0a1d-4835-ae44-1716ea7afea6', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/blockdevices/2/partition/7'}], u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/blockdevices/2/'}, {u'size': 107374182400, u'block_size': 4096, u'uuid': u'cd5859b7-c367-4433-9276-cc31ea2bba5e', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'59c2396f-2aab-45b1-9442-f7ded9d22398', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u's6fkg7', u'partition_table_type': None, u'available_size': 0, u'id': 12, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u's6fkg7', u'partition_table_type': u'MBR', u'available_size': 0, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'30a6974f-ffdf-4571-857b-0cbc1916cbd1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u's6fkg7', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'c1dbe596-0a1d-4835-ae44-1716ea7afea6', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/blockdevices/2/partition/7'}], u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/blockdevices/2/'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u's6fkg7', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u's6fkg7', u'partition_table_type': u'MBR', u'available_size': 0, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'30a6974f-ffdf-4571-857b-0cbc1916cbd1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u's6fkg7', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'c1dbe596-0a1d-4835-ae44-1716ea7afea6', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/blockdevices/2/partition/7'}], u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/blockdevices/2/'}, u'interface_set': [{u'vlan': {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'nx667k', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'nx667k', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 26}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'nx667k', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u's6fkg7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'id': 16, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u's6fkg7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/interfaces/16/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'id': 17, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u's6fkg7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/interfaces/17/'}, {u'vlan': {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'nx667k', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'nx667k', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'id': 18, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'nx667k', u'name': u'untagged', 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.44'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u's6fkg7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/interfaces/18/'}, {u'vlan': {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'ens2f0', u'links': [{u'mode': u'link_up', u'id': 28}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'id': 19, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u's6fkg7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/interfaces/19/'}, {u'vlan': {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'ens2f1', u'links': [{u'mode': u'link_up', u'id': 29}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'id': 20, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u's6fkg7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'uuid': u'cd5859b7-c367-4433-9276-cc31ea2bba5e', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'59c2396f-2aab-45b1-9442-f7ded9d22398', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u's6fkg7', u'partition_table_type': None, u'available_size': 0, u'id': 12, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/s6fkg7/blockdevices/12/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-04-20 20:21:56,693 [salt.state       :300 ][INFO    ][8109] {'new': {'storage_layout': 'lvm'}}
2019-04-20 20:21:56,694 [salt.state       :1951][INFO    ][8109] Completed state [maas_machines_storage_cmp001_lvm] at time 20:21:56.694041 duration_in_ms=2484.219
2019-04-20 20:21:56,698 [salt.minion      :1711][INFO    ][8109] Returning information for job: 20190420202143525587
2019-04-20 20:21:57,390 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command state.apply with jid 20190420202157374151
2019-04-20 20:21:57,408 [salt.minion      :1432][INFO    ][8234] Starting a new job with PID 8234
2019-04-20 20:21:58,339 [salt.state       :915 ][INFO    ][8234] Loading fresh modules for state activity
2019-04-20 20:21:58,393 [salt.fileclient  :1219][INFO    ][8234] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-20 20:21:58,442 [salt.state       :1780][INFO    ][8234] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:58.441988
2019-04-20 20:21:58,442 [salt.state       :1813][INFO    ][8234] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 20:21:58,444 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8234] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 20:22:00,274 [salt.state       :300 ][INFO    ][8234] {'pid': 8241, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 20:22:00,275 [salt.state       :1951][INFO    ][8234] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:22:00.275667 duration_in_ms=1833.68
2019-04-20 20:22:00,278 [salt.state       :1780][INFO    ][8234] Running state [maas.deploy_machines] at time 20:22:00.278151
2019-04-20 20:22:00,279 [salt.state       :1813][INFO    ][8234] Executing state module.run for [maas.deploy_machines]
2019-04-20 20:22:00,281 [salt.utils.decorators:613 ][WARNING ][8234] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 20:22:00,854 [salt.loaded.ext.module.maas:684 ][INFO    ][8234] deploymachines hwe_kernel=hwe-16.04 system_id=qa7csc distro_series=xenial
2019-04-20 20:22:03,313 [salt.loaded.ext.module.maas:684 ][INFO    ][8234] deploymachines hwe_kernel=hwe-16.04 system_id=s6fkg7 distro_series=xenial
2019-04-20 20:22:05,721 [salt.loaded.ext.module.maas:684 ][INFO    ][8234] deploymachines hwe_kernel=hwe-16.04 system_id=tn4nke distro_series=xenial
2019-04-20 20:22:08,075 [salt.loaded.ext.module.maas:684 ][INFO    ][8234] deploymachines hwe_kernel=hwe-16.04 system_id=hhn3bm distro_series=xenial
2019-04-20 20:22:10,489 [salt.loaded.ext.module.maas:684 ][INFO    ][8234] deploymachines hwe_kernel=hwe-16.04 system_id=ege3db distro_series=xenial
2019-04-20 20:22:12,491 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202212481386
2019-04-20 20:22:12,504 [salt.minion      :1432][INFO    ][8505] Starting a new job with PID 8505
2019-04-20 20:22:12,534 [salt.minion      :1711][INFO    ][8505] Returning information for job: 20190420202212481386
2019-04-20 20:22:12,882 [salt.state       :300 ][INFO    ][8234] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-20 20:22:12,884 [salt.state       :1951][INFO    ][8234] Completed state [maas.deploy_machines] at time 20:22:12.884127 duration_in_ms=12605.976
2019-04-20 20:22:12,886 [salt.minion      :1711][INFO    ][8234] Returning information for job: 20190420202157374151
2019-04-20 20:22:13,561 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command state.apply with jid 20190420202213549725
2019-04-20 20:22:13,578 [salt.minion      :1432][INFO    ][8520] Starting a new job with PID 8520
2019-04-20 20:22:19,507 [salt.state       :915 ][INFO    ][8520] Loading fresh modules for state activity
2019-04-20 20:22:19,560 [salt.fileclient  :1219][INFO    ][8520] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-20 20:22:19,611 [salt.state       :1780][INFO    ][8520] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:22:19.611789
2019-04-20 20:22:19,612 [salt.state       :1813][INFO    ][8520] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 20:22:19,614 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8520] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 20:22:21,450 [salt.state       :300 ][INFO    ][8520] {'pid': 8533, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 20:22:21,451 [salt.state       :1951][INFO    ][8520] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:22:21.451614 duration_in_ms=1839.823
2019-04-20 20:22:21,455 [salt.state       :1780][INFO    ][8520] Running state [maas.wait_for_machine_status] at time 20:22:21.455711
2019-04-20 20:22:21,456 [salt.state       :1813][INFO    ][8520] Executing state module.run for [maas.wait_for_machine_status]
2019-04-20 20:22:21,457 [salt.utils.decorators:613 ][WARNING ][8520] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 20:22:24,225 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.24520302s left)
2019-04-20 20:22:28,580 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202228565751
2019-04-20 20:22:28,602 [salt.minion      :1432][INFO    ][8546] Starting a new job with PID 8546
2019-04-20 20:22:28,628 [salt.minion      :1711][INFO    ][8546] Returning information for job: 20190420202228565751
2019-04-20 20:22:57,169 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.30130601s left)
2019-04-20 20:22:58,637 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202258622129
2019-04-20 20:22:58,654 [salt.minion      :1432][INFO    ][8638] Starting a new job with PID 8638
2019-04-20 20:22:58,682 [salt.minion      :1711][INFO    ][8638] Returning information for job: 20190420202258622129
2019-04-20 20:23:28,688 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202328680401
2019-04-20 20:23:28,703 [salt.minion      :1432][INFO    ][8657] Starting a new job with PID 8657
2019-04-20 20:23:28,734 [salt.minion      :1711][INFO    ][8657] Returning information for job: 20190420202328680401
2019-04-20 20:23:29,984 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.48605204s left)
2019-04-20 20:23:58,734 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202358726593
2019-04-20 20:23:58,747 [salt.minion      :1432][INFO    ][8750] Starting a new job with PID 8750
2019-04-20 20:23:58,773 [salt.minion      :1711][INFO    ][8750] Returning information for job: 20190420202358726593
2019-04-20 20:24:02,904 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.56571412s left)
2019-04-20 20:24:28,782 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202428770197
2019-04-20 20:24:28,806 [salt.minion      :1432][INFO    ][8778] Starting a new job with PID 8778
2019-04-20 20:24:28,832 [salt.minion      :1711][INFO    ][8778] Returning information for job: 20190420202428770197
2019-04-20 20:24:35,550 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.92007208s left)
2019-04-20 20:24:58,838 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202458829054
2019-04-20 20:24:58,856 [salt.minion      :1432][INFO    ][8880] Starting a new job with PID 8880
2019-04-20 20:24:58,883 [salt.minion      :1711][INFO    ][8880] Returning information for job: 20190420202458829054
2019-04-20 20:25:08,277 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.19327807s left)
2019-04-20 20:25:28,905 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202528891687
2019-04-20 20:25:28,926 [salt.minion      :1432][INFO    ][8977] Starting a new job with PID 8977
2019-04-20 20:25:28,953 [salt.minion      :1711][INFO    ][8977] Returning information for job: 20190420202528891687
2019-04-20 20:25:41,072 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.39770103s left)
2019-04-20 20:25:59,024 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202559013832
2019-04-20 20:25:59,042 [salt.minion      :1432][INFO    ][9311] Starting a new job with PID 9311
2019-04-20 20:25:59,070 [salt.minion      :1711][INFO    ][9311] Returning information for job: 20190420202559013832
2019-04-20 20:26:13,878 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.59191322s left)
2019-04-20 20:26:29,072 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202629065800
2019-04-20 20:26:29,086 [salt.minion      :1432][INFO    ][9373] Starting a new job with PID 9373
2019-04-20 20:26:29,112 [salt.minion      :1711][INFO    ][9373] Returning information for job: 20190420202629065800
2019-04-20 20:26:46,763 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.70659208s left)
2019-04-20 20:26:59,141 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202659127583
2019-04-20 20:26:59,159 [salt.minion      :1432][INFO    ][9582] Starting a new job with PID 9582
2019-04-20 20:26:59,185 [salt.minion      :1711][INFO    ][9582] Returning information for job: 20190420202659127583
2019-04-20 20:27:19,680 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.78961205s left)
2019-04-20 20:27:29,202 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202729187872
2019-04-20 20:27:29,220 [salt.minion      :1432][INFO    ][9630] Starting a new job with PID 9630
2019-04-20 20:27:29,250 [salt.minion      :1711][INFO    ][9630] Returning information for job: 20190420202729187872
2019-04-20 20:27:52,489 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.98128605s left)
2019-04-20 20:27:59,389 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202759376134
2019-04-20 20:27:59,411 [salt.minion      :1432][INFO    ][10227] Starting a new job with PID 10227
2019-04-20 20:27:59,438 [salt.minion      :1711][INFO    ][10227] Returning information for job: 20190420202759376134
2019-04-20 20:28:25,484 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.98617315s left)
2019-04-20 20:28:29,480 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202829465387
2019-04-20 20:28:29,498 [salt.minion      :1432][INFO    ][10250] Starting a new job with PID 10250
2019-04-20 20:28:29,526 [salt.minion      :1711][INFO    ][10250] Returning information for job: 20190420202829465387
2019-04-20 20:28:58,445 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1853.02510619s left)
2019-04-20 20:28:59,626 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202859618122
2019-04-20 20:28:59,647 [salt.minion      :1432][INFO    ][10634] Starting a new job with PID 10634
2019-04-20 20:28:59,673 [salt.minion      :1711][INFO    ][10634] Returning information for job: 20190420202859618122
2019-04-20 20:29:29,723 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202929713110
2019-04-20 20:29:29,738 [salt.minion      :1432][INFO    ][10664] Starting a new job with PID 10664
2019-04-20 20:29:29,766 [salt.minion      :1711][INFO    ][10664] Returning information for job: 20190420202929713110
2019-04-20 20:29:31,269 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1820.20091414s left)
2019-04-20 20:29:59,881 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420202959868345
2019-04-20 20:29:59,901 [salt.minion      :1432][INFO    ][10956] Starting a new job with PID 10956
2019-04-20 20:29:59,929 [salt.minion      :1711][INFO    ][10956] Returning information for job: 20190420202959868345
2019-04-20 20:30:04,231 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1787.23857617s left)
2019-04-20 20:30:30,000 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203029993952
2019-04-20 20:30:30,012 [salt.minion      :1432][INFO    ][10997] Starting a new job with PID 10997
2019-04-20 20:30:30,038 [salt.minion      :1711][INFO    ][10997] Returning information for job: 20190420203029993952
2019-04-20 20:30:36,959 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1754.51053405s left)
2019-04-20 20:31:00,156 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203100139270
2019-04-20 20:31:00,175 [salt.minion      :1432][INFO    ][11248] Starting a new job with PID 11248
2019-04-20 20:31:00,203 [salt.minion      :1711][INFO    ][11248] Returning information for job: 20190420203100139270
2019-04-20 20:31:09,940 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1721.52974415s left)
2019-04-20 20:31:30,302 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203130287730
2019-04-20 20:31:30,323 [salt.minion      :1432][INFO    ][11290] Starting a new job with PID 11290
2019-04-20 20:31:30,349 [salt.minion      :1711][INFO    ][11290] Returning information for job: 20190420203130287730
2019-04-20 20:31:42,690 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1688.77978706s left)
2019-04-20 20:32:00,378 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203200364022
2019-04-20 20:32:00,395 [salt.minion      :1432][INFO    ][11509] Starting a new job with PID 11509
2019-04-20 20:32:00,420 [salt.minion      :1711][INFO    ][11509] Returning information for job: 20190420203200364022
2019-04-20 20:32:15,484 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1655.98597813s left)
2019-04-20 20:32:30,452 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203230436646
2019-04-20 20:32:30,472 [salt.minion      :1432][INFO    ][11543] Starting a new job with PID 11543
2019-04-20 20:32:30,499 [salt.minion      :1711][INFO    ][11543] Returning information for job: 20190420203230436646
2019-04-20 20:32:48,378 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1623.09187818s left)
2019-04-20 20:33:00,609 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203300599381
2019-04-20 20:33:00,631 [salt.minion      :1432][INFO    ][11778] Starting a new job with PID 11778
2019-04-20 20:33:00,659 [salt.minion      :1711][INFO    ][11778] Returning information for job: 20190420203300599381
2019-04-20 20:33:21,223 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1590.24682617s left)
2019-04-20 20:33:30,782 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203330767746
2019-04-20 20:33:30,801 [salt.minion      :1432][INFO    ][11799] Starting a new job with PID 11799
2019-04-20 20:33:30,829 [salt.minion      :1711][INFO    ][11799] Returning information for job: 20190420203330767746
2019-04-20 20:33:54,161 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1557.309129s left)
2019-04-20 20:34:00,947 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203400932500
2019-04-20 20:34:00,967 [salt.minion      :1432][INFO    ][12023] Starting a new job with PID 12023
2019-04-20 20:34:00,994 [salt.minion      :1711][INFO    ][12023] Returning information for job: 20190420203400932500
2019-04-20 20:34:26,959 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1524.51104116s left)
2019-04-20 20:34:31,141 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203431128615
2019-04-20 20:34:31,158 [salt.minion      :1432][INFO    ][12069] Starting a new job with PID 12069
2019-04-20 20:34:31,186 [salt.minion      :1711][INFO    ][12069] Returning information for job: 20190420203431128615
2019-04-20 20:34:59,903 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1491.56706405s left)
2019-04-20 20:35:01,314 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203501301185
2019-04-20 20:35:01,334 [salt.minion      :1432][INFO    ][12335] Starting a new job with PID 12335
2019-04-20 20:35:01,364 [salt.minion      :1711][INFO    ][12335] Returning information for job: 20190420203501301185
2019-04-20 20:35:31,446 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203531436998
2019-04-20 20:35:31,461 [salt.minion      :1432][INFO    ][12373] Starting a new job with PID 12373
2019-04-20 20:35:31,489 [salt.minion      :1711][INFO    ][12373] Returning information for job: 20190420203531436998
2019-04-20 20:35:32,647 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1458.82354403s left)
2019-04-20 20:36:01,652 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203601638496
2019-04-20 20:36:01,673 [salt.minion      :1432][INFO    ][12570] Starting a new job with PID 12570
2019-04-20 20:36:01,701 [salt.minion      :1711][INFO    ][12570] Returning information for job: 20190420203601638496
2019-04-20 20:36:05,509 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1425.9607861s left)
2019-04-20 20:36:31,673 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203631659688
2019-04-20 20:36:31,691 [salt.minion      :1432][INFO    ][12590] Starting a new job with PID 12590
2019-04-20 20:36:31,717 [salt.minion      :1711][INFO    ][12590] Returning information for job: 20190420203631659688
2019-04-20 20:36:38,501 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1392.9693861s left)
2019-04-20 20:37:01,881 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203701874129
2019-04-20 20:37:01,895 [salt.minion      :1432][INFO    ][12671] Starting a new job with PID 12671
2019-04-20 20:37:01,921 [salt.minion      :1711][INFO    ][12671] Returning information for job: 20190420203701874129
2019-04-20 20:37:11,395 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1360.07485914s left)
2019-04-20 20:37:32,092 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203732083437
2019-04-20 20:37:32,111 [salt.minion      :1432][INFO    ][12699] Starting a new job with PID 12699
2019-04-20 20:37:32,141 [salt.minion      :1711][INFO    ][12699] Returning information for job: 20190420203732083437
2019-04-20 20:37:44,225 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1327.24464822s left)
2019-04-20 20:38:02,145 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203802132749
2019-04-20 20:38:02,168 [salt.minion      :1432][INFO    ][12782] Starting a new job with PID 12782
2019-04-20 20:38:02,197 [salt.minion      :1711][INFO    ][12782] Returning information for job: 20190420203802132749
2019-04-20 20:38:16,878 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1294.591995s left)
2019-04-20 20:38:32,183 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203832172477
2019-04-20 20:38:32,204 [salt.minion      :1432][INFO    ][12805] Starting a new job with PID 12805
2019-04-20 20:38:32,233 [salt.minion      :1711][INFO    ][12805] Returning information for job: 20190420203832172477
2019-04-20 20:38:49,490 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1261.98034215s left)
2019-04-20 20:39:02,261 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203902248066
2019-04-20 20:39:02,280 [salt.minion      :1432][INFO    ][12887] Starting a new job with PID 12887
2019-04-20 20:39:02,312 [salt.minion      :1711][INFO    ][12887] Returning information for job: 20190420203902248066
2019-04-20 20:39:22,156 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1229.3137362s left)
2019-04-20 20:39:32,314 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420203932300620
2019-04-20 20:39:32,334 [salt.minion      :1432][INFO    ][12915] Starting a new job with PID 12915
2019-04-20 20:39:32,361 [salt.minion      :1711][INFO    ][12915] Returning information for job: 20190420203932300620
2019-04-20 20:39:55,113 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1196.35719204s left)
2019-04-20 20:40:02,399 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204002390704
2019-04-20 20:40:02,420 [salt.minion      :1432][INFO    ][12997] Starting a new job with PID 12997
2019-04-20 20:40:02,449 [salt.minion      :1711][INFO    ][12997] Returning information for job: 20190420204002390704
2019-04-20 20:40:27,967 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1163.50297618s left)
2019-04-20 20:40:32,477 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204032460707
2019-04-20 20:40:32,495 [salt.minion      :1432][INFO    ][13018] Starting a new job with PID 13018
2019-04-20 20:40:32,528 [salt.minion      :1711][INFO    ][13018] Returning information for job: 20190420204032460707
2019-04-20 20:41:00,718 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1130.75167704s left)
2019-04-20 20:41:02,588 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204102577895
2019-04-20 20:41:02,605 [salt.minion      :1432][INFO    ][13103] Starting a new job with PID 13103
2019-04-20 20:41:02,636 [salt.minion      :1711][INFO    ][13103] Returning information for job: 20190420204102577895
2019-04-20 20:41:32,685 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204132675729
2019-04-20 20:41:32,701 [salt.minion      :1432][INFO    ][13123] Starting a new job with PID 13123
2019-04-20 20:41:32,731 [salt.minion      :1711][INFO    ][13123] Returning information for job: 20190420204132675729
2019-04-20 20:41:33,390 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1098.08018303s left)
2019-04-20 20:42:02,839 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204202828095
2019-04-20 20:42:02,860 [salt.minion      :1432][INFO    ][13200] Starting a new job with PID 13200
2019-04-20 20:42:02,888 [salt.minion      :1711][INFO    ][13200] Returning information for job: 20190420204202828095
2019-04-20 20:42:06,049 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1065.42074513s left)
2019-04-20 20:42:32,949 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204232941796
2019-04-20 20:42:32,963 [salt.minion      :1432][INFO    ][13221] Starting a new job with PID 13221
2019-04-20 20:42:32,993 [salt.minion      :1711][INFO    ][13221] Returning information for job: 20190420204232941796
2019-04-20 20:42:38,813 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1032.6567831s left)
2019-04-20 20:43:03,109 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204303097051
2019-04-20 20:43:03,129 [salt.minion      :1432][INFO    ][13303] Starting a new job with PID 13303
2019-04-20 20:43:03,160 [salt.minion      :1711][INFO    ][13303] Returning information for job: 20190420204303097051
2019-04-20 20:43:11,539 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (999.93099618s left)
2019-04-20 20:43:33,308 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204333298676
2019-04-20 20:43:33,324 [salt.minion      :1432][INFO    ][13326] Starting a new job with PID 13326
2019-04-20 20:43:33,353 [salt.minion      :1711][INFO    ][13326] Returning information for job: 20190420204333298676
2019-04-20 20:43:44,215 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (967.255503178s left)
2019-04-20 20:44:03,446 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204403433966
2019-04-20 20:44:03,464 [salt.minion      :1432][INFO    ][13429] Starting a new job with PID 13429
2019-04-20 20:44:03,493 [salt.minion      :1711][INFO    ][13429] Returning information for job: 20190420204403433966
2019-04-20 20:44:17,093 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (934.377061129s left)
2019-04-20 20:44:33,653 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204433644594
2019-04-20 20:44:33,668 [salt.minion      :1432][INFO    ][13453] Starting a new job with PID 13453
2019-04-20 20:44:33,703 [salt.minion      :1711][INFO    ][13453] Returning information for job: 20190420204433644594
2019-04-20 20:44:49,702 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (901.768143177s left)
2019-04-20 20:45:03,858 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204503845059
2019-04-20 20:45:03,877 [salt.minion      :1432][INFO    ][13534] Starting a new job with PID 13534
2019-04-20 20:45:03,908 [salt.minion      :1711][INFO    ][13534] Returning information for job: 20190420204503845059
2019-04-20 20:45:22,378 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (869.092324018s left)
2019-04-20 20:45:33,894 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204533883305
2019-04-20 20:45:33,918 [salt.minion      :1432][INFO    ][13557] Starting a new job with PID 13557
2019-04-20 20:45:33,945 [salt.minion      :1711][INFO    ][13557] Returning information for job: 20190420204533883305
2019-04-20 20:45:55,386 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (836.083972216s left)
2019-04-20 20:46:04,112 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204604102954
2019-04-20 20:46:04,129 [salt.minion      :1432][INFO    ][13638] Starting a new job with PID 13638
2019-04-20 20:46:04,160 [salt.minion      :1711][INFO    ][13638] Returning information for job: 20190420204604102954
2019-04-20 20:46:28,157 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (803.313254118s left)
2019-04-20 20:46:34,174 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204634160468
2019-04-20 20:46:34,194 [salt.minion      :1432][INFO    ][13659] Starting a new job with PID 13659
2019-04-20 20:46:34,222 [salt.minion      :1711][INFO    ][13659] Returning information for job: 20190420204634160468
2019-04-20 20:47:00,877 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (770.593332052s left)
2019-04-20 20:47:04,397 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204704386978
2019-04-20 20:47:04,414 [salt.minion      :1432][INFO    ][13745] Starting a new job with PID 13745
2019-04-20 20:47:04,444 [salt.minion      :1711][INFO    ][13745] Returning information for job: 20190420204704386978
2019-04-20 20:47:33,499 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (737.970538139s left)
2019-04-20 20:47:34,506 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204734493507
2019-04-20 20:47:34,526 [salt.minion      :1432][INFO    ][13768] Starting a new job with PID 13768
2019-04-20 20:47:34,556 [salt.minion      :1711][INFO    ][13768] Returning information for job: 20190420204734493507
2019-04-20 20:48:04,550 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204804542518
2019-04-20 20:48:04,565 [salt.minion      :1432][INFO    ][13845] Starting a new job with PID 13845
2019-04-20 20:48:04,596 [salt.minion      :1711][INFO    ][13845] Returning information for job: 20190420204804542518
2019-04-20 20:48:06,296 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (705.173634052s left)
2019-04-20 20:48:34,697 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204834682389
2019-04-20 20:48:34,722 [salt.minion      :1432][INFO    ][13864] Starting a new job with PID 13864
2019-04-20 20:48:34,752 [salt.minion      :1711][INFO    ][13864] Returning information for job: 20190420204834682389
2019-04-20 20:48:38,900 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (672.570642233s left)
2019-04-20 20:49:04,808 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204904800667
2019-04-20 20:49:04,821 [salt.minion      :1432][INFO    ][13947] Starting a new job with PID 13947
2019-04-20 20:49:04,849 [salt.minion      :1711][INFO    ][13947] Returning information for job: 20190420204904800667
2019-04-20 20:49:11,541 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (639.929173231s left)
2019-04-20 20:49:34,962 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420204934948724
2019-04-20 20:49:34,986 [salt.minion      :1432][INFO    ][13971] Starting a new job with PID 13971
2019-04-20 20:49:35,016 [salt.minion      :1711][INFO    ][13971] Returning information for job: 20190420204934948724
2019-04-20 20:49:44,274 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (607.19563508s left)
2019-04-20 20:50:05,157 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205005144677
2019-04-20 20:50:05,178 [salt.minion      :1432][INFO    ][14052] Starting a new job with PID 14052
2019-04-20 20:50:05,207 [salt.minion      :1711][INFO    ][14052] Returning information for job: 20190420205005144677
2019-04-20 20:50:17,158 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (574.312290192s left)
2019-04-20 20:50:35,302 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205035293737
2019-04-20 20:50:35,324 [salt.minion      :1432][INFO    ][14073] Starting a new job with PID 14073
2019-04-20 20:50:35,353 [salt.minion      :1711][INFO    ][14073] Returning information for job: 20190420205035293737
2019-04-20 20:50:49,670 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (541.799870014s left)
2019-04-20 20:51:05,340 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205105326405
2019-04-20 20:51:05,362 [salt.minion      :1432][INFO    ][14152] Starting a new job with PID 14152
2019-04-20 20:51:05,392 [salt.minion      :1711][INFO    ][14152] Returning information for job: 20190420205105326405
2019-04-20 20:51:22,450 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (509.0198071s left)
2019-04-20 20:51:35,543 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205135529968
2019-04-20 20:51:35,564 [salt.minion      :1432][INFO    ][14175] Starting a new job with PID 14175
2019-04-20 20:51:35,594 [salt.minion      :1711][INFO    ][14175] Returning information for job: 20190420205135529968
2019-04-20 20:51:55,312 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (476.157754183s left)
2019-04-20 20:52:05,612 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205205601807
2019-04-20 20:52:05,636 [salt.minion      :1432][INFO    ][14255] Starting a new job with PID 14255
2019-04-20 20:52:05,665 [salt.minion      :1711][INFO    ][14255] Returning information for job: 20190420205205601807
2019-04-20 20:52:28,110 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (443.360086203s left)
2019-04-20 20:52:35,639 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205235624268
2019-04-20 20:52:35,660 [salt.minion      :1432][INFO    ][14278] Starting a new job with PID 14278
2019-04-20 20:52:35,689 [salt.minion      :1711][INFO    ][14278] Returning information for job: 20190420205235624268
2019-04-20 20:53:00,745 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (410.725132227s left)
2019-04-20 20:53:05,720 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205305709314
2019-04-20 20:53:05,743 [salt.minion      :1432][INFO    ][14360] Starting a new job with PID 14360
2019-04-20 20:53:05,777 [salt.minion      :1711][INFO    ][14360] Returning information for job: 20190420205305709314
2019-04-20 20:53:33,394 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (378.07646513s left)
2019-04-20 20:53:35,790 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205335776017
2019-04-20 20:53:35,812 [salt.minion      :1432][INFO    ][14385] Starting a new job with PID 14385
2019-04-20 20:53:35,843 [salt.minion      :1711][INFO    ][14385] Returning information for job: 20190420205335776017
2019-04-20 20:54:05,910 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205405900629
2019-04-20 20:54:05,927 [salt.minion      :1432][INFO    ][14611] Starting a new job with PID 14611
2019-04-20 20:54:05,956 [salt.minion      :1711][INFO    ][14611] Returning information for job: 20190420205405900629
2019-04-20 20:54:06,294 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (345.175827026s left)
2019-04-20 20:54:36,028 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205436012679
2019-04-20 20:54:36,046 [salt.minion      :1432][INFO    ][14629] Starting a new job with PID 14629
2019-04-20 20:54:36,076 [salt.minion      :1711][INFO    ][14629] Returning information for job: 20190420205436012679
2019-04-20 20:54:39,057 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (312.412809134s left)
2019-04-20 20:55:06,185 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205506171268
2019-04-20 20:55:06,204 [salt.minion      :1432][INFO    ][14709] Starting a new job with PID 14709
2019-04-20 20:55:06,236 [salt.minion      :1711][INFO    ][14709] Returning information for job: 20190420205506171268
2019-04-20 20:55:11,777 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (279.692514181s left)
2019-04-20 20:55:36,362 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205536355894
2019-04-20 20:55:36,378 [salt.minion      :1432][INFO    ][14730] Starting a new job with PID 14730
2019-04-20 20:55:36,405 [salt.minion      :1711][INFO    ][14730] Returning information for job: 20190420205536355894
2019-04-20 20:55:44,490 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (246.980081081s left)
2019-04-20 20:56:06,513 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205606499784
2019-04-20 20:56:06,534 [salt.minion      :1432][INFO    ][14815] Starting a new job with PID 14815
2019-04-20 20:56:06,564 [salt.minion      :1711][INFO    ][14815] Returning information for job: 20190420205606499784
2019-04-20 20:56:17,286 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (214.18414712s left)
2019-04-20 20:56:36,569 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205636555487
2019-04-20 20:56:36,589 [salt.minion      :1432][INFO    ][14836] Starting a new job with PID 14836
2019-04-20 20:56:36,621 [salt.minion      :1711][INFO    ][14836] Returning information for job: 20190420205636555487
2019-04-20 20:56:50,027 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (181.442868233s left)
2019-04-20 20:57:06,774 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205706764141
2019-04-20 20:57:06,791 [salt.minion      :1432][INFO    ][14918] Starting a new job with PID 14918
2019-04-20 20:57:06,824 [salt.minion      :1711][INFO    ][14918] Returning information for job: 20190420205706764141
2019-04-20 20:57:22,689 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (148.781136036s left)
2019-04-20 20:57:36,856 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205736842516
2019-04-20 20:57:36,875 [salt.minion      :1432][INFO    ][14941] Starting a new job with PID 14941
2019-04-20 20:57:36,907 [salt.minion      :1711][INFO    ][14941] Returning information for job: 20190420205736842516
2019-04-20 20:57:55,462 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (116.008071184s left)
2019-04-20 20:58:06,872 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205806861303
2019-04-20 20:58:06,893 [salt.minion      :1432][INFO    ][15024] Starting a new job with PID 15024
2019-04-20 20:58:06,928 [salt.minion      :1711][INFO    ][15024] Returning information for job: 20190420205806861303
2019-04-20 20:58:28,208 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (83.2619810104s left)
2019-04-20 20:58:36,988 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205836974772
2019-04-20 20:58:37,007 [salt.minion      :1432][INFO    ][15045] Starting a new job with PID 15045
2019-04-20 20:58:37,038 [salt.minion      :1711][INFO    ][15045] Returning information for job: 20190420205836974772
2019-04-20 20:59:00,988 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (50.4835841656s left)
2019-04-20 20:59:07,004 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205906994503
2019-04-20 20:59:07,025 [salt.minion      :1432][INFO    ][15128] Starting a new job with PID 15128
2019-04-20 20:59:07,056 [salt.minion      :1711][INFO    ][15128] Returning information for job: 20190420205906994503
2019-04-20 20:59:33,692 [salt.loaded.ext.module.maas:1023][INFO    ][8520] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (17.7780361176s left)
2019-04-20 20:59:37,138 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420205937124340
2019-04-20 20:59:37,159 [salt.minion      :1432][INFO    ][15149] Starting a new job with PID 15149
2019-04-20 20:59:37,192 [salt.minion      :1711][INFO    ][15149] Returning information for job: 20190420205937124340
2019-04-20 21:00:06,376 [salt.state       :302 ][ERROR   ][8520] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-20 21:00:06,377 [salt.state       :1951][INFO    ][8520] Completed state [maas.wait_for_machine_status] at time 21:00:06.376977 duration_in_ms=2264921.264
2019-04-20 21:00:06,380 [salt.minion      :1711][INFO    ][8520] Returning information for job: 20190420202213549725
2019-04-20 21:00:17,289 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command pillar.get with jid 20190420210017279584
2019-04-20 21:00:17,310 [salt.minion      :1432][INFO    ][15239] Starting a new job with PID 15239
2019-04-20 21:00:17,316 [salt.minion      :1711][INFO    ][15239] Returning information for job: 20190420210017279584
2019-04-20 21:00:17,989 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command service.status with jid 20190420210017981267
2019-04-20 21:00:18,009 [salt.minion      :1432][INFO    ][15244] Starting a new job with PID 15244
2019-04-20 21:00:18,758 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15244] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-20 21:00:18,797 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15244] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-20 21:00:18,815 [salt.minion      :1711][INFO    ][15244] Returning information for job: 20190420210017981267
2019-04-20 21:00:19,500 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command state.apply with jid 20190420210019492511
2019-04-20 21:00:19,521 [salt.minion      :1432][INFO    ][15255] Starting a new job with PID 15255
2019-04-20 21:00:25,388 [salt.state       :915 ][INFO    ][15255] Loading fresh modules for state activity
2019-04-20 21:00:25,948 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command 'salt-minion --version' in directory '/root'
2019-04-20 21:00:26,276 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command 'salt-minion --version' in directory '/root'
2019-04-20 21:00:27,303 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command 'salt-minion --version' in directory '/root'
2019-04-20 21:00:27,632 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command 'salt-minion --version' in directory '/root'
2019-04-20 21:00:29,784 [salt.state       :1780][INFO    ][15255] Running state [salt-minion] at time 21:00:29.784193
2019-04-20 21:00:29,784 [salt.state       :1813][INFO    ][15255] Executing state pkg.installed for [salt-minion]
2019-04-20 21:00:29,785 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-20 21:00:29,892 [salt.state       :300 ][INFO    ][15255] All specified packages are already installed
2019-04-20 21:00:29,893 [salt.state       :1951][INFO    ][15255] Completed state [salt-minion] at time 21:00:29.893281 duration_in_ms=109.089
2019-04-20 21:00:29,893 [salt.state       :1780][INFO    ][15255] Running state [salt_minion_dependency_packages] at time 21:00:29.893649
2019-04-20 21:00:29,894 [salt.state       :1813][INFO    ][15255] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-20 21:00:29,903 [salt.state       :300 ][INFO    ][15255] All specified packages are already installed
2019-04-20 21:00:29,903 [salt.state       :1951][INFO    ][15255] Completed state [salt_minion_dependency_packages] at time 21:00:29.903608 duration_in_ms=9.959
2019-04-20 21:00:29,906 [salt.state       :1780][INFO    ][15255] Running state [/etc/salt/minion.d/minion.conf] at time 21:00:29.906608
2019-04-20 21:00:29,907 [salt.state       :1813][INFO    ][15255] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-20 21:00:30,172 [salt.state       :300 ][INFO    ][15255] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-20 21:00:30,172 [salt.state       :1951][INFO    ][15255] Completed state [/etc/salt/minion.d/minion.conf] at time 21:00:30.172522 duration_in_ms=265.914
2019-04-20 21:00:30,172 [salt.state       :1780][INFO    ][15255] Running state [python-netaddr] at time 21:00:30.172821
2019-04-20 21:00:30,173 [salt.state       :1813][INFO    ][15255] Executing state pkg.installed for [python-netaddr]
2019-04-20 21:00:30,182 [salt.state       :300 ][INFO    ][15255] All specified packages are already installed
2019-04-20 21:00:30,182 [salt.state       :1951][INFO    ][15255] Completed state [python-netaddr] at time 21:00:30.182481 duration_in_ms=9.659
2019-04-20 21:00:30,186 [salt.state       :1780][INFO    ][15255] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:00:30.186153
2019-04-20 21:00:30,186 [salt.state       :1813][INFO    ][15255] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-20 21:00:30,200 [salt.state       :300 ][INFO    ][15255] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-20 21:00:30,200 [salt.state       :1951][INFO    ][15255] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:00:30.200791 duration_in_ms=14.638
2019-04-20 21:00:30,202 [salt.state       :1780][INFO    ][15255] Running state [salt-minion] at time 21:00:30.202227
2019-04-20 21:00:30,202 [salt.state       :1813][INFO    ][15255] Executing state service.running for [salt-minion]
2019-04-20 21:00:30,204 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-20 21:00:30,241 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-20 21:00:30,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-20 21:00:30,278 [salt.state       :300 ][INFO    ][15255] The service salt-minion is already running
2019-04-20 21:00:30,281 [salt.state       :1951][INFO    ][15255] Completed state [salt-minion] at time 21:00:30.281356 duration_in_ms=79.129
2019-04-20 21:00:30,284 [salt.state       :1780][INFO    ][15255] Running state [/etc/salt/grains.d] at time 21:00:30.283959
2019-04-20 21:00:30,284 [salt.state       :1813][INFO    ][15255] Executing state file.directory for [/etc/salt/grains.d]
2019-04-20 21:00:30,286 [salt.state       :300 ][INFO    ][15255] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-20 21:00:30,286 [salt.state       :1951][INFO    ][15255] Completed state [/etc/salt/grains.d] at time 21:00:30.286610 duration_in_ms=2.651
2019-04-20 21:00:30,288 [salt.state       :1780][INFO    ][15255] Running state [/etc/salt/grains] at time 21:00:30.288562
2019-04-20 21:00:30,289 [salt.state       :1813][INFO    ][15255] Executing state file.managed for [/etc/salt/grains]
2019-04-20 21:00:30,290 [salt.state       :300 ][INFO    ][15255] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-20 21:00:30,290 [salt.state       :1951][INFO    ][15255] Completed state [/etc/salt/grains] at time 21:00:30.290327 duration_in_ms=1.765
2019-04-20 21:00:30,292 [salt.state       :1780][INFO    ][15255] Running state [/etc/salt/grains.d/placeholder] at time 21:00:30.292459
2019-04-20 21:00:30,292 [salt.state       :1813][INFO    ][15255] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-20 21:00:30,293 [salt.state       :300 ][INFO    ][15255] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-20 21:00:30,293 [salt.state       :1951][INFO    ][15255] Completed state [/etc/salt/grains.d/placeholder] at time 21:00:30.293553 duration_in_ms=1.093
2019-04-20 21:00:30,294 [salt.state       :1780][INFO    ][15255] Running state [/etc/salt/grains.d/sphinx] at time 21:00:30.294055
2019-04-20 21:00:30,294 [salt.state       :1813][INFO    ][15255] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-20 21:00:30,295 [salt.state       :300 ][INFO    ][15255] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-20 21:00:30,296 [salt.state       :1951][INFO    ][15255] Completed state [/etc/salt/grains.d/sphinx] at time 21:00:30.296176 duration_in_ms=2.121
2019-04-20 21:00:30,298 [salt.state       :1780][INFO    ][15255] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:00:30.298219
2019-04-20 21:00:30,298 [salt.state       :1813][INFO    ][15255] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-20 21:00:30,299 [salt.state       :300 ][INFO    ][15255] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-20 21:00:30,299 [salt.state       :1951][INFO    ][15255] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:00:30.299287 duration_in_ms=1.068
2019-04-20 21:00:30,299 [salt.state       :1780][INFO    ][15255] Running state [/etc/salt/grains.d/dns_records] at time 21:00:30.299807
2019-04-20 21:00:30,300 [salt.state       :1813][INFO    ][15255] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-20 21:00:30,301 [salt.state       :300 ][INFO    ][15255] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-20 21:00:30,301 [salt.state       :1951][INFO    ][15255] Completed state [/etc/salt/grains.d/dns_records] at time 21:00:30.301389 duration_in_ms=1.581
2019-04-20 21:00:30,302 [salt.state       :1780][INFO    ][15255] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:00:30.302340
2019-04-20 21:00:30,302 [salt.state       :1813][INFO    ][15255] 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-20 21:00:30,304 [salt.state       :300 ][INFO    ][15255] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-20 21:00:30,304 [salt.state       :1951][INFO    ][15255] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:00:30.304288 duration_in_ms=1.948
2019-04-20 21:00:30,304 [salt.state       :1780][INFO    ][15255] Running state [/etc/salt/grains.d/salt] at time 21:00:30.304801
2019-04-20 21:00:30,305 [salt.state       :1813][INFO    ][15255] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-20 21:00:30,306 [salt.state       :300 ][INFO    ][15255] File /etc/salt/grains.d/salt is in the correct state
2019-04-20 21:00:30,306 [salt.state       :1951][INFO    ][15255] Completed state [/etc/salt/grains.d/salt] at time 21:00:30.306365 duration_in_ms=1.565
2019-04-20 21:00:30,307 [salt.state       :1780][INFO    ][15255] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:00:30.307841
2019-04-20 21:00:30,308 [salt.state       :1813][INFO    ][15255] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-20 21:00:30,308 [salt.state       :300 ][INFO    ][15255] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-20 21:00:30,308 [salt.state       :1951][INFO    ][15255] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:00:30.308684 duration_in_ms=0.844
2019-04-20 21:00:30,310 [salt.state       :1780][INFO    ][15255] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:00:30.310616
2019-04-20 21:00:30,311 [salt.state       :1813][INFO    ][15255] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-20 21:00:30,311 [salt.state       :300 ][INFO    ][15255] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-20 21:00:30,311 [salt.state       :1951][INFO    ][15255] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:00:30.311761 duration_in_ms=1.145
2019-04-20 21:00:30,312 [salt.state       :1780][INFO    ][15255] Running state [mine.update] at time 21:00:30.312449
2019-04-20 21:00:30,312 [salt.state       :1813][INFO    ][15255] Executing state module.wait for [mine.update]
2019-04-20 21:00:30,313 [salt.state       :300 ][INFO    ][15255] No changes made for mine.update
2019-04-20 21:00:30,313 [salt.state       :1951][INFO    ][15255] Completed state [mine.update] at time 21:00:30.313260 duration_in_ms=0.812
2019-04-20 21:00:30,313 [salt.state       :1780][INFO    ][15255] Running state [ca-certificates] at time 21:00:30.313536
2019-04-20 21:00:30,313 [salt.state       :1813][INFO    ][15255] Executing state pkg.installed for [ca-certificates]
2019-04-20 21:00:30,323 [salt.state       :300 ][INFO    ][15255] All specified packages are already installed
2019-04-20 21:00:30,324 [salt.state       :1951][INFO    ][15255] Completed state [ca-certificates] at time 21:00:30.324096 duration_in_ms=10.559
2019-04-20 21:00:30,324 [salt.state       :1780][INFO    ][15255] Running state [update-ca-certificates] at time 21:00:30.324816
2019-04-20 21:00:30,325 [salt.state       :1813][INFO    ][15255] Executing state cmd.wait for [update-ca-certificates]
2019-04-20 21:00:30,325 [salt.state       :300 ][INFO    ][15255] No changes made for update-ca-certificates
2019-04-20 21:00:30,325 [salt.state       :1951][INFO    ][15255] Completed state [update-ca-certificates] at time 21:00:30.325651 duration_in_ms=0.835
2019-04-20 21:00:30,325 [salt.state       :1780][INFO    ][15255] Running state [iptables] at time 21:00:30.325929
2019-04-20 21:00:30,326 [salt.state       :1813][INFO    ][15255] Executing state pkg.installed for [iptables]
2019-04-20 21:00:30,335 [salt.state       :300 ][INFO    ][15255] All specified packages are already installed
2019-04-20 21:00:30,335 [salt.state       :1951][INFO    ][15255] Completed state [iptables] at time 21:00:30.335640 duration_in_ms=9.711
2019-04-20 21:00:30,335 [salt.state       :1780][INFO    ][15255] Running state [iptables-persistent] at time 21:00:30.335929
2019-04-20 21:00:30,336 [salt.state       :1813][INFO    ][15255] Executing state pkg.installed for [iptables-persistent]
2019-04-20 21:00:30,345 [salt.state       :300 ][INFO    ][15255] All specified packages are already installed
2019-04-20 21:00:30,345 [salt.state       :1951][INFO    ][15255] Completed state [iptables-persistent] at time 21:00:30.345536 duration_in_ms=9.607
2019-04-20 21:00:30,346 [salt.state       :1780][INFO    ][15255] Running state [iptables_modules_v4_load] at time 21:00:30.346570
2019-04-20 21:00:30,347 [salt.state       :1813][INFO    ][15255] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-20 21:00:30,347 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command 'lsmod' in directory '/root'
2019-04-20 21:00:30,367 [salt.state       :300 ][INFO    ][15255] Kernel modules iptable_filter, ip_tables are already present
2019-04-20 21:00:30,368 [salt.state       :1951][INFO    ][15255] Completed state [iptables_modules_v4_load] at time 21:00:30.368243 duration_in_ms=21.673
2019-04-20 21:00:30,369 [salt.state       :1780][INFO    ][15255] Running state [/etc/iptables/rules.v4] at time 21:00:30.369244
2019-04-20 21:00:30,369 [salt.state       :1813][INFO    ][15255] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-20 21:00:30,471 [salt.state       :300 ][INFO    ][15255] File /etc/iptables/rules.v4 is in the correct state
2019-04-20 21:00:30,471 [salt.state       :1951][INFO    ][15255] Completed state [/etc/iptables/rules.v4] at time 21:00:30.471389 duration_in_ms=102.145
2019-04-20 21:00:30,472 [salt.state       :1780][INFO    ][15255] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:00:30.472512
2019-04-20 21:00:30,472 [salt.state       :1813][INFO    ][15255] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-20 21:00:30,473 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-20 21:00:30,491 [salt.state       :300 ][INFO    ][15255] onlyif execution failed
2019-04-20 21:00:30,492 [salt.state       :1951][INFO    ][15255] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:00:30.492160 duration_in_ms=19.648
2019-04-20 21:00:30,493 [salt.state       :1780][INFO    ][15255] Running state [netfilter-persistent] at time 21:00:30.493762
2019-04-20 21:00:30,494 [salt.state       :1813][INFO    ][15255] Executing state service.running for [netfilter-persistent]
2019-04-20 21:00:30,497 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-20 21:00:30,518 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-20 21:00:30,535 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-20 21:00:30,551 [salt.state       :300 ][INFO    ][15255] The service netfilter-persistent is already running
2019-04-20 21:00:30,551 [salt.state       :1951][INFO    ][15255] Completed state [netfilter-persistent] at time 21:00:30.551638 duration_in_ms=57.875
2019-04-20 21:00:30,552 [salt.state       :1780][INFO    ][15255] Running state [iptables_extra.remove_stale_tables] at time 21:00:30.552889
2019-04-20 21:00:30,553 [salt.state       :1813][INFO    ][15255] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-20 21:00:30,553 [salt.state       :300 ][INFO    ][15255] No changes made for iptables_extra.remove_stale_tables
2019-04-20 21:00:30,554 [salt.state       :1951][INFO    ][15255] Completed state [iptables_extra.remove_stale_tables] at time 21:00:30.554154 duration_in_ms=1.266
2019-04-20 21:00:30,554 [salt.state       :1780][INFO    ][15255] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:00:30.554553
2019-04-20 21:00:30,556 [salt.state       :1813][INFO    ][15255] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-20 21:00:30,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-20 21:00:30,572 [salt.state       :300 ][INFO    ][15255] onlyif execution failed
2019-04-20 21:00:30,572 [salt.state       :1951][INFO    ][15255] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:00:30.572583 duration_in_ms=18.03
2019-04-20 21:00:30,574 [salt.state       :1780][INFO    ][15255] Running state [/etc/iptables/rules.v6] at time 21:00:30.574069
2019-04-20 21:00:30,574 [salt.state       :1813][INFO    ][15255] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-20 21:00:30,575 [salt.state       :300 ][INFO    ][15255] File /etc/iptables/rules.v6 is not present
2019-04-20 21:00:30,575 [salt.state       :1951][INFO    ][15255] Completed state [/etc/iptables/rules.v6] at time 21:00:30.575870 duration_in_ms=1.801
2019-04-20 21:00:30,577 [salt.state       :1780][INFO    ][15255] Running state [iptables_extra.flush_all] at time 21:00:30.576987
2019-04-20 21:00:30,577 [salt.state       :1813][INFO    ][15255] Executing state module.wait for [iptables_extra.flush_all]
2019-04-20 21:00:30,577 [salt.state       :300 ][INFO    ][15255] No changes made for iptables_extra.flush_all
2019-04-20 21:00:30,578 [salt.state       :1951][INFO    ][15255] Completed state [iptables_extra.flush_all] at time 21:00:30.578252 duration_in_ms=1.265
2019-04-20 21:00:30,583 [salt.minion      :1711][INFO    ][15255] Returning information for job: 20190420210019492511
2019-04-20 21:00:31,267 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command state.apply with jid 20190420210031256360
2019-04-20 21:00:31,284 [salt.minion      :1432][INFO    ][15335] Starting a new job with PID 15335
2019-04-20 21:00:32,227 [salt.state       :915 ][INFO    ][15335] Loading fresh modules for state activity
2019-04-20 21:00:33,452 [salt.state       :1780][INFO    ][15335] Running state [maas-rack-controller] at time 21:00:33.452453
2019-04-20 21:00:33,452 [salt.state       :1813][INFO    ][15335] Executing state pkg.installed for [maas-rack-controller]
2019-04-20 21:00:33,453 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15335] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-20 21:00:33,550 [salt.state       :300 ][INFO    ][15335] All specified packages are already installed
2019-04-20 21:00:33,551 [salt.state       :1951][INFO    ][15335] Completed state [maas-rack-controller] at time 21:00:33.551103 duration_in_ms=98.65
2019-04-20 21:00:33,551 [salt.state       :1780][INFO    ][15335] Running state [ipmitool] at time 21:00:33.551471
2019-04-20 21:00:33,551 [salt.state       :1813][INFO    ][15335] Executing state pkg.installed for [ipmitool]
2019-04-20 21:00:33,561 [salt.state       :300 ][INFO    ][15335] All specified packages are already installed
2019-04-20 21:00:33,561 [salt.state       :1951][INFO    ][15335] Completed state [ipmitool] at time 21:00:33.561388 duration_in_ms=9.917
2019-04-20 21:00:33,564 [salt.state       :1780][INFO    ][15335] Running state [/etc/maas/rackd.conf] at time 21:00:33.564571
2019-04-20 21:00:33,564 [salt.state       :1813][INFO    ][15335] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-20 21:00:33,565 [salt.state       :300 ][INFO    ][15335] No changes needed to be made
2019-04-20 21:00:33,566 [salt.state       :1951][INFO    ][15335] Completed state [/etc/maas/rackd.conf] at time 21:00:33.566050 duration_in_ms=1.479
2019-04-20 21:00:33,566 [salt.state       :1780][INFO    ][15335] Running state [/etc/maas/rackd.conf] at time 21:00:33.566341
2019-04-20 21:00:33,566 [salt.state       :1813][INFO    ][15335] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-20 21:00:33,567 [salt.loaded.int.states.file:2298][WARNING ][15335] 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-20 21:00:33,568 [salt.state       :300 ][INFO    ][15335] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-20 21:00:33,568 [salt.state       :1951][INFO    ][15335] Completed state [/etc/maas/rackd.conf] at time 21:00:33.568735 duration_in_ms=2.394
2019-04-20 21:00:33,569 [salt.state       :1780][INFO    ][15335] Running state [maas-rackd] at time 21:00:33.569776
2019-04-20 21:00:33,570 [salt.state       :1813][INFO    ][15335] Executing state service.running for [maas-rackd]
2019-04-20 21:00:33,570 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15335] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-20 21:00:33,606 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15335] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-20 21:00:33,621 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15335] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-20 21:00:33,642 [salt.state       :300 ][INFO    ][15335] The service maas-rackd is already running
2019-04-20 21:00:33,642 [salt.state       :1951][INFO    ][15335] Completed state [maas-rackd] at time 21:00:33.642579 duration_in_ms=72.802
2019-04-20 21:00:33,644 [salt.minion      :1711][INFO    ][15335] Returning information for job: 20190420210031256360
2019-04-20 21:00:34,327 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command state.apply with jid 20190420210034316976
2019-04-20 21:00:34,349 [salt.minion      :1432][INFO    ][15358] Starting a new job with PID 15358
2019-04-20 21:00:35,270 [salt.state       :915 ][INFO    ][15358] Loading fresh modules for state activity
2019-04-20 21:00:36,625 [salt.state       :1780][INFO    ][15358] Running state [maas-region-controller] at time 21:00:36.625417
2019-04-20 21:00:36,625 [salt.state       :1813][INFO    ][15358] Executing state pkg.installed for [maas-region-controller]
2019-04-20 21:00:36,626 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15358] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-20 21:00:36,741 [salt.state       :300 ][INFO    ][15358] All specified packages are already installed
2019-04-20 21:00:36,741 [salt.state       :1951][INFO    ][15358] Completed state [maas-region-controller] at time 21:00:36.741594 duration_in_ms=116.178
2019-04-20 21:00:36,742 [salt.state       :1780][INFO    ][15358] Running state [python-oauth] at time 21:00:36.741956
2019-04-20 21:00:36,742 [salt.state       :1813][INFO    ][15358] Executing state pkg.installed for [python-oauth]
2019-04-20 21:00:36,751 [salt.state       :300 ][INFO    ][15358] All specified packages are already installed
2019-04-20 21:00:36,751 [salt.state       :1951][INFO    ][15358] Completed state [python-oauth] at time 21:00:36.751807 duration_in_ms=9.851
2019-04-20 21:00:36,755 [salt.state       :1780][INFO    ][15358] Running state [/etc/maas/regiond.conf] at time 21:00:36.754997
2019-04-20 21:00:36,755 [salt.state       :1813][INFO    ][15358] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-20 21:00:36,760 [salt.state       :300 ][INFO    ][15358] No changes needed to be made
2019-04-20 21:00:36,761 [salt.state       :1951][INFO    ][15358] Completed state [/etc/maas/regiond.conf] at time 21:00:36.761021 duration_in_ms=6.024
2019-04-20 21:00:36,761 [salt.state       :1780][INFO    ][15358] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:00:36.761532
2019-04-20 21:00:36,761 [salt.state       :1813][INFO    ][15358] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-20 21:00:36,818 [salt.state       :300 ][INFO    ][15358] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-20 21:00:36,818 [salt.state       :1951][INFO    ][15358] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:00:36.818535 duration_in_ms=57.002
2019-04-20 21:00:36,820 [salt.state       :1780][INFO    ][15358] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:00:36.820066
2019-04-20 21:00:36,820 [salt.state       :1813][INFO    ][15358] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-20 21:00:36,827 [salt.state       :300 ][INFO    ][15358] No changes needed to be made
2019-04-20 21:00:36,828 [salt.state       :1951][INFO    ][15358] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:00:36.827994 duration_in_ms=7.929
2019-04-20 21:00:36,828 [salt.state       :1780][INFO    ][15358] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:00:36.828742
2019-04-20 21:00:36,829 [salt.state       :1813][INFO    ][15358] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-20 21:00:36,835 [salt.state       :300 ][INFO    ][15358] No changes needed to be made
2019-04-20 21:00:36,835 [salt.state       :1951][INFO    ][15358] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:00:36.835287 duration_in_ms=6.546
2019-04-20 21:00:36,835 [salt.state       :1780][INFO    ][15358] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:00:36.835800
2019-04-20 21:00:36,836 [salt.state       :1813][INFO    ][15358] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-20 21:00:36,860 [salt.state       :300 ][INFO    ][15358] No changes needed to be made
2019-04-20 21:00:36,860 [salt.state       :1951][INFO    ][15358] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:00:36.860899 duration_in_ms=25.098
2019-04-20 21:00:36,861 [salt.state       :1780][INFO    ][15358] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:00:36.861414
2019-04-20 21:00:36,861 [salt.state       :1813][INFO    ][15358] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-20 21:00:36,875 [salt.state       :300 ][INFO    ][15358] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-20 21:00:36,875 [salt.state       :1951][INFO    ][15358] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:00:36.875354 duration_in_ms=13.939
2019-04-20 21:00:36,877 [salt.state       :1780][INFO    ][15358] Running state [a2enmod headers] at time 21:00:36.877331
2019-04-20 21:00:36,877 [salt.state       :1813][INFO    ][15358] Executing state cmd.run for [a2enmod headers]
2019-04-20 21:00:36,880 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15358] Executing command 'a2enmod headers' in directory '/root'
2019-04-20 21:00:36,946 [salt.state       :300 ][INFO    ][15358] {'pid': 15377, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-20 21:00:36,947 [salt.state       :1951][INFO    ][15358] Completed state [a2enmod headers] at time 21:00:36.947109 duration_in_ms=69.779
2019-04-20 21:00:36,947 [salt.state       :1780][INFO    ][15358] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:00:36.947574
2019-04-20 21:00:36,948 [salt.state       :1813][INFO    ][15358] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-20 21:00:36,970 [salt.state       :300 ][INFO    ][15358] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-20 21:00:36,970 [salt.state       :1951][INFO    ][15358] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:00:36.970475 duration_in_ms=22.902
2019-04-20 21:00:36,971 [salt.state       :1780][INFO    ][15358] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:00:36.971286
2019-04-20 21:00:36,971 [salt.state       :1813][INFO    ][15358] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-20 21:00:37,027 [salt.state       :300 ][INFO    ][15358] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-20 21:00:37,027 [salt.state       :1951][INFO    ][15358] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:00:37.027589 duration_in_ms=56.303
2019-04-20 21:00:37,028 [salt.state       :1780][INFO    ][15358] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:00:37.028126
2019-04-20 21:00:37,028 [salt.state       :1813][INFO    ][15358] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-20 21:00:37,088 [salt.state       :300 ][INFO    ][15358] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-20 21:00:37,088 [salt.state       :1951][INFO    ][15358] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:00:37.088351 duration_in_ms=60.225
2019-04-20 21:00:37,088 [salt.state       :1780][INFO    ][15358] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:00:37.088888
2019-04-20 21:00:37,089 [salt.state       :1813][INFO    ][15358] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-20 21:00:37,156 [salt.state       :300 ][INFO    ][15358] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-20 21:00:37,156 [salt.state       :1951][INFO    ][15358] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:00:37.156746 duration_in_ms=67.858
2019-04-20 21:00:37,157 [salt.state       :1780][INFO    ][15358] Running state [/root/.pgpass] at time 21:00:37.157038
2019-04-20 21:00:37,157 [salt.state       :1813][INFO    ][15358] Executing state file.managed for [/root/.pgpass]
2019-04-20 21:00:37,200 [salt.state       :300 ][INFO    ][15358] File /root/.pgpass is in the correct state
2019-04-20 21:00:37,201 [salt.state       :1951][INFO    ][15358] Completed state [/root/.pgpass] at time 21:00:37.201161 duration_in_ms=44.123
2019-04-20 21:00:37,207 [salt.state       :1780][INFO    ][15358] Running state [maas-region syncdb --noinput] at time 21:00:37.207632
2019-04-20 21:00:37,207 [salt.state       :1813][INFO    ][15358] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-20 21:00:37,208 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15358] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-20 21:00:39,804 [salt.state       :300 ][INFO    ][15358] {'pid': 15390, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, metadataserver, maasserver, sessions, sites, auth, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-20 21:00:39,805 [salt.state       :1951][INFO    ][15358] Completed state [maas-region syncdb --noinput] at time 21:00:39.805367 duration_in_ms=2597.734
2019-04-20 21:00:39,805 [salt.state       :2022][WARNING ][15358] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-20 21:00:39,810 [salt.state       :1780][INFO    ][15358] Running state [maas-regiond] at time 21:00:39.810046
2019-04-20 21:00:39,810 [salt.state       :1813][INFO    ][15358] Executing state service.running for [maas-regiond]
2019-04-20 21:00:39,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15358] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-20 21:00:39,848 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15358] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-20 21:00:39,865 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15358] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-20 21:00:39,887 [salt.state       :300 ][INFO    ][15358] The service maas-regiond is already running
2019-04-20 21:00:39,888 [salt.state       :1951][INFO    ][15358] Completed state [maas-regiond] at time 21:00:39.888037 duration_in_ms=77.992
2019-04-20 21:00:39,892 [salt.state       :1780][INFO    ][15358] Running state [bind9] at time 21:00:39.890798
2019-04-20 21:00:39,893 [salt.state       :1813][INFO    ][15358] Executing state service.running for [bind9]
2019-04-20 21:00:39,894 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15358] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-20 21:00:39,914 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15358] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-20 21:00:39,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15358] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-20 21:00:39,947 [salt.state       :300 ][INFO    ][15358] The service bind9 is already running
2019-04-20 21:00:39,948 [salt.state       :1951][INFO    ][15358] Completed state [bind9] at time 21:00:39.948172 duration_in_ms=57.373
2019-04-20 21:00:39,950 [salt.state       :1780][INFO    ][15358] Running state [apache2] at time 21:00:39.950654
2019-04-20 21:00:39,954 [salt.state       :1813][INFO    ][15358] Executing state service.running for [apache2]
2019-04-20 21:00:39,955 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15358] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-20 21:00:39,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15358] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-20 21:00:40,002 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15358] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-20 21:00:40,028 [salt.state       :300 ][INFO    ][15358] The service apache2 is already running
2019-04-20 21:00:40,029 [salt.state       :1951][INFO    ][15358] Completed state [apache2] at time 21:00:40.029312 duration_in_ms=78.658
2019-04-20 21:00:40,030 [salt.state       :1780][INFO    ][15358] Running state [maasng.wait_for_http_code] at time 21:00:40.030545
2019-04-20 21:00:40,030 [salt.state       :1813][INFO    ][15358] Executing state module.run for [maasng.wait_for_http_code]
2019-04-20 21:00:40,031 [salt.utils.decorators:613 ][WARNING ][15358] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 21:00:40,116 [salt.state       :300 ][INFO    ][15358] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-20 21:00:40,117 [salt.state       :1951][INFO    ][15358] Completed state [maasng.wait_for_http_code] at time 21:00:40.117306 duration_in_ms=86.76
2019-04-20 21:00:40,118 [salt.state       :1780][INFO    ][15358] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:00:40.118185
2019-04-20 21:00:40,118 [salt.state       :1813][INFO    ][15358] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-20 21:00:40,120 [salt.state       :300 ][INFO    ][15358] /var/lib/maas/.setup_admin exists
2019-04-20 21:00:40,120 [salt.state       :1951][INFO    ][15358] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:00:40.120321 duration_in_ms=2.136
2019-04-20 21:00:40,121 [salt.state       :1780][INFO    ][15358] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:00:40.121035
2019-04-20 21:00:40,121 [salt.state       :1813][INFO    ][15358] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 21:00:40,122 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15358] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 21:00:42,019 [salt.state       :300 ][INFO    ][15358] {'pid': 15431, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 21:00:42,020 [salt.state       :1951][INFO    ][15358] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:00:42.020625 duration_in_ms=1899.588
2019-04-20 21:00:42,029 [salt.state       :1780][INFO    ][15358] Running state [maas_region_boot_source_resources_mirror] at time 21:00:42.029306
2019-04-20 21:00:42,029 [salt.state       :1813][INFO    ][15358] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-20 21:00:42,113 [salt.state       :300 ][INFO    ][15358] {'changes': {}}
2019-04-20 21:00:42,113 [salt.state       :1951][INFO    ][15358] Completed state [maas_region_boot_source_resources_mirror] at time 21:00:42.113481 duration_in_ms=84.175
2019-04-20 21:00:42,114 [salt.state       :1780][INFO    ][15358] Running state [maasng.boot_resources_import] at time 21:00:42.114484
2019-04-20 21:00:42,115 [salt.state       :1813][INFO    ][15358] Executing state module.run for [maasng.boot_resources_import]
2019-04-20 21:00:42,116 [salt.utils.decorators:613 ][WARNING ][15358] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 21:00:42,201 [salt.loaded.ext.module.maasng:1600][INFO    ][15358] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-20 21:00:47,266 [salt.loaded.ext.module.maasng:1600][INFO    ][15358] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-20 21:00:49,433 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210049425258
2019-04-20 21:00:49,447 [salt.minion      :1432][INFO    ][15602] Starting a new job with PID 15602
2019-04-20 21:00:49,475 [salt.minion      :1711][INFO    ][15602] Returning information for job: 20190420210049425258
2019-04-20 21:00:52,360 [salt.state       :300 ][INFO    ][15358] {'ret': True}
2019-04-20 21:00:52,360 [salt.state       :1951][INFO    ][15358] Completed state [maasng.boot_resources_import] at time 21:00:52.360584 duration_in_ms=10246.1
2019-04-20 21:00:52,361 [salt.state       :1780][INFO    ][15358] Running state [maas_region_boot_sources_selection_xenial] at time 21:00:52.361586
2019-04-20 21:00:52,362 [salt.state       :1813][INFO    ][15358] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-20 21:00:52,528 [salt.state       :300 ][INFO    ][15358] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-20 21:00:52,529 [salt.state       :1951][INFO    ][15358] Completed state [maas_region_boot_sources_selection_xenial] at time 21:00:52.528919 duration_in_ms=167.332
2019-04-20 21:00:52,530 [salt.state       :1780][INFO    ][15358] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:00:52.530311
2019-04-20 21:00:52,530 [salt.state       :1813][INFO    ][15358] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-20 21:00:52,531 [salt.utils.decorators:613 ][WARNING ][15358] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 21:00:52,531 [salt.loaded.ext.module.maasng:1771][INFO    ][15358] boot-sources sync initiated for ALL Rack's
2019-04-20 21:00:53,619 [salt.state       :300 ][INFO    ][15358] {'ret': True}
2019-04-20 21:00:53,619 [salt.state       :1951][INFO    ][15358] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:00:53.619806 duration_in_ms=1089.494
2019-04-20 21:00:53,622 [salt.state       :1780][INFO    ][15358] Running state [maas.process_maas_config] at time 21:00:53.622128
2019-04-20 21:00:53,622 [salt.state       :1813][INFO    ][15358] Executing state module.run for [maas.process_maas_config]
2019-04-20 21:00:53,623 [salt.utils.decorators:613 ][WARNING ][15358] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 21:00:53,624 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=enable_http_proxy value=True
2019-04-20 21:00:53,672 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=upstream_dns value=8.8.8.8
2019-04-20 21:00:55,029 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=commissioning_distro_series value=xenial
2019-04-20 21:00:55,089 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=default_osystem value=ubuntu
2019-04-20 21:00:55,189 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=active_discovery_interval value=600
2019-04-20 21:00:55,238 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=dnssec_validation value=no
2019-04-20 21:00:55,278 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=maas_name value=mas01
2019-04-20 21:00:55,325 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=network_discovery value=enabled
2019-04-20 21:00:55,429 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=enable_third_party_drivers value=True
2019-04-20 21:00:55,473 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=default_storage_layout value=lvm
2019-04-20 21:00:55,517 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=ntp_external_only value=True
2019-04-20 21:00:55,557 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-20 21:00:55,600 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=default_distro_series value=xenial
2019-04-20 21:00:55,667 [salt.loaded.ext.module.maas:92  ][INFO    ][15358] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-20 21:00:55,775 [salt.state       :300 ][INFO    ][15358] {'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-20 21:00:55,775 [salt.state       :1951][INFO    ][15358] Completed state [maas.process_maas_config] at time 21:00:55.775822 duration_in_ms=2153.693
2019-04-20 21:00:55,776 [salt.state       :1780][INFO    ][15358] Running state [pxe_admin] at time 21:00:55.776541
2019-04-20 21:00:55,776 [salt.state       :1813][INFO    ][15358] Executing state maasng.fabric_present for [pxe_admin]
2019-04-20 21:00:55,831 [salt.loaded.ext.module.maasng:945 ][INFO    ][15358] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nx667k', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-20 21:00:55,893 [salt.loaded.ext.module.maasng:1008][WARNING ][15358] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-20 21:00:55,894 [salt.loaded.ext.module.maasng:1011][WARNING ][15358] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-20 21:00:55,964 [salt.state       :300 ][INFO    ][15358] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-20 21:00:55,965 [salt.state       :1951][INFO    ][15358] Completed state [pxe_admin] at time 21:00:55.965032 duration_in_ms=188.491
2019-04-20 21:00:55,965 [salt.state       :1780][INFO    ][15358] Running state [vlan 0] at time 21:00:55.965409
2019-04-20 21:00:55,965 [salt.state       :1813][INFO    ][15358] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-20 21:00:56,017 [salt.loaded.ext.module.maasng:945 ][INFO    ][15358] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nx667k', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-20 21:00:56,105 [salt.loaded.ext.module.maasng:945 ][INFO    ][15358] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nx667k', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-20 21:00:56,340 [salt.loaded.ext.module.maasng:945 ][INFO    ][15358] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nx667k', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-20 21:00:56,435 [salt.state       :300 ][INFO    ][15358] {'new': 'Vlan untagged was updated'}
2019-04-20 21:00:56,436 [salt.state       :1951][INFO    ][15358] Completed state [vlan 0] at time 21:00:56.436339 duration_in_ms=470.929
2019-04-20 21:00:56,437 [salt.state       :1780][INFO    ][15358] Running state [192.168.11.0/24] at time 21:00:56.437605
2019-04-20 21:00:56,438 [salt.state       :1813][INFO    ][15358] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-20 21:00:56,604 [salt.loaded.ext.module.maasng:945 ][INFO    ][15358] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nx667k', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-20 21:00:56,605 [salt.loaded.ext.module.maasng:1235][WARNING ][15358] Ignoring parameter vlan:0
2019-04-20 21:00:56,661 [salt.state       :300 ][INFO    ][15358] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-20 21:00:56,662 [salt.state       :1951][INFO    ][15358] Completed state [192.168.11.0/24] at time 21:00:56.662234 duration_in_ms=224.628
2019-04-20 21:00:56,663 [salt.state       :1780][INFO    ][15358] Running state [maas_create_iprange_1] at time 21:00:56.663361
2019-04-20 21:00:56,663 [salt.state       :1813][INFO    ][15358] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-20 21:00:56,705 [salt.state       :300 ][INFO    ][15358] Iprange maas_create_iprange_1 already exist.
2019-04-20 21:00:56,706 [salt.state       :1951][INFO    ][15358] Completed state [maas_create_iprange_1] at time 21:00:56.705993 duration_in_ms=42.632
2019-04-20 21:00:56,706 [salt.state       :1780][INFO    ][15358] Running state [vlan 0] at time 21:00:56.706309
2019-04-20 21:00:56,706 [salt.state       :1813][INFO    ][15358] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-20 21:00:56,749 [salt.loaded.ext.module.maasng:945 ][INFO    ][15358] [{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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nx667k', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-20 21:00:56,829 [salt.loaded.ext.module.maasng:945 ][INFO    ][15358] [{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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nx667k', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-20 21:00:57,040 [salt.loaded.ext.module.maasng:945 ][INFO    ][15358] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nx667k', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-20 21:00:57,105 [salt.state       :300 ][INFO    ][15358] {'new': 'Vlan untagged was updated'}
2019-04-20 21:00:57,105 [salt.state       :1951][INFO    ][15358] Completed state [vlan 0] at time 21:00:57.105559 duration_in_ms=399.249
2019-04-20 21:00:57,106 [salt.state       :1780][INFO    ][15358] Running state [opnfv] at time 21:00:57.106166
2019-04-20 21:00:57,106 [salt.state       :1813][INFO    ][15358] Executing state maasng.sshkey_present for [opnfv]
2019-04-20 21:00:57,152 [salt.loaded.ext.module.maasng:1903][INFO    ][15358] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-04-20 21:00:57,152 [salt.state       :300 ][INFO    ][15358] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-20 21:00:57,153 [salt.state       :1951][INFO    ][15358] Completed state [opnfv] at time 21:00:57.152926 duration_in_ms=46.76
2019-04-20 21:00:57,155 [salt.minion      :1711][INFO    ][15358] Returning information for job: 20190420210034316976
2019-04-20 21:00:57,908 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command state.apply with jid 20190420210057895844
2019-04-20 21:00:57,930 [salt.minion      :1432][INFO    ][15867] Starting a new job with PID 15867
2019-04-20 21:01:03,754 [salt.state       :915 ][INFO    ][15867] Loading fresh modules for state activity
2019-04-20 21:01:03,843 [salt.state       :1780][INFO    ][15867] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:03.843406
2019-04-20 21:01:03,844 [salt.state       :1813][INFO    ][15867] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 21:01:03,845 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15867] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 21:01:05,683 [salt.state       :300 ][INFO    ][15867] {'pid': 15890, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 21:01:05,684 [salt.state       :1951][INFO    ][15867] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:05.684534 duration_in_ms=1841.129
2019-04-20 21:01:05,686 [salt.state       :1780][INFO    ][15867] Running state [maas.process_machines] at time 21:01:05.686333
2019-04-20 21:01:05,686 [salt.state       :1813][INFO    ][15867] Executing state module.run for [maas.process_machines]
2019-04-20 21:01:05,687 [salt.utils.decorators:613 ][WARNING ][15867] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 21:01:06,158 [salt.loaded.ext.module.maas:412 ][WARNING ][15867] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 21:01:06,160 [salt.loaded.ext.module.maas:92  ][INFO    ][15867] 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=qa7csc architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 21:01:07,040 [salt.loaded.ext.module.maas:412 ][WARNING ][15867] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 21:01:07,041 [salt.loaded.ext.module.maas:92  ][INFO    ][15867] 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=s6fkg7 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 21:01:07,941 [salt.loaded.ext.module.maas:412 ][WARNING ][15867] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 21:01:07,941 [salt.loaded.ext.module.maas:92  ][INFO    ][15867] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=tn4nke architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 21:01:09,060 [salt.loaded.ext.module.maas:412 ][WARNING ][15867] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 21:01:09,061 [salt.loaded.ext.module.maas:92  ][INFO    ][15867] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=hhn3bm architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 21:01:10,292 [salt.loaded.ext.module.maas:412 ][WARNING ][15867] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 21:01:10,293 [salt.loaded.ext.module.maas:92  ][INFO    ][15867] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=ege3db architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 21:01:11,380 [salt.state       :300 ][INFO    ][15867] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-20 21:01:11,381 [salt.state       :1951][INFO    ][15867] Completed state [maas.process_machines] at time 21:01:11.381033 duration_in_ms=5694.699
2019-04-20 21:01:11,384 [salt.minion      :1711][INFO    ][15867] Returning information for job: 20190420210057895844
2019-04-20 21:01:45,205 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command state.apply with jid 20190420210145198390
2019-04-20 21:01:45,226 [salt.minion      :1432][INFO    ][16143] Starting a new job with PID 16143
2019-04-20 21:01:51,092 [salt.state       :915 ][INFO    ][16143] Loading fresh modules for state activity
2019-04-20 21:01:51,188 [salt.state       :1780][INFO    ][16143] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:51.188361
2019-04-20 21:01:51,188 [salt.state       :1813][INFO    ][16143] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 21:01:51,190 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16143] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 21:01:53,021 [salt.state       :300 ][INFO    ][16143] {'pid': 16163, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 21:01:53,022 [salt.state       :1951][INFO    ][16143] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:53.022453 duration_in_ms=1834.091
2019-04-20 21:01:53,026 [salt.state       :1780][INFO    ][16143] Running state [maas.wait_for_machine_status] at time 21:01:53.026608
2019-04-20 21:01:53,028 [salt.state       :1813][INFO    ][16143] Executing state module.run for [maas.wait_for_machine_status]
2019-04-20 21:01:53,028 [salt.utils.decorators:613 ][WARNING ][16143] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 21:01:55,801 [salt.state       :300 ][INFO    ][16143] {'ret': True}
2019-04-20 21:01:55,801 [salt.state       :1951][INFO    ][16143] Completed state [maas.wait_for_machine_status] at time 21:01:55.801421 duration_in_ms=2774.813
2019-04-20 21:01:55,804 [salt.minion      :1711][INFO    ][16143] Returning information for job: 20190420210145198390
2019-04-20 21:01:56,479 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command state.apply with jid 20190420210156468427
2019-04-20 21:01:56,501 [salt.minion      :1432][INFO    ][16208] Starting a new job with PID 16208
2019-04-20 21:01:57,449 [salt.state       :915 ][INFO    ][16208] Loading fresh modules for state activity
2019-04-20 21:01:57,596 [salt.state       :1780][INFO    ][16208] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:57.596005
2019-04-20 21:01:57,596 [salt.state       :1813][INFO    ][16208] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 21:01:57,598 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16208] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 21:01:59,437 [salt.state       :300 ][INFO    ][16208] {'pid': 16215, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 21:01:59,438 [salt.state       :1951][INFO    ][16208] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:59.437949 duration_in_ms=1841.943
2019-04-20 21:01:59,442 [salt.state       :1780][INFO    ][16208] Running state [maas_machines_storage_cmp002_lvm] at time 21:01:59.442721
2019-04-20 21:01:59,443 [salt.state       :1813][INFO    ][16208] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-20 21:01:59,981 [salt.state       :300 ][INFO    ][16208] Machine cmp002 is not in Ready state.
2019-04-20 21:01:59,981 [salt.state       :1951][INFO    ][16208] Completed state [maas_machines_storage_cmp002_lvm] at time 21:01:59.981529 duration_in_ms=538.808
2019-04-20 21:01:59,981 [salt.state       :1780][INFO    ][16208] Running state [maas_machines_storage_cmp001_lvm] at time 21:01:59.981924
2019-04-20 21:01:59,982 [salt.state       :1813][INFO    ][16208] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-20 21:02:00,504 [salt.state       :300 ][INFO    ][16208] Machine cmp001 is not in Ready state.
2019-04-20 21:02:00,505 [salt.state       :1951][INFO    ][16208] Completed state [maas_machines_storage_cmp001_lvm] at time 21:02:00.505044 duration_in_ms=523.118
2019-04-20 21:02:00,509 [salt.minion      :1711][INFO    ][16208] Returning information for job: 20190420210156468427
2019-04-20 21:02:01,186 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command state.apply with jid 20190420210201176155
2019-04-20 21:02:01,207 [salt.minion      :1432][INFO    ][16225] Starting a new job with PID 16225
2019-04-20 21:02:02,150 [salt.state       :915 ][INFO    ][16225] Loading fresh modules for state activity
2019-04-20 21:02:02,249 [salt.state       :1780][INFO    ][16225] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:02:02.249103
2019-04-20 21:02:02,249 [salt.state       :1813][INFO    ][16225] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 21:02:02,251 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16225] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 21:02:04,092 [salt.state       :300 ][INFO    ][16225] {'pid': 16232, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 21:02:04,094 [salt.state       :1951][INFO    ][16225] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:02:04.093872 duration_in_ms=1844.768
2019-04-20 21:02:04,097 [salt.state       :1780][INFO    ][16225] Running state [maas.deploy_machines] at time 21:02:04.097537
2019-04-20 21:02:04,097 [salt.state       :1813][INFO    ][16225] Executing state module.run for [maas.deploy_machines]
2019-04-20 21:02:04,098 [salt.utils.decorators:613 ][WARNING ][16225] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 21:02:04,644 [salt.loaded.ext.module.maas:684 ][INFO    ][16225] deploymachines hwe_kernel=hwe-16.04 system_id=ege3db distro_series=xenial
2019-04-20 21:02:06,957 [salt.state       :300 ][INFO    ][16225] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-20 21:02:06,957 [salt.state       :1951][INFO    ][16225] Completed state [maas.deploy_machines] at time 21:02:06.957426 duration_in_ms=2859.889
2019-04-20 21:02:06,960 [salt.minion      :1711][INFO    ][16225] Returning information for job: 20190420210201176155
2019-04-20 21:02:07,661 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command state.apply with jid 20190420210207650140
2019-04-20 21:02:07,679 [salt.minion      :1432][INFO    ][16293] Starting a new job with PID 16293
2019-04-20 21:02:13,607 [salt.state       :915 ][INFO    ][16293] Loading fresh modules for state activity
2019-04-20 21:02:13,699 [salt.state       :1780][INFO    ][16293] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:02:13.699006
2019-04-20 21:02:13,699 [salt.state       :1813][INFO    ][16293] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 21:02:13,701 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16293] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 21:02:15,525 [salt.state       :300 ][INFO    ][16293] {'pid': 16311, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 21:02:15,527 [salt.state       :1951][INFO    ][16293] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:02:15.527463 duration_in_ms=1828.457
2019-04-20 21:02:15,530 [salt.state       :1780][INFO    ][16293] Running state [maas.wait_for_machine_status] at time 21:02:15.530298
2019-04-20 21:02:15,532 [salt.state       :1813][INFO    ][16293] Executing state module.run for [maas.wait_for_machine_status]
2019-04-20 21:02:15,532 [salt.utils.decorators:613 ][WARNING ][16293] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 21:02:18,240 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.3057251s left)
2019-04-20 21:02:22,724 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210222668173
2019-04-20 21:02:22,740 [salt.minion      :1432][INFO    ][16324] Starting a new job with PID 16324
2019-04-20 21:02:22,772 [salt.minion      :1711][INFO    ][16324] Returning information for job: 20190420210222668173
2019-04-20 21:02:50,828 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.71774507s left)
2019-04-20 21:02:52,798 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210252783942
2019-04-20 21:02:52,822 [salt.minion      :1432][INFO    ][16383] Starting a new job with PID 16383
2019-04-20 21:02:52,853 [salt.minion      :1711][INFO    ][16383] Returning information for job: 20190420210252783942
2019-04-20 21:03:22,870 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210322862141
2019-04-20 21:03:22,885 [salt.minion      :1432][INFO    ][16428] Starting a new job with PID 16428
2019-04-20 21:03:22,917 [salt.minion      :1711][INFO    ][16428] Returning information for job: 20190420210322862141
2019-04-20 21:03:23,601 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.94419098s left)
2019-04-20 21:03:52,941 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210352924627
2019-04-20 21:03:52,965 [salt.minion      :1432][INFO    ][16490] Starting a new job with PID 16490
2019-04-20 21:03:52,994 [salt.minion      :1711][INFO    ][16490] Returning information for job: 20190420210352924627
2019-04-20 21:03:56,526 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.01955295s left)
2019-04-20 21:04:23,010 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210422998663
2019-04-20 21:04:23,025 [salt.minion      :1432][INFO    ][16547] Starting a new job with PID 16547
2019-04-20 21:04:23,057 [salt.minion      :1711][INFO    ][16547] Returning information for job: 20190420210422998663
2019-04-20 21:04:29,172 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.37357402s left)
2019-04-20 21:04:53,078 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210453070943
2019-04-20 21:04:53,097 [salt.minion      :1432][INFO    ][16605] Starting a new job with PID 16605
2019-04-20 21:04:53,131 [salt.minion      :1711][INFO    ][16605] Returning information for job: 20190420210453070943
2019-04-20 21:05:01,852 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.69337106s left)
2019-04-20 21:05:23,156 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210523140524
2019-04-20 21:05:23,178 [salt.minion      :1432][INFO    ][16651] Starting a new job with PID 16651
2019-04-20 21:05:23,207 [salt.minion      :1711][INFO    ][16651] Returning information for job: 20190420210523140524
2019-04-20 21:05:34,482 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.0632s left)
2019-04-20 21:05:53,221 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210553209495
2019-04-20 21:05:53,239 [salt.minion      :1432][INFO    ][16711] Starting a new job with PID 16711
2019-04-20 21:05:53,278 [salt.minion      :1711][INFO    ][16711] Returning information for job: 20190420210553209495
2019-04-20 21:06:07,257 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.28897309s left)
2019-04-20 21:06:23,302 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210623295795
2019-04-20 21:06:23,318 [salt.minion      :1432][INFO    ][16778] Starting a new job with PID 16778
2019-04-20 21:06:23,348 [salt.minion      :1711][INFO    ][16778] Returning information for job: 20190420210623295795
2019-04-20 21:06:40,327 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.21882105s left)
2019-04-20 21:06:53,353 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210653343178
2019-04-20 21:06:53,373 [salt.minion      :1432][INFO    ][16843] Starting a new job with PID 16843
2019-04-20 21:06:53,413 [salt.minion      :1711][INFO    ][16843] Returning information for job: 20190420210653343178
2019-04-20 21:07:13,045 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.50023103s left)
2019-04-20 21:07:23,465 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210723448792
2019-04-20 21:07:23,479 [salt.minion      :1432][INFO    ][16913] Starting a new job with PID 16913
2019-04-20 21:07:23,514 [salt.minion      :1711][INFO    ][16913] Returning information for job: 20190420210723448792
2019-04-20 21:07:45,701 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.84442997s left)
2019-04-20 21:07:53,553 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210753541619
2019-04-20 21:07:53,577 [salt.minion      :1432][INFO    ][17027] Starting a new job with PID 17027
2019-04-20 21:07:53,617 [salt.minion      :1711][INFO    ][17027] Returning information for job: 20190420210753541619
2019-04-20 21:08:18,412 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.13335204s left)
2019-04-20 21:08:23,672 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210823659316
2019-04-20 21:08:23,696 [salt.minion      :1432][INFO    ][17162] Starting a new job with PID 17162
2019-04-20 21:08:23,728 [salt.minion      :1711][INFO    ][17162] Returning information for job: 20190420210823659316
2019-04-20 21:08:51,098 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1854.44763088s left)
2019-04-20 21:08:53,772 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210853764198
2019-04-20 21:08:53,792 [salt.minion      :1432][INFO    ][17252] Starting a new job with PID 17252
2019-04-20 21:08:53,822 [salt.minion      :1711][INFO    ][17252] Returning information for job: 20190420210853764198
2019-04-20 21:09:23,890 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210923881352
2019-04-20 21:09:23,905 [salt.minion      :1432][INFO    ][17286] Starting a new job with PID 17286
2019-04-20 21:09:23,937 [salt.minion      :1711][INFO    ][17286] Returning information for job: 20190420210923881352
2019-04-20 21:09:23,960 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.58544588s left)
2019-04-20 21:09:54,013 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420210954001840
2019-04-20 21:09:54,034 [salt.minion      :1432][INFO    ][17439] Starting a new job with PID 17439
2019-04-20 21:09:54,074 [salt.minion      :1711][INFO    ][17439] Returning information for job: 20190420210954001840
2019-04-20 21:09:57,227 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1788.31915998s left)
2019-04-20 21:10:24,166 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420211024153290
2019-04-20 21:10:24,187 [salt.minion      :1432][INFO    ][17506] Starting a new job with PID 17506
2019-04-20 21:10:24,218 [salt.minion      :1711][INFO    ][17506] Returning information for job: 20190420211024153290
2019-04-20 21:10:30,056 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1755.48966289s left)
2019-04-20 21:10:54,308 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420211054299900
2019-04-20 21:10:54,321 [salt.minion      :1432][INFO    ][17656] Starting a new job with PID 17656
2019-04-20 21:10:54,354 [salt.minion      :1711][INFO    ][17656] Returning information for job: 20190420211054299900
2019-04-20 21:11:02,786 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1722.7592659s left)
2019-04-20 21:11:24,436 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420211124424918
2019-04-20 21:11:24,452 [salt.minion      :1432][INFO    ][17682] Starting a new job with PID 17682
2019-04-20 21:11:24,482 [salt.minion      :1711][INFO    ][17682] Returning information for job: 20190420211124424918
2019-04-20 21:11:35,536 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1690.00951195s left)
2019-04-20 21:11:54,596 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420211154584321
2019-04-20 21:11:54,619 [salt.minion      :1432][INFO    ][17759] Starting a new job with PID 17759
2019-04-20 21:11:54,649 [salt.minion      :1711][INFO    ][17759] Returning information for job: 20190420211154584321
2019-04-20 21:12:08,446 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1657.10001707s left)
2019-04-20 21:12:24,749 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420211224732619
2019-04-20 21:12:24,766 [salt.minion      :1432][INFO    ][17783] Starting a new job with PID 17783
2019-04-20 21:12:24,796 [salt.minion      :1711][INFO    ][17783] Returning information for job: 20190420211224732619
2019-04-20 21:12:41,324 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1624.22181392s left)
2019-04-20 21:12:54,918 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420211254907549
2019-04-20 21:12:54,936 [salt.minion      :1432][INFO    ][17863] Starting a new job with PID 17863
2019-04-20 21:12:54,970 [salt.minion      :1711][INFO    ][17863] Returning information for job: 20190420211254907549
2019-04-20 21:13:14,063 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1591.48245597s left)
2019-04-20 21:13:25,070 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420211325056340
2019-04-20 21:13:25,090 [salt.minion      :1432][INFO    ][17885] Starting a new job with PID 17885
2019-04-20 21:13:25,121 [salt.minion      :1711][INFO    ][17885] Returning information for job: 20190420211325056340
2019-04-20 21:13:46,873 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1558.67307806s left)
2019-04-20 21:13:55,254 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420211355245193
2019-04-20 21:13:55,271 [salt.minion      :1432][INFO    ][17991] Starting a new job with PID 17991
2019-04-20 21:13:55,301 [salt.minion      :1711][INFO    ][17991] Returning information for job: 20190420211355245193
2019-04-20 21:14:19,585 [salt.loaded.ext.module.maas:1023][INFO    ][16293] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1525.96034408s left)
2019-04-20 21:14:25,409 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420211425396492
2019-04-20 21:14:25,428 [salt.minion      :1432][INFO    ][18177] Starting a new job with PID 18177
2019-04-20 21:14:25,464 [salt.minion      :1711][INFO    ][18177] Returning information for job: 20190420211425396492
2019-04-20 21:14:52,537 [salt.state       :300 ][INFO    ][16293] {'ret': True}
2019-04-20 21:14:52,538 [salt.state       :1951][INFO    ][16293] Completed state [maas.wait_for_machine_status] at time 21:14:52.538440 duration_in_ms=757008.135
2019-04-20 21:14:52,543 [salt.minion      :1711][INFO    ][16293] Returning information for job: 20190420210207650140
2019-04-20 21:14:55,690 [salt.utils.schedule:1377][INFO    ][6085] Running scheduled job: __mine_interval
2019-04-20 22:14:55,690 [salt.utils.schedule:1377][INFO    ][6085] Running scheduled job: __mine_interval
2019-04-20 22:49:39,672 [salt.minion      :1308][INFO    ][6085] User sudo_ubuntu Executing command cp.push_dir with jid 20190420224939662238
2019-04-20 22:49:39,693 [salt.minion      :1432][INFO    ][27928] Starting a new job with PID 27928
