2019-03-10 05:14:11,220 [salt.utils.decorators:613 ][WARNING ][2170] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 05:14:12,104 [salt.utils.decorators:613 ][WARNING ][2170] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 05:14:14,690 [salt.loaded.int.states.file:2298][WARNING ][2468] 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-03-10 05:14:41,875 [salt.state       :2022][WARNING ][2948] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-10 05:14:45,036 [salt.utils.decorators:613 ][WARNING ][2948] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 05:14:58,014 [salt.utils.decorators:613 ][WARNING ][2948] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 05:15:24,727 [salt.utils.decorators:613 ][WARNING ][2948] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 05:15:25,622 [salt.utils.decorators:613 ][WARNING ][2948] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 05:15:28,204 [salt.loaded.ext.module.maasng:1008][WARNING ][2948] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-03-10 05:15:28,204 [salt.loaded.ext.module.maasng:1011][WARNING ][2948] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-03-10 05:15:28,853 [salt.loaded.ext.module.maasng:1235][WARNING ][2948] Ignoring parameter vlan:0
2019-03-10 05:15:29,685 [salt.utils.decorators:613 ][WARNING ][2948] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 05:15:38,384 [salt.utils.decorators:613 ][WARNING ][5631] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 05:15:38,437 [salt.loaded.ext.module.maas:412 ][WARNING ][5631] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-10 05:15:39,683 [salt.loaded.ext.module.maas:412 ][WARNING ][5631] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-10 05:15:40,776 [salt.loaded.ext.module.maas:412 ][WARNING ][5631] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-10 05:15:41,893 [salt.loaded.ext.module.maas:412 ][WARNING ][5631] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-10 05:15:42,972 [salt.loaded.ext.module.maas:412 ][WARNING ][5631] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-10 05:15:47,464 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6225] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-10 05:15:47,498 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6225] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-10 05:15:47,549 [salt.utils.parsers:1051][WARNING ][383] Minion received a SIGTERM. Exiting.
2019-03-10 05:15:48,836 [salt.cli.daemons :293 ][INFO    ][6305] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-03-10 05:15:48,956 [salt.cli.daemons :82  ][INFO    ][6305] Starting up the Salt Minion
2019-03-10 05:15:48,956 [salt.utils.event :1017][INFO    ][6305] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-03-10 05:15:50,094 [salt.minion      :976 ][INFO    ][6305] Creating minion process manager
2019-03-10 05:15:51,800 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6305] Executing command ['date', '+%z'] in directory '/root'
2019-03-10 05:15:51,820 [salt.utils.schedule:568 ][INFO    ][6305] Updating job settings for scheduled job: __mine_interval
2019-03-10 05:15:51,823 [salt.minion      :1108][INFO    ][6305] Added mine.update to scheduler
2019-03-10 05:15:51,834 [salt.minion      :1975][INFO    ][6305] Minion is starting as user 'root'
2019-03-10 05:15:51,850 [salt.minion      :2336][INFO    ][6305] Minion is ready to receive requests!
2019-03-10 05:16:15,152 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command state.apply with jid 20190310051615141305
2019-03-10 05:16:15,170 [salt.minion      :1432][INFO    ][6419] Starting a new job with PID 6419
2019-03-10 05:16:20,754 [salt.state       :915 ][INFO    ][6419] Loading fresh modules for state activity
2019-03-10 05:16:20,809 [salt.fileclient  :1219][INFO    ][6419] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-10 05:16:20,854 [salt.state       :1780][INFO    ][6419] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:20.854744
2019-03-10 05:16:20,855 [salt.state       :1813][INFO    ][6419] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-10 05:16:20,856 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6419] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-10 05:16:22,529 [salt.state       :300 ][INFO    ][6419] {'pid': 6427, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-10 05:16:22,530 [salt.state       :1951][INFO    ][6419] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:22.530368 duration_in_ms=1675.623
2019-03-10 05:16:22,533 [salt.state       :1780][INFO    ][6419] Running state [maas.wait_for_machine_status] at time 05:16:22.533564
2019-03-10 05:16:22,534 [salt.state       :1813][INFO    ][6419] Executing state module.run for [maas.wait_for_machine_status]
2019-03-10 05:16:22,535 [salt.utils.decorators:613 ][WARNING ][6419] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 05:16:23,687 [salt.loaded.ext.module.maas:1023][INFO    ][6419] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.85780311s left)
2019-03-10 05:16:30,288 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310051630268589
2019-03-10 05:16:30,311 [salt.minion      :1432][INFO    ][6438] Starting a new job with PID 6438
2019-03-10 05:16:30,332 [salt.minion      :1711][INFO    ][6438] Returning information for job: 20190310051630268589
2019-03-10 05:16:54,513 [salt.loaded.ext.module.maas:1023][INFO    ][6419] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.03233218s left)
2019-03-10 05:17:00,325 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310051700309626
2019-03-10 05:17:00,345 [salt.minion      :1432][INFO    ][6545] Starting a new job with PID 6545
2019-03-10 05:17:00,368 [salt.minion      :1711][INFO    ][6545] Returning information for job: 20190310051700309626
2019-03-10 05:17:25,253 [salt.loaded.ext.module.maas:1023][INFO    ][6419] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.29219413s left)
2019-03-10 05:17:30,514 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310051730505044
2019-03-10 05:17:30,539 [salt.minion      :1432][INFO    ][6570] Starting a new job with PID 6570
2019-03-10 05:17:30,558 [salt.minion      :1711][INFO    ][6570] Returning information for job: 20190310051730505044
2019-03-10 05:17:55,969 [salt.loaded.ext.module.maas:1023][INFO    ][6419] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.57628512s left)
2019-03-10 05:18:00,705 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310051800697847
2019-03-10 05:18:00,729 [salt.minion      :1432][INFO    ][6649] Starting a new job with PID 6649
2019-03-10 05:18:00,756 [salt.minion      :1711][INFO    ][6649] Returning information for job: 20190310051800697847
2019-03-10 05:18:27,001 [salt.loaded.ext.module.maas:1023][INFO    ][6419] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.543432s left)
2019-03-10 05:18:30,902 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310051830894530
2019-03-10 05:18:30,917 [salt.minion      :1432][INFO    ][6698] Starting a new job with PID 6698
2019-03-10 05:18:30,935 [salt.minion      :1711][INFO    ][6698] Returning information for job: 20190310051830894530
2019-03-10 05:18:57,897 [salt.loaded.ext.module.maas:1023][INFO    ][6419] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.64774203s left)
2019-03-10 05:19:00,955 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310051900945314
2019-03-10 05:19:00,970 [salt.minion      :1432][INFO    ][6877] Starting a new job with PID 6877
2019-03-10 05:19:00,990 [salt.minion      :1711][INFO    ][6877] Returning information for job: 20190310051900945314
2019-03-10 05:19:29,161 [salt.loaded.ext.module.maas:1023][INFO    ][6419] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.38370299s left)
2019-03-10 05:19:31,148 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310051931140645
2019-03-10 05:19:31,171 [salt.minion      :1432][INFO    ][6985] Starting a new job with PID 6985
2019-03-10 05:19:31,195 [salt.minion      :1711][INFO    ][6985] Returning information for job: 20190310051931140645
2019-03-10 05:20:00,297 [salt.loaded.ext.module.maas:1023][INFO    ][6419] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.247895s left)
2019-03-10 05:20:01,169 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052001160761
2019-03-10 05:20:01,184 [salt.minion      :1432][INFO    ][7300] Starting a new job with PID 7300
2019-03-10 05:20:01,202 [salt.minion      :1711][INFO    ][7300] Returning information for job: 20190310052001160761
2019-03-10 05:20:31,389 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052031375054
2019-03-10 05:20:31,412 [salt.minion      :1432][INFO    ][7456] Starting a new job with PID 7456
2019-03-10 05:20:31,436 [salt.minion      :1711][INFO    ][7456] Returning information for job: 20190310052031375054
2019-03-10 05:20:32,198 [salt.loaded.ext.module.maas:1023][INFO    ][6419] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.34700823s left)
2019-03-10 05:21:01,448 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052101431838
2019-03-10 05:21:01,469 [salt.minion      :1432][INFO    ][7733] Starting a new job with PID 7733
2019-03-10 05:21:01,490 [salt.minion      :1711][INFO    ][7733] Returning information for job: 20190310052101431838
2019-03-10 05:21:04,238 [salt.loaded.ext.module.maas:1023][INFO    ][6419] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.30678821s left)
2019-03-10 05:21:31,652 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052131643529
2019-03-10 05:21:31,673 [salt.minion      :1432][INFO    ][7852] Starting a new job with PID 7852
2019-03-10 05:21:31,692 [salt.minion      :1711][INFO    ][7852] Returning information for job: 20190310052131643529
2019-03-10 05:21:36,675 [salt.loaded.ext.module.maas:1023][INFO    ][6419] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.86955404s left)
2019-03-10 05:22:01,669 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052201657781
2019-03-10 05:22:01,689 [salt.minion      :1432][INFO    ][8160] Starting a new job with PID 8160
2019-03-10 05:22:01,710 [salt.minion      :1711][INFO    ][8160] Returning information for job: 20190310052201657781
2019-03-10 05:22:08,877 [salt.loaded.ext.module.maas:1023][INFO    ][6419] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.66766405s left)
2019-03-10 05:22:31,867 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052231857364
2019-03-10 05:22:31,891 [salt.minion      :1432][INFO    ][8226] Starting a new job with PID 8226
2019-03-10 05:22:31,910 [salt.minion      :1711][INFO    ][8226] Returning information for job: 20190310052231857364
2019-03-10 05:22:41,353 [salt.loaded.ext.module.maas:1023][INFO    ][6419] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1121.19168806s left)
2019-03-10 05:23:01,905 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052301897161
2019-03-10 05:23:01,931 [salt.minion      :1432][INFO    ][8416] Starting a new job with PID 8416
2019-03-10 05:23:01,954 [salt.minion      :1711][INFO    ][8416] Returning information for job: 20190310052301897161
2019-03-10 05:23:14,229 [salt.state       :300 ][INFO    ][6419] {'ret': True}
2019-03-10 05:23:14,229 [salt.state       :1951][INFO    ][6419] Completed state [maas.wait_for_machine_status] at time 05:23:14.229875 duration_in_ms=411696.31
2019-03-10 05:23:14,233 [salt.minion      :1711][INFO    ][6419] Returning information for job: 20190310051615141305
2019-03-10 05:23:14,844 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command state.apply with jid 20190310052314827246
2019-03-10 05:23:14,864 [salt.minion      :1432][INFO    ][8462] Starting a new job with PID 8462
2019-03-10 05:23:20,596 [salt.state       :915 ][INFO    ][8462] Loading fresh modules for state activity
2019-03-10 05:23:20,645 [salt.fileclient  :1219][INFO    ][8462] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-10 05:23:20,735 [salt.state       :1780][INFO    ][8462] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:23:20.735712
2019-03-10 05:23:20,736 [salt.state       :1813][INFO    ][8462] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-10 05:23:20,737 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8462] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-10 05:23:22,493 [salt.state       :300 ][INFO    ][8462] {'pid': 8475, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-10 05:23:22,494 [salt.state       :1951][INFO    ][8462] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:23:22.494117 duration_in_ms=1758.405
2019-03-10 05:23:22,497 [salt.state       :1780][INFO    ][8462] Running state [maas_machines_storage_cmp002_lvm] at time 05:23:22.496998
2019-03-10 05:23:22,497 [salt.state       :1813][INFO    ][8462] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-10 05:23:23,457 [salt.loaded.ext.module.maasng:610 ][INFO    ][8462] sepn47
2019-03-10 05:23:23,458 [salt.loaded.ext.module.maasng:626 ][INFO    ][8462] sda
2019-03-10 05:23:23,982 [salt.loaded.ext.module.maasng:361 ][INFO    ][8462] sepn47
2019-03-10 05:23:24,077 [salt.loaded.ext.module.maasng:367 ][INFO    ][8462] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/blockdevices/1/', u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'tags': [u'ssd'], u'name': u'sda', u'system_id': u'sepn47', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'size': 800101236736, u'uuid': u'37219e05-0732-4146-bbd2-7691b1e3f24b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sepn47', u'filesystem': {u'uuid': u'bc5d81fb-fef2-4397-9656-2a6cb1796c20', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'uuid': u'ef6ef7f1-951d-4e41-a56a-3fee2fe41133', u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/blockdevices/3/', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'tags': [], u'name': u'vgroot-lvroot', u'system_id': u'sepn47', u'partition_table_type': None, u'filesystem': {u'uuid': u'd866e42b-41da-4b68-8e0d-13c3e5bef239', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'partitions': [], u'type': u'virtual', u'id': 3, u'serial': None, u'size': 800097042432}]
2019-03-10 05:23:24,078 [salt.loaded.ext.module.maasng:632 ][INFO    ][8462] vgroot
2019-03-10 05:23:24,078 [salt.loaded.ext.module.maasng:635 ][INFO    ][8462] lvroot
2019-03-10 05:23:24,078 [salt.loaded.ext.module.maasng:639 ][INFO    ][8462] 107374182400
2019-03-10 05:23:24,668 [salt.loaded.ext.module.maasng:645 ][INFO    ][8462] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'status_action': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], 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'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'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'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'sepn47', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/interfaces/4/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'e572076c-cc77-434e-8fea-b7700c4c733f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sepn47', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a2294c61-18cb-47e0-8ec9-46a92e571074', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/blockdevices/1/partition/6'}], u'filesystem': None, u'name': u'sda', u'system_id': u'sepn47', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/blockdevices/1/'}, u'blockdevice_set': [{u'size': 800109715456, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'e572076c-cc77-434e-8fea-b7700c4c733f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sepn47', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a2294c61-18cb-47e0-8ec9-46a92e571074', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/blockdevices/1/partition/6'}], u'filesystem': None, u'name': u'sda', u'system_id': u'sepn47', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/blockdevices/1/'}, {u'size': 107374182400, u'uuid': u'98d9bc11-02b0-43f0-b334-a34851fb7820', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'368178ec-2e8b-4e4e-a2e8-d9522babb155', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'sepn47', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/blockdevices/11/'}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'sepn47', 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'uuid': u'98d9bc11-02b0-43f0-b334-a34851fb7820', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'368178ec-2e8b-4e4e-a2e8-d9522babb155', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'sepn47', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/blockdevices/11/'}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'e572076c-cc77-434e-8fea-b7700c4c733f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sepn47', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a2294c61-18cb-47e0-8ec9-46a92e571074', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/blockdevices/1/partition/6'}], u'filesystem': None, u'name': u'sda', u'system_id': u'sepn47', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/blockdevices/1/'}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'sepn47', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], 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'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'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'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'sepn47', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/interfaces/4/'}, {u'name': u'ens2f1', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'sepn47', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/interfaces/13/'}, {u'name': u'ens2f0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'id': 14, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'sepn47', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/interfaces/14/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'id': 15, 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'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'sepn47', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/interfaces/15/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'sepn47', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/interfaces/11/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, 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'effective_mtu': 1500, u'system_id': u'sepn47', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sepn47/interfaces/12/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/sepn47/', u'distro_series': u'', u'memory_test_status': -1}
2019-03-10 05:23:24,670 [salt.state       :300 ][INFO    ][8462] {'new': {'storage_layout': 'lvm'}}
2019-03-10 05:23:24,670 [salt.state       :1951][INFO    ][8462] Completed state [maas_machines_storage_cmp002_lvm] at time 05:23:24.670751 duration_in_ms=2173.75
2019-03-10 05:23:24,671 [salt.state       :1780][INFO    ][8462] Running state [maas_machines_storage_cmp001_lvm] at time 05:23:24.671419
2019-03-10 05:23:24,672 [salt.state       :1813][INFO    ][8462] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-10 05:23:25,788 [salt.loaded.ext.module.maasng:610 ][INFO    ][8462] mecgnf
2019-03-10 05:23:25,789 [salt.loaded.ext.module.maasng:626 ][INFO    ][8462] sda
2019-03-10 05:23:26,315 [salt.loaded.ext.module.maasng:361 ][INFO    ][8462] mecgnf
2019-03-10 05:23:26,424 [salt.loaded.ext.module.maasng:367 ][INFO    ][8462] [{u'size': 800109715456, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'e77dbdb5-3e18-4701-90f4-62014fe1e942', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mecgnf', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b6185245-aefb-4a31-ac64-8bdb4ed25fd6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/blockdevices/2/partition/2'}], u'filesystem': None, u'name': u'sda', u'system_id': u'mecgnf', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/blockdevices/2/'}, {u'size': 800097042432, u'uuid': u'b0abd9e9-7b21-478c-ab85-a1f7c54e8321', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'b0403f78-be48-487b-b1bd-c12cb5e1fccc', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'mecgnf', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/blockdevices/4/'}]
2019-03-10 05:23:26,424 [salt.loaded.ext.module.maasng:632 ][INFO    ][8462] vgroot
2019-03-10 05:23:26,425 [salt.loaded.ext.module.maasng:635 ][INFO    ][8462] lvroot
2019-03-10 05:23:26,425 [salt.loaded.ext.module.maasng:639 ][INFO    ][8462] 107374182400
2019-03-10 05:23:27,013 [salt.loaded.ext.module.maasng:645 ][INFO    ][8462] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'mecgnf', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 25}], 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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'system_id': u'mecgnf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/interfaces/5/'}, u'blockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'720f78d0-c42c-4281-aaa8-44ba4ebe9735', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mecgnf', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'cf029e7d-27dd-407e-99cf-4db2756c45f4'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mecgnf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/blockdevices/2/'}, {u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'2a9dc9d1-b7ee-48bc-9301-5e185a569236', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'mecgnf', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'1001316d-d578-4332-81d1-25d24ce8af3e'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/blockdevices/12/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'mecgnf', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'720f78d0-c42c-4281-aaa8-44ba4ebe9735', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mecgnf', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'cf029e7d-27dd-407e-99cf-4db2756c45f4'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mecgnf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/blockdevices/2/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'720f78d0-c42c-4281-aaa8-44ba4ebe9735', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mecgnf', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'cf029e7d-27dd-407e-99cf-4db2756c45f4'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mecgnf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/blockdevices/2/'}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'2a9dc9d1-b7ee-48bc-9301-5e185a569236', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'mecgnf', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'1001316d-d578-4332-81d1-25d24ce8af3e'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/blockdevices/12/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 25}], 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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'system_id': u'mecgnf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/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'system_id': u'mecgnf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/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'system_id': u'mecgnf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/interfaces/17/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'id': 18, u'discovered': None, u'system_id': u'mecgnf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/interfaces/18/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'id': 19, u'discovered': None, u'system_id': u'mecgnf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/interfaces/19/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 28}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'id': 20, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'system_id': u'mecgnf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mecgnf/interfaces/20/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/mecgnf/', u'distro_series': u'', u'memory_test_status': -1}
2019-03-10 05:23:27,014 [salt.state       :300 ][INFO    ][8462] {'new': {'storage_layout': 'lvm'}}
2019-03-10 05:23:27,016 [salt.state       :1951][INFO    ][8462] Completed state [maas_machines_storage_cmp001_lvm] at time 05:23:27.014812 duration_in_ms=2343.392
2019-03-10 05:23:27,018 [salt.minion      :1711][INFO    ][8462] Returning information for job: 20190310052314827246
2019-03-10 05:23:27,561 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command state.apply with jid 20190310052327549986
2019-03-10 05:23:27,583 [salt.minion      :1432][INFO    ][8493] Starting a new job with PID 8493
2019-03-10 05:23:28,278 [salt.state       :915 ][INFO    ][8493] Loading fresh modules for state activity
2019-03-10 05:23:28,332 [salt.fileclient  :1219][INFO    ][8493] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-10 05:23:28,377 [salt.state       :1780][INFO    ][8493] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:23:28.377239
2019-03-10 05:23:28,377 [salt.state       :1813][INFO    ][8493] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-10 05:23:28,379 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8493] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-10 05:23:30,100 [salt.state       :300 ][INFO    ][8493] {'pid': 8500, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-10 05:23:30,100 [salt.state       :1951][INFO    ][8493] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:23:30.100651 duration_in_ms=1723.413
2019-03-10 05:23:30,102 [salt.state       :1780][INFO    ][8493] Running state [maas.deploy_machines] at time 05:23:30.102060
2019-03-10 05:23:30,102 [salt.state       :1813][INFO    ][8493] Executing state module.run for [maas.deploy_machines]
2019-03-10 05:23:30,102 [salt.utils.decorators:613 ][WARNING ][8493] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 05:23:30,683 [salt.loaded.ext.module.maas:684 ][INFO    ][8493] deploymachines hwe_kernel=hwe-16.04 system_id=sepn47 distro_series=xenial
2019-03-10 05:23:33,007 [salt.loaded.ext.module.maas:684 ][INFO    ][8493] deploymachines hwe_kernel=hwe-16.04 system_id=mecgnf distro_series=xenial
2019-03-10 05:23:35,422 [salt.loaded.ext.module.maas:684 ][INFO    ][8493] deploymachines hwe_kernel=hwe-16.04 system_id=c8ta8m distro_series=xenial
2019-03-10 05:23:37,752 [salt.loaded.ext.module.maas:684 ][INFO    ][8493] deploymachines hwe_kernel=hwe-16.04 system_id=qgyexn distro_series=xenial
2019-03-10 05:23:40,061 [salt.loaded.ext.module.maas:684 ][INFO    ][8493] deploymachines hwe_kernel=hwe-16.04 system_id=k7nnbx distro_series=xenial
2019-03-10 05:23:42,368 [salt.state       :300 ][INFO    ][8493] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-10 05:23:42,369 [salt.state       :1951][INFO    ][8493] Completed state [maas.deploy_machines] at time 05:23:42.369292 duration_in_ms=12267.231
2019-03-10 05:23:42,371 [salt.minion      :1711][INFO    ][8493] Returning information for job: 20190310052327549986
2019-03-10 05:23:42,914 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command state.apply with jid 20190310052342902543
2019-03-10 05:23:42,934 [salt.minion      :1432][INFO    ][8784] Starting a new job with PID 8784
2019-03-10 05:23:48,689 [salt.state       :915 ][INFO    ][8784] Loading fresh modules for state activity
2019-03-10 05:23:48,739 [salt.fileclient  :1219][INFO    ][8784] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-10 05:23:48,779 [salt.state       :1780][INFO    ][8784] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:23:48.779890
2019-03-10 05:23:48,780 [salt.state       :1813][INFO    ][8784] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-10 05:23:48,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8784] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-10 05:23:50,534 [salt.state       :300 ][INFO    ][8784] {'pid': 8834, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-10 05:23:50,534 [salt.state       :1951][INFO    ][8784] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:23:50.534742 duration_in_ms=1754.851
2019-03-10 05:23:50,536 [salt.state       :1780][INFO    ][8784] Running state [maas.wait_for_machine_status] at time 05:23:50.536752
2019-03-10 05:23:50,537 [salt.state       :1813][INFO    ][8784] Executing state module.run for [maas.wait_for_machine_status]
2019-03-10 05:23:50,537 [salt.utils.decorators:613 ][WARNING ][8784] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 05:23:53,293 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.25213003s left)
2019-03-10 05:23:57,988 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052357979083
2019-03-10 05:23:58,002 [salt.minion      :1432][INFO    ][8859] Starting a new job with PID 8859
2019-03-10 05:23:58,024 [salt.minion      :1711][INFO    ][8859] Returning information for job: 20190310052357979083
2019-03-10 05:24:25,827 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.71763992s left)
2019-03-10 05:24:28,024 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052428013449
2019-03-10 05:24:28,037 [salt.minion      :1432][INFO    ][8883] Starting a new job with PID 8883
2019-03-10 05:24:28,057 [salt.minion      :1711][INFO    ][8883] Returning information for job: 20190310052428013449
2019-03-10 05:24:58,079 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052458071777
2019-03-10 05:24:58,097 [salt.minion      :1432][INFO    ][8976] Starting a new job with PID 8976
2019-03-10 05:24:58,117 [salt.minion      :1711][INFO    ][8976] Returning information for job: 20190310052458071777
2019-03-10 05:24:58,766 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.77949095s left)
2019-03-10 05:25:28,147 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052528138216
2019-03-10 05:25:28,162 [salt.minion      :1432][INFO    ][9000] Starting a new job with PID 9000
2019-03-10 05:25:28,188 [salt.minion      :1711][INFO    ][9000] Returning information for job: 20190310052528138216
2019-03-10 05:25:31,458 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2149.08678102s left)
2019-03-10 05:25:58,350 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052558342101
2019-03-10 05:25:58,369 [salt.minion      :1432][INFO    ][9074] Starting a new job with PID 9074
2019-03-10 05:25:58,389 [salt.minion      :1711][INFO    ][9074] Returning information for job: 20190310052558342101
2019-03-10 05:26:04,708 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.83665204s left)
2019-03-10 05:26:28,412 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052628397551
2019-03-10 05:26:28,428 [salt.minion      :1432][INFO    ][9119] Starting a new job with PID 9119
2019-03-10 05:26:28,450 [salt.minion      :1711][INFO    ][9119] Returning information for job: 20190310052628397551
2019-03-10 05:26:37,390 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.15497494s left)
2019-03-10 05:26:58,500 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052658491484
2019-03-10 05:26:58,518 [salt.minion      :1432][INFO    ][9292] Starting a new job with PID 9292
2019-03-10 05:26:58,544 [salt.minion      :1711][INFO    ][9292] Returning information for job: 20190310052658491484
2019-03-10 05:27:10,325 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.21995497s left)
2019-03-10 05:27:28,584 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052728577311
2019-03-10 05:27:28,605 [salt.minion      :1432][INFO    ][9398] Starting a new job with PID 9398
2019-03-10 05:27:28,633 [salt.minion      :1711][INFO    ][9398] Returning information for job: 20190310052728577311
2019-03-10 05:27:43,214 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.33076191s left)
2019-03-10 05:27:58,730 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052758722714
2019-03-10 05:27:58,749 [salt.minion      :1432][INFO    ][9677] Starting a new job with PID 9677
2019-03-10 05:27:58,777 [salt.minion      :1711][INFO    ][9677] Returning information for job: 20190310052758722714
2019-03-10 05:28:16,409 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.13576412s left)
2019-03-10 05:28:28,785 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052828776023
2019-03-10 05:28:28,811 [salt.minion      :1432][INFO    ][9769] Starting a new job with PID 9769
2019-03-10 05:28:28,841 [salt.minion      :1711][INFO    ][9769] Returning information for job: 20190310052828776023
2019-03-10 05:28:49,625 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.9195199s left)
2019-03-10 05:28:58,920 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052858911993
2019-03-10 05:28:58,936 [salt.minion      :1432][INFO    ][10007] Starting a new job with PID 10007
2019-03-10 05:28:58,964 [salt.minion      :1711][INFO    ][10007] Returning information for job: 20190310052858911993
2019-03-10 05:29:22,529 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.01587009s left)
2019-03-10 05:29:29,034 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052929021411
2019-03-10 05:29:29,057 [salt.minion      :1432][INFO    ][10103] Starting a new job with PID 10103
2019-03-10 05:29:29,082 [salt.minion      :1711][INFO    ][10103] Returning information for job: 20190310052929021411
2019-03-10 05:29:55,499 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.04602313s left)
2019-03-10 05:29:59,156 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310052959144003
2019-03-10 05:29:59,169 [salt.minion      :1432][INFO    ][10435] Starting a new job with PID 10435
2019-03-10 05:29:59,198 [salt.minion      :1711][INFO    ][10435] Returning information for job: 20190310052959144003
2019-03-10 05:30:28,358 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1852.18748999s left)
2019-03-10 05:30:29,237 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053029224698
2019-03-10 05:30:29,256 [salt.minion      :1432][INFO    ][10550] Starting a new job with PID 10550
2019-03-10 05:30:29,286 [salt.minion      :1711][INFO    ][10550] Returning information for job: 20190310053029224698
2019-03-10 05:30:59,358 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053059345862
2019-03-10 05:30:59,375 [salt.minion      :1432][INFO    ][10684] Starting a new job with PID 10684
2019-03-10 05:30:59,404 [salt.minion      :1711][INFO    ][10684] Returning information for job: 20190310053059345862
2019-03-10 05:31:01,117 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1819.42756605s left)
2019-03-10 05:31:29,483 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053129467681
2019-03-10 05:31:29,506 [salt.minion      :1432][INFO    ][10735] Starting a new job with PID 10735
2019-03-10 05:31:29,533 [salt.minion      :1711][INFO    ][10735] Returning information for job: 20190310053129467681
2019-03-10 05:31:33,882 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1786.66275096s left)
2019-03-10 05:31:59,638 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053159628097
2019-03-10 05:31:59,659 [salt.minion      :1432][INFO    ][10990] Starting a new job with PID 10990
2019-03-10 05:31:59,687 [salt.minion      :1711][INFO    ][10990] Returning information for job: 20190310053159628097
2019-03-10 05:32:06,452 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1754.09274697s left)
2019-03-10 05:32:29,688 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053229674289
2019-03-10 05:32:29,707 [salt.minion      :1432][INFO    ][11088] Starting a new job with PID 11088
2019-03-10 05:32:29,734 [salt.minion      :1711][INFO    ][11088] Returning information for job: 20190310053229674289
2019-03-10 05:32:39,321 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1721.22388792s left)
2019-03-10 05:32:59,770 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053259763595
2019-03-10 05:32:59,789 [salt.minion      :1432][INFO    ][11267] Starting a new job with PID 11267
2019-03-10 05:32:59,816 [salt.minion      :1711][INFO    ][11267] Returning information for job: 20190310053259763595
2019-03-10 05:33:12,041 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1688.50383592s left)
2019-03-10 05:33:29,839 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053329824166
2019-03-10 05:33:29,863 [salt.minion      :1432][INFO    ][11344] Starting a new job with PID 11344
2019-03-10 05:33:29,893 [salt.minion      :1711][INFO    ][11344] Returning information for job: 20190310053329824166
2019-03-10 05:33:44,767 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1655.7781589s left)
2019-03-10 05:34:00,004 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053359991537
2019-03-10 05:34:00,024 [salt.minion      :1432][INFO    ][11529] Starting a new job with PID 11529
2019-03-10 05:34:00,049 [salt.minion      :1711][INFO    ][11529] Returning information for job: 20190310053359991537
2019-03-10 05:34:17,663 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1622.88168597s left)
2019-03-10 05:34:30,153 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053430139095
2019-03-10 05:34:30,176 [salt.minion      :1432][INFO    ][11572] Starting a new job with PID 11572
2019-03-10 05:34:30,204 [salt.minion      :1711][INFO    ][11572] Returning information for job: 20190310053430139095
2019-03-10 05:34:50,354 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1590.19075513s left)
2019-03-10 05:35:00,331 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053500319736
2019-03-10 05:35:00,350 [salt.minion      :1432][INFO    ][11794] Starting a new job with PID 11794
2019-03-10 05:35:00,379 [salt.minion      :1711][INFO    ][11794] Returning information for job: 20190310053500319736
2019-03-10 05:35:22,990 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1557.55497813s left)
2019-03-10 05:35:30,397 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053530381850
2019-03-10 05:35:30,421 [salt.minion      :1432][INFO    ][11819] Starting a new job with PID 11819
2019-03-10 05:35:30,453 [salt.minion      :1711][INFO    ][11819] Returning information for job: 20190310053530381850
2019-03-10 05:35:55,871 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1524.67405701s left)
2019-03-10 05:36:00,416 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053600405903
2019-03-10 05:36:00,433 [salt.minion      :1432][INFO    ][11970] Starting a new job with PID 11970
2019-03-10 05:36:00,463 [salt.minion      :1711][INFO    ][11970] Returning information for job: 20190310053600405903
2019-03-10 05:36:28,607 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1491.93762708s left)
2019-03-10 05:36:30,575 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053630567269
2019-03-10 05:36:30,588 [salt.minion      :1432][INFO    ][12076] Starting a new job with PID 12076
2019-03-10 05:36:30,615 [salt.minion      :1711][INFO    ][12076] Returning information for job: 20190310053630567269
2019-03-10 05:37:00,773 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053700761679
2019-03-10 05:37:00,788 [salt.minion      :1432][INFO    ][12296] Starting a new job with PID 12296
2019-03-10 05:37:00,813 [salt.minion      :1711][INFO    ][12296] Returning information for job: 20190310053700761679
2019-03-10 05:37:01,535 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1459.010221s left)
2019-03-10 05:37:30,990 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053730977950
2019-03-10 05:37:31,014 [salt.minion      :1432][INFO    ][12331] Starting a new job with PID 12331
2019-03-10 05:37:31,040 [salt.minion      :1711][INFO    ][12331] Returning information for job: 20190310053730977950
2019-03-10 05:37:34,198 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1426.34727693s left)
2019-03-10 05:38:01,198 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053801185085
2019-03-10 05:38:01,221 [salt.minion      :1432][INFO    ][12408] Starting a new job with PID 12408
2019-03-10 05:38:01,253 [salt.minion      :1711][INFO    ][12408] Returning information for job: 20190310053801185085
2019-03-10 05:38:06,938 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1393.60723495s left)
2019-03-10 05:38:31,233 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053831222481
2019-03-10 05:38:31,257 [salt.minion      :1432][INFO    ][12441] Starting a new job with PID 12441
2019-03-10 05:38:31,293 [salt.minion      :1711][INFO    ][12441] Returning information for job: 20190310053831222481
2019-03-10 05:38:39,660 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1360.88662291s left)
2019-03-10 05:39:01,268 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053901257453
2019-03-10 05:39:01,286 [salt.minion      :1432][INFO    ][12517] Starting a new job with PID 12517
2019-03-10 05:39:01,318 [salt.minion      :1711][INFO    ][12517] Returning information for job: 20190310053901257453
2019-03-10 05:39:12,348 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1328.19748497s left)
2019-03-10 05:39:31,319 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310053931306788
2019-03-10 05:39:31,340 [salt.minion      :1432][INFO    ][12550] Starting a new job with PID 12550
2019-03-10 05:39:31,375 [salt.minion      :1711][INFO    ][12550] Returning information for job: 20190310053931306788
2019-03-10 05:39:45,189 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1295.35590696s left)
2019-03-10 05:40:01,532 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054001521252
2019-03-10 05:40:01,553 [salt.minion      :1432][INFO    ][12619] Starting a new job with PID 12619
2019-03-10 05:40:01,582 [salt.minion      :1711][INFO    ][12619] Returning information for job: 20190310054001521252
2019-03-10 05:40:17,998 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1262.54715109s left)
2019-03-10 05:40:31,596 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054031584287
2019-03-10 05:40:31,615 [salt.minion      :1432][INFO    ][12654] Starting a new job with PID 12654
2019-03-10 05:40:31,644 [salt.minion      :1711][INFO    ][12654] Returning information for job: 20190310054031584287
2019-03-10 05:40:50,657 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1229.88811493s left)
2019-03-10 05:41:01,652 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054101641303
2019-03-10 05:41:01,674 [salt.minion      :1432][INFO    ][12731] Starting a new job with PID 12731
2019-03-10 05:41:01,703 [salt.minion      :1711][INFO    ][12731] Returning information for job: 20190310054101641303
2019-03-10 05:41:23,299 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1197.24567699s left)
2019-03-10 05:41:31,757 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054131744297
2019-03-10 05:41:31,774 [salt.minion      :1432][INFO    ][12764] Starting a new job with PID 12764
2019-03-10 05:41:31,806 [salt.minion      :1711][INFO    ][12764] Returning information for job: 20190310054131744297
2019-03-10 05:41:56,009 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1164.53649807s left)
2019-03-10 05:42:01,837 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054201825474
2019-03-10 05:42:01,856 [salt.minion      :1432][INFO    ][12836] Starting a new job with PID 12836
2019-03-10 05:42:01,888 [salt.minion      :1711][INFO    ][12836] Returning information for job: 20190310054201825474
2019-03-10 05:42:28,699 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1131.84577394s left)
2019-03-10 05:42:31,968 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054231958083
2019-03-10 05:42:31,987 [salt.minion      :1432][INFO    ][12869] Starting a new job with PID 12869
2019-03-10 05:42:32,013 [salt.minion      :1711][INFO    ][12869] Returning information for job: 20190310054231958083
2019-03-10 05:43:01,528 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1099.01692295s left)
2019-03-10 05:43:02,069 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054302061569
2019-03-10 05:43:02,084 [salt.minion      :1432][INFO    ][12937] Starting a new job with PID 12937
2019-03-10 05:43:02,112 [salt.minion      :1711][INFO    ][12937] Returning information for job: 20190310054302061569
2019-03-10 05:43:32,190 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054332179801
2019-03-10 05:43:32,205 [salt.minion      :1432][INFO    ][12967] Starting a new job with PID 12967
2019-03-10 05:43:32,233 [salt.minion      :1711][INFO    ][12967] Returning information for job: 20190310054332179801
2019-03-10 05:43:34,190 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1066.35493612s left)
2019-03-10 05:44:02,360 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054402347913
2019-03-10 05:44:02,382 [salt.minion      :1432][INFO    ][13033] Starting a new job with PID 13033
2019-03-10 05:44:02,410 [salt.minion      :1711][INFO    ][13033] Returning information for job: 20190310054402347913
2019-03-10 05:44:06,787 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1033.75824094s left)
2019-03-10 05:44:32,506 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054432495696
2019-03-10 05:44:32,528 [salt.minion      :1432][INFO    ][13073] Starting a new job with PID 13073
2019-03-10 05:44:32,556 [salt.minion      :1711][INFO    ][13073] Returning information for job: 20190310054432495696
2019-03-10 05:44:39,571 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1000.97393608s left)
2019-03-10 05:45:02,724 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054502716386
2019-03-10 05:45:02,744 [salt.minion      :1432][INFO    ][13161] Starting a new job with PID 13161
2019-03-10 05:45:02,773 [salt.minion      :1711][INFO    ][13161] Returning information for job: 20190310054502716386
2019-03-10 05:45:12,284 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (968.261274099s left)
2019-03-10 05:45:32,777 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054532764178
2019-03-10 05:45:32,796 [salt.minion      :1432][INFO    ][13196] Starting a new job with PID 13196
2019-03-10 05:45:32,827 [salt.minion      :1711][INFO    ][13196] Returning information for job: 20190310054532764178
2019-03-10 05:45:45,072 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (935.47329402s left)
2019-03-10 05:46:02,807 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054602793434
2019-03-10 05:46:02,826 [salt.minion      :1432][INFO    ][13266] Starting a new job with PID 13266
2019-03-10 05:46:02,855 [salt.minion      :1711][INFO    ][13266] Returning information for job: 20190310054602793434
2019-03-10 05:46:17,849 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (902.695979118s left)
2019-03-10 05:46:33,035 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054633021220
2019-03-10 05:46:33,055 [salt.minion      :1432][INFO    ][13301] Starting a new job with PID 13301
2019-03-10 05:46:33,085 [salt.minion      :1711][INFO    ][13301] Returning information for job: 20190310054633021220
2019-03-10 05:46:50,530 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (870.015326023s left)
2019-03-10 05:47:03,074 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054703067007
2019-03-10 05:47:03,090 [salt.minion      :1432][INFO    ][13369] Starting a new job with PID 13369
2019-03-10 05:47:03,116 [salt.minion      :1711][INFO    ][13369] Returning information for job: 20190310054703067007
2019-03-10 05:47:23,213 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (837.331665993s left)
2019-03-10 05:47:33,093 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054733079045
2019-03-10 05:47:33,113 [salt.minion      :1432][INFO    ][13402] Starting a new job with PID 13402
2019-03-10 05:47:33,141 [salt.minion      :1711][INFO    ][13402] Returning information for job: 20190310054733079045
2019-03-10 05:47:56,024 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (804.521383047s left)
2019-03-10 05:48:03,151 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054803141910
2019-03-10 05:48:03,170 [salt.minion      :1432][INFO    ][13473] Starting a new job with PID 13473
2019-03-10 05:48:03,202 [salt.minion      :1711][INFO    ][13473] Returning information for job: 20190310054803141910
2019-03-10 05:48:28,703 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (771.841578007s left)
2019-03-10 05:48:33,200 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054833192938
2019-03-10 05:48:33,215 [salt.minion      :1432][INFO    ][13506] Starting a new job with PID 13506
2019-03-10 05:48:33,242 [salt.minion      :1711][INFO    ][13506] Returning information for job: 20190310054833192938
2019-03-10 05:49:01,589 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (738.955720901s left)
2019-03-10 05:49:03,317 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054903303312
2019-03-10 05:49:03,337 [salt.minion      :1432][INFO    ][13574] Starting a new job with PID 13574
2019-03-10 05:49:03,365 [salt.minion      :1711][INFO    ][13574] Returning information for job: 20190310054903303312
2019-03-10 05:49:33,440 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310054933429895
2019-03-10 05:49:33,462 [salt.minion      :1432][INFO    ][13608] Starting a new job with PID 13608
2019-03-10 05:49:33,491 [salt.minion      :1711][INFO    ][13608] Returning information for job: 20190310054933429895
2019-03-10 05:49:34,198 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (706.346688032s left)
2019-03-10 05:50:03,550 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055003539224
2019-03-10 05:50:03,575 [salt.minion      :1432][INFO    ][13678] Starting a new job with PID 13678
2019-03-10 05:50:03,604 [salt.minion      :1711][INFO    ][13678] Returning information for job: 20190310055003539224
2019-03-10 05:50:06,815 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (673.729862928s left)
2019-03-10 05:50:33,728 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055033713617
2019-03-10 05:50:33,752 [salt.minion      :1432][INFO    ][13711] Starting a new job with PID 13711
2019-03-10 05:50:33,782 [salt.minion      :1711][INFO    ][13711] Returning information for job: 20190310055033713617
2019-03-10 05:50:39,516 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (641.029469967s left)
2019-03-10 05:51:03,886 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055103873980
2019-03-10 05:51:03,910 [salt.minion      :1432][INFO    ][13782] Starting a new job with PID 13782
2019-03-10 05:51:03,943 [salt.minion      :1711][INFO    ][13782] Returning information for job: 20190310055103873980
2019-03-10 05:51:12,218 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (608.326700926s left)
2019-03-10 05:51:33,921 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055133905324
2019-03-10 05:51:33,941 [salt.minion      :1432][INFO    ][13815] Starting a new job with PID 13815
2019-03-10 05:51:33,973 [salt.minion      :1711][INFO    ][13815] Returning information for job: 20190310055133905324
2019-03-10 05:51:44,880 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (575.665484905s left)
2019-03-10 05:52:04,110 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055204096318
2019-03-10 05:52:04,132 [salt.minion      :1432][INFO    ][13884] Starting a new job with PID 13884
2019-03-10 05:52:04,162 [salt.minion      :1711][INFO    ][13884] Returning information for job: 20190310055204096318
2019-03-10 05:52:17,825 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (542.72034812s left)
2019-03-10 05:52:34,163 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055234156024
2019-03-10 05:52:34,183 [salt.minion      :1432][INFO    ][13916] Starting a new job with PID 13916
2019-03-10 05:52:34,213 [salt.minion      :1711][INFO    ][13916] Returning information for job: 20190310055234156024
2019-03-10 05:52:50,385 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (510.159830093s left)
2019-03-10 05:53:04,350 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055304337182
2019-03-10 05:53:04,370 [salt.minion      :1432][INFO    ][13983] Starting a new job with PID 13983
2019-03-10 05:53:04,402 [salt.minion      :1711][INFO    ][13983] Returning information for job: 20190310055304337182
2019-03-10 05:53:23,132 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (477.413090944s left)
2019-03-10 05:53:34,441 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055334429062
2019-03-10 05:53:34,462 [salt.minion      :1432][INFO    ][14016] Starting a new job with PID 14016
2019-03-10 05:53:34,494 [salt.minion      :1711][INFO    ][14016] Returning information for job: 20190310055334429062
2019-03-10 05:53:55,865 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (444.679610014s left)
2019-03-10 05:54:04,663 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055404650317
2019-03-10 05:54:04,681 [salt.minion      :1432][INFO    ][14090] Starting a new job with PID 14090
2019-03-10 05:54:04,713 [salt.minion      :1711][INFO    ][14090] Returning information for job: 20190310055404650317
2019-03-10 05:54:28,474 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (412.071276903s left)
2019-03-10 05:54:34,788 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055434774197
2019-03-10 05:54:34,808 [salt.minion      :1432][INFO    ][14258] Starting a new job with PID 14258
2019-03-10 05:54:34,838 [salt.minion      :1711][INFO    ][14258] Returning information for job: 20190310055434774197
2019-03-10 05:55:01,214 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (379.331025124s left)
2019-03-10 05:55:04,868 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055504858440
2019-03-10 05:55:04,881 [salt.minion      :1432][INFO    ][14342] Starting a new job with PID 14342
2019-03-10 05:55:04,912 [salt.minion      :1711][INFO    ][14342] Returning information for job: 20190310055504858440
2019-03-10 05:55:33,924 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (346.620728016s left)
2019-03-10 05:55:34,997 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055534984102
2019-03-10 05:55:35,013 [salt.minion      :1432][INFO    ][14377] Starting a new job with PID 14377
2019-03-10 05:55:35,048 [salt.minion      :1711][INFO    ][14377] Returning information for job: 20190310055534984102
2019-03-10 05:56:05,173 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055605165405
2019-03-10 05:56:05,190 [salt.minion      :1432][INFO    ][14444] Starting a new job with PID 14444
2019-03-10 05:56:05,217 [salt.minion      :1711][INFO    ][14444] Returning information for job: 20190310055605165405
2019-03-10 05:56:06,592 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (313.953310966s left)
2019-03-10 05:56:35,316 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055635304137
2019-03-10 05:56:35,336 [salt.minion      :1432][INFO    ][14474] Starting a new job with PID 14474
2019-03-10 05:56:35,366 [salt.minion      :1711][INFO    ][14474] Returning information for job: 20190310055635304137
2019-03-10 05:56:39,244 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (281.300563097s left)
2019-03-10 05:57:05,346 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055705333400
2019-03-10 05:57:05,366 [salt.minion      :1432][INFO    ][14545] Starting a new job with PID 14545
2019-03-10 05:57:05,399 [salt.minion      :1711][INFO    ][14545] Returning information for job: 20190310055705333400
2019-03-10 05:57:11,981 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (248.564050913s left)
2019-03-10 05:57:35,514 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055735501400
2019-03-10 05:57:35,532 [salt.minion      :1432][INFO    ][14578] Starting a new job with PID 14578
2019-03-10 05:57:35,565 [salt.minion      :1711][INFO    ][14578] Returning information for job: 20190310055735501400
2019-03-10 05:57:44,818 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (215.726562023s left)
2019-03-10 05:58:05,532 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055805518776
2019-03-10 05:58:05,552 [salt.minion      :1432][INFO    ][14651] Starting a new job with PID 14651
2019-03-10 05:58:05,584 [salt.minion      :1711][INFO    ][14651] Returning information for job: 20190310055805518776
2019-03-10 05:58:17,602 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (182.943323135s left)
2019-03-10 05:58:35,737 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055835722535
2019-03-10 05:58:35,757 [salt.minion      :1432][INFO    ][14684] Starting a new job with PID 14684
2019-03-10 05:58:35,788 [salt.minion      :1711][INFO    ][14684] Returning information for job: 20190310055835722535
2019-03-10 05:58:50,245 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (150.300367117s left)
2019-03-10 05:59:05,833 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055905823872
2019-03-10 05:59:05,853 [salt.minion      :1432][INFO    ][14754] Starting a new job with PID 14754
2019-03-10 05:59:05,882 [salt.minion      :1711][INFO    ][14754] Returning information for job: 20190310055905823872
2019-03-10 05:59:22,901 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (117.64399004s left)
2019-03-10 05:59:35,881 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310055935870535
2019-03-10 05:59:35,903 [salt.minion      :1432][INFO    ][14787] Starting a new job with PID 14787
2019-03-10 05:59:35,935 [salt.minion      :1711][INFO    ][14787] Returning information for job: 20190310055935870535
2019-03-10 05:59:55,777 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (84.767827034s left)
2019-03-10 06:00:05,983 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060005967932
2019-03-10 06:00:06,004 [salt.minion      :1432][INFO    ][14861] Starting a new job with PID 14861
2019-03-10 06:00:06,034 [salt.minion      :1711][INFO    ][14861] Returning information for job: 20190310060005967932
2019-03-10 06:00:28,487 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (52.0577600002s left)
2019-03-10 06:00:36,016 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060036001591
2019-03-10 06:00:36,035 [salt.minion      :1432][INFO    ][14894] Starting a new job with PID 14894
2019-03-10 06:00:36,062 [salt.minion      :1711][INFO    ][14894] Returning information for job: 20190310060036001591
2019-03-10 06:01:01,417 [salt.loaded.ext.module.maas:1023][INFO    ][8784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (19.1280300617s left)
2019-03-10 06:01:06,141 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060106127473
2019-03-10 06:01:06,163 [salt.minion      :1432][INFO    ][14962] Starting a new job with PID 14962
2019-03-10 06:01:06,194 [salt.minion      :1711][INFO    ][14962] Returning information for job: 20190310060106127473
2019-03-10 06:01:34,063 [salt.state       :302 ][ERROR   ][8784] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-03-10 06:01:34,063 [salt.state       :1951][INFO    ][8784] Completed state [maas.wait_for_machine_status] at time 06:01:34.063618 duration_in_ms=2263526.862
2019-03-10 06:01:34,067 [salt.minion      :1711][INFO    ][8784] Returning information for job: 20190310052342902543
2019-03-10 06:01:45,281 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command pillar.get with jid 20190310060145270048
2019-03-10 06:01:45,296 [salt.minion      :1432][INFO    ][15019] Starting a new job with PID 15019
2019-03-10 06:01:45,302 [salt.minion      :1711][INFO    ][15019] Returning information for job: 20190310060145270048
2019-03-10 06:01:46,030 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command service.status with jid 20190310060146019677
2019-03-10 06:01:46,046 [salt.minion      :1432][INFO    ][15040] Starting a new job with PID 15040
2019-03-10 06:01:47,131 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15040] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-10 06:01:47,171 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15040] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-10 06:01:47,185 [salt.minion      :1711][INFO    ][15040] Returning information for job: 20190310060146019677
2019-03-10 06:01:47,863 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command state.apply with jid 20190310060147855039
2019-03-10 06:01:47,881 [salt.minion      :1432][INFO    ][15056] Starting a new job with PID 15056
2019-03-10 06:01:53,641 [salt.state       :915 ][INFO    ][15056] Loading fresh modules for state activity
2019-03-10 06:01:54,212 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command 'salt-minion --version' in directory '/root'
2019-03-10 06:01:54,646 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command 'salt-minion --version' in directory '/root'
2019-03-10 06:01:55,701 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command 'salt-minion --version' in directory '/root'
2019-03-10 06:01:56,052 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command 'salt-minion --version' in directory '/root'
2019-03-10 06:01:58,345 [salt.state       :1780][INFO    ][15056] Running state [salt-minion] at time 06:01:58.345556
2019-03-10 06:01:58,346 [salt.state       :1813][INFO    ][15056] Executing state pkg.installed for [salt-minion]
2019-03-10 06:01:58,346 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-10 06:01:58,457 [salt.state       :300 ][INFO    ][15056] All specified packages are already installed
2019-03-10 06:01:58,458 [salt.state       :1951][INFO    ][15056] Completed state [salt-minion] at time 06:01:58.458005 duration_in_ms=112.45
2019-03-10 06:01:58,458 [salt.state       :1780][INFO    ][15056] Running state [salt_minion_dependency_packages] at time 06:01:58.458384
2019-03-10 06:01:58,458 [salt.state       :1813][INFO    ][15056] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-10 06:01:58,469 [salt.state       :300 ][INFO    ][15056] All specified packages are already installed
2019-03-10 06:01:58,469 [salt.state       :1951][INFO    ][15056] Completed state [salt_minion_dependency_packages] at time 06:01:58.469509 duration_in_ms=11.124
2019-03-10 06:01:58,472 [salt.state       :1780][INFO    ][15056] Running state [/etc/salt/minion.d/minion.conf] at time 06:01:58.472765
2019-03-10 06:01:58,473 [salt.state       :1813][INFO    ][15056] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-10 06:01:58,739 [salt.state       :300 ][INFO    ][15056] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-10 06:01:58,739 [salt.state       :1951][INFO    ][15056] Completed state [/etc/salt/minion.d/minion.conf] at time 06:01:58.739406 duration_in_ms=266.641
2019-03-10 06:01:58,739 [salt.state       :1780][INFO    ][15056] Running state [python-netaddr] at time 06:01:58.739701
2019-03-10 06:01:58,740 [salt.state       :1813][INFO    ][15056] Executing state pkg.installed for [python-netaddr]
2019-03-10 06:01:58,748 [salt.state       :300 ][INFO    ][15056] All specified packages are already installed
2019-03-10 06:01:58,749 [salt.state       :1951][INFO    ][15056] Completed state [python-netaddr] at time 06:01:58.749134 duration_in_ms=9.433
2019-03-10 06:01:58,753 [salt.state       :1780][INFO    ][15056] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:01:58.753561
2019-03-10 06:01:58,753 [salt.state       :1813][INFO    ][15056] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-10 06:01:58,765 [salt.state       :300 ][INFO    ][15056] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-10 06:01:58,765 [salt.state       :1951][INFO    ][15056] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:01:58.765913 duration_in_ms=12.352
2019-03-10 06:01:58,768 [salt.state       :1780][INFO    ][15056] Running state [salt-minion] at time 06:01:58.768670
2019-03-10 06:01:58,769 [salt.state       :1813][INFO    ][15056] Executing state service.running for [salt-minion]
2019-03-10 06:01:58,770 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-10 06:01:58,809 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-10 06:01:58,829 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-10 06:01:58,848 [salt.state       :300 ][INFO    ][15056] The service salt-minion is already running
2019-03-10 06:01:58,849 [salt.state       :1951][INFO    ][15056] Completed state [salt-minion] at time 06:01:58.849022 duration_in_ms=80.353
2019-03-10 06:01:58,853 [salt.state       :1780][INFO    ][15056] Running state [/etc/salt/grains.d] at time 06:01:58.853524
2019-03-10 06:01:58,854 [salt.state       :1813][INFO    ][15056] Executing state file.directory for [/etc/salt/grains.d]
2019-03-10 06:01:58,855 [salt.state       :300 ][INFO    ][15056] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-10 06:01:58,856 [salt.state       :1951][INFO    ][15056] Completed state [/etc/salt/grains.d] at time 06:01:58.856213 duration_in_ms=2.689
2019-03-10 06:01:58,857 [salt.state       :1780][INFO    ][15056] Running state [/etc/salt/grains] at time 06:01:58.857186
2019-03-10 06:01:58,857 [salt.state       :1813][INFO    ][15056] Executing state file.managed for [/etc/salt/grains]
2019-03-10 06:01:58,858 [salt.state       :300 ][INFO    ][15056] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-10 06:01:58,858 [salt.state       :1951][INFO    ][15056] Completed state [/etc/salt/grains] at time 06:01:58.858699 duration_in_ms=1.513
2019-03-10 06:01:58,859 [salt.state       :1780][INFO    ][15056] Running state [/etc/salt/grains.d/placeholder] at time 06:01:58.859414
2019-03-10 06:01:58,859 [salt.state       :1813][INFO    ][15056] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-10 06:01:58,860 [salt.state       :300 ][INFO    ][15056] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-10 06:01:58,860 [salt.state       :1951][INFO    ][15056] Completed state [/etc/salt/grains.d/placeholder] at time 06:01:58.860908 duration_in_ms=1.494
2019-03-10 06:01:58,861 [salt.state       :1780][INFO    ][15056] Running state [/etc/salt/grains.d/sphinx] at time 06:01:58.861606
2019-03-10 06:01:58,862 [salt.state       :1813][INFO    ][15056] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-10 06:01:58,865 [salt.state       :300 ][INFO    ][15056] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-10 06:01:58,866 [salt.state       :1951][INFO    ][15056] Completed state [/etc/salt/grains.d/sphinx] at time 06:01:58.866177 duration_in_ms=4.571
2019-03-10 06:01:58,869 [salt.state       :1780][INFO    ][15056] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:01:58.869439
2019-03-10 06:01:58,869 [salt.state       :1813][INFO    ][15056] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-10 06:01:58,870 [salt.state       :300 ][INFO    ][15056] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-10 06:01:58,870 [salt.state       :1951][INFO    ][15056] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:01:58.870719 duration_in_ms=1.281
2019-03-10 06:01:58,871 [salt.state       :1780][INFO    ][15056] Running state [/etc/salt/grains.d/dns_records] at time 06:01:58.871719
2019-03-10 06:01:58,872 [salt.state       :1813][INFO    ][15056] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-10 06:01:58,873 [salt.state       :300 ][INFO    ][15056] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-10 06:01:58,874 [salt.state       :1951][INFO    ][15056] Completed state [/etc/salt/grains.d/dns_records] at time 06:01:58.874118 duration_in_ms=2.399
2019-03-10 06:01:58,876 [salt.state       :1780][INFO    ][15056] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:01:58.876495
2019-03-10 06:01:58,876 [salt.state       :1813][INFO    ][15056] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-10 06:01:58,877 [salt.state       :300 ][INFO    ][15056] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-10 06:01:58,877 [salt.state       :1951][INFO    ][15056] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:01:58.877353 duration_in_ms=0.859
2019-03-10 06:01:58,878 [salt.state       :1780][INFO    ][15056] Running state [/etc/salt/grains.d/salt] at time 06:01:58.878126
2019-03-10 06:01:58,878 [salt.state       :1813][INFO    ][15056] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-10 06:01:58,880 [salt.state       :300 ][INFO    ][15056] File /etc/salt/grains.d/salt is in the correct state
2019-03-10 06:01:58,880 [salt.state       :1951][INFO    ][15056] Completed state [/etc/salt/grains.d/salt] at time 06:01:58.880316 duration_in_ms=2.191
2019-03-10 06:01:58,881 [salt.state       :1780][INFO    ][15056] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:01:58.881243
2019-03-10 06:01:58,881 [salt.state       :1813][INFO    ][15056] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-10 06:01:58,881 [salt.state       :300 ][INFO    ][15056] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-10 06:01:58,882 [salt.state       :1951][INFO    ][15056] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:01:58.882096 duration_in_ms=0.853
2019-03-10 06:01:58,884 [salt.state       :1780][INFO    ][15056] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:01:58.884390
2019-03-10 06:01:58,884 [salt.state       :1813][INFO    ][15056] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-10 06:01:58,885 [salt.state       :300 ][INFO    ][15056] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-10 06:01:58,885 [salt.state       :1951][INFO    ][15056] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:01:58.885255 duration_in_ms=0.866
2019-03-10 06:01:58,886 [salt.state       :1780][INFO    ][15056] Running state [mine.update] at time 06:01:58.885956
2019-03-10 06:01:58,886 [salt.state       :1813][INFO    ][15056] Executing state module.wait for [mine.update]
2019-03-10 06:01:58,886 [salt.state       :300 ][INFO    ][15056] No changes made for mine.update
2019-03-10 06:01:58,886 [salt.state       :1951][INFO    ][15056] Completed state [mine.update] at time 06:01:58.886745 duration_in_ms=0.789
2019-03-10 06:01:58,888 [salt.state       :1780][INFO    ][15056] Running state [ca-certificates] at time 06:01:58.888147
2019-03-10 06:01:58,888 [salt.state       :1813][INFO    ][15056] Executing state pkg.installed for [ca-certificates]
2019-03-10 06:01:58,898 [salt.state       :300 ][INFO    ][15056] All specified packages are already installed
2019-03-10 06:01:58,898 [salt.state       :1951][INFO    ][15056] Completed state [ca-certificates] at time 06:01:58.898330 duration_in_ms=10.183
2019-03-10 06:01:58,899 [salt.state       :1780][INFO    ][15056] Running state [update-ca-certificates] at time 06:01:58.899360
2019-03-10 06:01:58,899 [salt.state       :1813][INFO    ][15056] Executing state cmd.wait for [update-ca-certificates]
2019-03-10 06:01:58,899 [salt.state       :300 ][INFO    ][15056] No changes made for update-ca-certificates
2019-03-10 06:01:58,900 [salt.state       :1951][INFO    ][15056] Completed state [update-ca-certificates] at time 06:01:58.900180 duration_in_ms=0.82
2019-03-10 06:01:58,900 [salt.state       :1780][INFO    ][15056] Running state [iptables] at time 06:01:58.900450
2019-03-10 06:01:58,900 [salt.state       :1813][INFO    ][15056] Executing state pkg.installed for [iptables]
2019-03-10 06:01:58,910 [salt.state       :300 ][INFO    ][15056] All specified packages are already installed
2019-03-10 06:01:58,910 [salt.state       :1951][INFO    ][15056] Completed state [iptables] at time 06:01:58.910513 duration_in_ms=10.063
2019-03-10 06:01:58,911 [salt.state       :1780][INFO    ][15056] Running state [iptables-persistent] at time 06:01:58.910792
2019-03-10 06:01:58,911 [salt.state       :1813][INFO    ][15056] Executing state pkg.installed for [iptables-persistent]
2019-03-10 06:01:58,920 [salt.state       :300 ][INFO    ][15056] All specified packages are already installed
2019-03-10 06:01:58,920 [salt.state       :1951][INFO    ][15056] Completed state [iptables-persistent] at time 06:01:58.920666 duration_in_ms=9.874
2019-03-10 06:01:58,922 [salt.state       :1780][INFO    ][15056] Running state [iptables_modules_v4_load] at time 06:01:58.922664
2019-03-10 06:01:58,923 [salt.state       :1813][INFO    ][15056] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-10 06:01:58,924 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command 'lsmod' in directory '/root'
2019-03-10 06:01:58,949 [salt.state       :300 ][INFO    ][15056] Kernel modules iptable_filter, ip_tables are already present
2019-03-10 06:01:58,949 [salt.state       :1951][INFO    ][15056] Completed state [iptables_modules_v4_load] at time 06:01:58.949619 duration_in_ms=26.956
2019-03-10 06:01:58,951 [salt.state       :1780][INFO    ][15056] Running state [/etc/iptables/rules.v4] at time 06:01:58.951464
2019-03-10 06:01:58,951 [salt.state       :1813][INFO    ][15056] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-10 06:01:59,054 [salt.state       :300 ][INFO    ][15056] File /etc/iptables/rules.v4 is in the correct state
2019-03-10 06:01:59,054 [salt.state       :1951][INFO    ][15056] Completed state [/etc/iptables/rules.v4] at time 06:01:59.054684 duration_in_ms=103.22
2019-03-10 06:01:59,055 [salt.state       :1780][INFO    ][15056] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:01:59.055854
2019-03-10 06:01:59,056 [salt.state       :1813][INFO    ][15056] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-10 06:01:59,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-10 06:01:59,077 [salt.state       :300 ][INFO    ][15056] onlyif execution failed
2019-03-10 06:01:59,077 [salt.state       :1951][INFO    ][15056] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:01:59.077809 duration_in_ms=21.954
2019-03-10 06:01:59,082 [salt.state       :1780][INFO    ][15056] Running state [netfilter-persistent] at time 06:01:59.082432
2019-03-10 06:01:59,083 [salt.state       :1813][INFO    ][15056] Executing state service.running for [netfilter-persistent]
2019-03-10 06:01:59,085 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-10 06:01:59,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-10 06:01:59,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-10 06:01:59,144 [salt.state       :300 ][INFO    ][15056] The service netfilter-persistent is already running
2019-03-10 06:01:59,144 [salt.state       :1951][INFO    ][15056] Completed state [netfilter-persistent] at time 06:01:59.144630 duration_in_ms=62.199
2019-03-10 06:01:59,146 [salt.state       :1780][INFO    ][15056] Running state [iptables_extra.remove_stale_tables] at time 06:01:59.146023
2019-03-10 06:01:59,146 [salt.state       :1813][INFO    ][15056] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-10 06:01:59,147 [salt.state       :300 ][INFO    ][15056] No changes made for iptables_extra.remove_stale_tables
2019-03-10 06:01:59,147 [salt.state       :1951][INFO    ][15056] Completed state [iptables_extra.remove_stale_tables] at time 06:01:59.147245 duration_in_ms=1.223
2019-03-10 06:01:59,147 [salt.state       :1780][INFO    ][15056] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:01:59.147599
2019-03-10 06:01:59,147 [salt.state       :1813][INFO    ][15056] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-10 06:01:59,148 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-10 06:01:59,168 [salt.state       :300 ][INFO    ][15056] onlyif execution failed
2019-03-10 06:01:59,168 [salt.state       :1951][INFO    ][15056] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:01:59.168719 duration_in_ms=21.119
2019-03-10 06:01:59,170 [salt.state       :1780][INFO    ][15056] Running state [/etc/iptables/rules.v6] at time 06:01:59.170356
2019-03-10 06:01:59,170 [salt.state       :1813][INFO    ][15056] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-10 06:01:59,171 [salt.state       :300 ][INFO    ][15056] File /etc/iptables/rules.v6 is not present
2019-03-10 06:01:59,172 [salt.state       :1951][INFO    ][15056] Completed state [/etc/iptables/rules.v6] at time 06:01:59.172084 duration_in_ms=1.729
2019-03-10 06:01:59,173 [salt.state       :1780][INFO    ][15056] Running state [iptables_extra.flush_all] at time 06:01:59.173330
2019-03-10 06:01:59,173 [salt.state       :1813][INFO    ][15056] Executing state module.wait for [iptables_extra.flush_all]
2019-03-10 06:01:59,174 [salt.state       :300 ][INFO    ][15056] No changes made for iptables_extra.flush_all
2019-03-10 06:01:59,174 [salt.state       :1951][INFO    ][15056] Completed state [iptables_extra.flush_all] at time 06:01:59.174691 duration_in_ms=1.361
2019-03-10 06:01:59,180 [salt.minion      :1711][INFO    ][15056] Returning information for job: 20190310060147855039
2019-03-10 06:01:59,864 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command state.apply with jid 20190310060159853389
2019-03-10 06:01:59,886 [salt.minion      :1432][INFO    ][15146] Starting a new job with PID 15146
2019-03-10 06:02:00,572 [salt.state       :915 ][INFO    ][15146] Loading fresh modules for state activity
2019-03-10 06:02:01,821 [salt.state       :1780][INFO    ][15146] Running state [maas-rack-controller] at time 06:02:01.820989
2019-03-10 06:02:01,821 [salt.state       :1813][INFO    ][15146] Executing state pkg.installed for [maas-rack-controller]
2019-03-10 06:02:01,822 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15146] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-10 06:02:01,930 [salt.state       :300 ][INFO    ][15146] All specified packages are already installed
2019-03-10 06:02:01,931 [salt.state       :1951][INFO    ][15146] Completed state [maas-rack-controller] at time 06:02:01.931220 duration_in_ms=110.232
2019-03-10 06:02:01,931 [salt.state       :1780][INFO    ][15146] Running state [ipmitool] at time 06:02:01.931589
2019-03-10 06:02:01,931 [salt.state       :1813][INFO    ][15146] Executing state pkg.installed for [ipmitool]
2019-03-10 06:02:01,941 [salt.state       :300 ][INFO    ][15146] All specified packages are already installed
2019-03-10 06:02:01,941 [salt.state       :1951][INFO    ][15146] Completed state [ipmitool] at time 06:02:01.941567 duration_in_ms=9.977
2019-03-10 06:02:01,944 [salt.state       :1780][INFO    ][15146] Running state [/etc/maas/rackd.conf] at time 06:02:01.944628
2019-03-10 06:02:01,944 [salt.state       :1813][INFO    ][15146] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-10 06:02:01,946 [salt.state       :300 ][INFO    ][15146] No changes needed to be made
2019-03-10 06:02:01,946 [salt.state       :1951][INFO    ][15146] Completed state [/etc/maas/rackd.conf] at time 06:02:01.946279 duration_in_ms=1.651
2019-03-10 06:02:01,946 [salt.state       :1780][INFO    ][15146] Running state [/etc/maas/rackd.conf] at time 06:02:01.946558
2019-03-10 06:02:01,947 [salt.state       :1813][INFO    ][15146] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-10 06:02:01,947 [salt.loaded.int.states.file:2298][WARNING ][15146] 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-03-10 06:02:01,948 [salt.state       :300 ][INFO    ][15146] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-10 06:02:01,948 [salt.state       :1951][INFO    ][15146] Completed state [/etc/maas/rackd.conf] at time 06:02:01.948615 duration_in_ms=2.056
2019-03-10 06:02:01,949 [salt.state       :1780][INFO    ][15146] Running state [maas-rackd] at time 06:02:01.949665
2019-03-10 06:02:01,949 [salt.state       :1813][INFO    ][15146] Executing state service.running for [maas-rackd]
2019-03-10 06:02:01,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15146] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-10 06:02:01,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15146] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-10 06:02:02,001 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15146] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-10 06:02:02,022 [salt.state       :300 ][INFO    ][15146] The service maas-rackd is already running
2019-03-10 06:02:02,023 [salt.state       :1951][INFO    ][15146] Completed state [maas-rackd] at time 06:02:02.023441 duration_in_ms=73.774
2019-03-10 06:02:02,026 [salt.minion      :1711][INFO    ][15146] Returning information for job: 20190310060159853389
2019-03-10 06:02:02,694 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command state.apply with jid 20190310060202682819
2019-03-10 06:02:02,718 [salt.minion      :1432][INFO    ][15173] Starting a new job with PID 15173
2019-03-10 06:02:03,512 [salt.state       :915 ][INFO    ][15173] Loading fresh modules for state activity
2019-03-10 06:02:04,872 [salt.state       :1780][INFO    ][15173] Running state [maas-region-controller] at time 06:02:04.872003
2019-03-10 06:02:04,872 [salt.state       :1813][INFO    ][15173] Executing state pkg.installed for [maas-region-controller]
2019-03-10 06:02:04,874 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15173] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-10 06:02:04,989 [salt.state       :300 ][INFO    ][15173] All specified packages are already installed
2019-03-10 06:02:04,989 [salt.state       :1951][INFO    ][15173] Completed state [maas-region-controller] at time 06:02:04.989558 duration_in_ms=117.555
2019-03-10 06:02:04,990 [salt.state       :1780][INFO    ][15173] Running state [python-oauth] at time 06:02:04.990042
2019-03-10 06:02:04,990 [salt.state       :1813][INFO    ][15173] Executing state pkg.installed for [python-oauth]
2019-03-10 06:02:05,000 [salt.state       :300 ][INFO    ][15173] All specified packages are already installed
2019-03-10 06:02:05,000 [salt.state       :1951][INFO    ][15173] Completed state [python-oauth] at time 06:02:05.000698 duration_in_ms=10.654
2019-03-10 06:02:05,004 [salt.state       :1780][INFO    ][15173] Running state [/etc/maas/regiond.conf] at time 06:02:05.004321
2019-03-10 06:02:05,004 [salt.state       :1813][INFO    ][15173] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-10 06:02:05,009 [salt.state       :300 ][INFO    ][15173] No changes needed to be made
2019-03-10 06:02:05,010 [salt.state       :1951][INFO    ][15173] Completed state [/etc/maas/regiond.conf] at time 06:02:05.010165 duration_in_ms=5.843
2019-03-10 06:02:05,011 [salt.state       :1780][INFO    ][15173] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:02:05.010816
2019-03-10 06:02:05,011 [salt.state       :1813][INFO    ][15173] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-10 06:02:05,071 [salt.state       :300 ][INFO    ][15173] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-10 06:02:05,071 [salt.state       :1951][INFO    ][15173] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:02:05.071567 duration_in_ms=60.751
2019-03-10 06:02:05,072 [salt.state       :1780][INFO    ][15173] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:02:05.072138
2019-03-10 06:02:05,072 [salt.state       :1813][INFO    ][15173] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-10 06:02:05,081 [salt.state       :300 ][INFO    ][15173] No changes needed to be made
2019-03-10 06:02:05,081 [salt.state       :1951][INFO    ][15173] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:02:05.081323 duration_in_ms=9.186
2019-03-10 06:02:05,081 [salt.state       :1780][INFO    ][15173] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:02:05.081854
2019-03-10 06:02:05,082 [salt.state       :1813][INFO    ][15173] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-10 06:02:05,086 [salt.state       :300 ][INFO    ][15173] No changes needed to be made
2019-03-10 06:02:05,086 [salt.state       :1951][INFO    ][15173] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:02:05.086538 duration_in_ms=4.683
2019-03-10 06:02:05,087 [salt.state       :1780][INFO    ][15173] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:02:05.087076
2019-03-10 06:02:05,087 [salt.state       :1813][INFO    ][15173] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-10 06:02:05,112 [salt.state       :300 ][INFO    ][15173] No changes needed to be made
2019-03-10 06:02:05,112 [salt.state       :1951][INFO    ][15173] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:02:05.112885 duration_in_ms=25.809
2019-03-10 06:02:05,113 [salt.state       :1780][INFO    ][15173] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:02:05.113408
2019-03-10 06:02:05,113 [salt.state       :1813][INFO    ][15173] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-10 06:02:05,129 [salt.state       :300 ][INFO    ][15173] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-10 06:02:05,129 [salt.state       :1951][INFO    ][15173] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:02:05.129651 duration_in_ms=16.242
2019-03-10 06:02:05,131 [salt.state       :1780][INFO    ][15173] Running state [a2enmod headers] at time 06:02:05.131724
2019-03-10 06:02:05,132 [salt.state       :1813][INFO    ][15173] Executing state cmd.run for [a2enmod headers]
2019-03-10 06:02:05,132 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15173] Executing command 'a2enmod headers' in directory '/root'
2019-03-10 06:02:05,215 [salt.state       :300 ][INFO    ][15173] {'pid': 15192, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-10 06:02:05,216 [salt.state       :1951][INFO    ][15173] Completed state [a2enmod headers] at time 06:02:05.216260 duration_in_ms=84.534
2019-03-10 06:02:05,216 [salt.state       :1780][INFO    ][15173] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:02:05.216885
2019-03-10 06:02:05,217 [salt.state       :1813][INFO    ][15173] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-10 06:02:05,243 [salt.state       :300 ][INFO    ][15173] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-10 06:02:05,243 [salt.state       :1951][INFO    ][15173] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:02:05.243540 duration_in_ms=26.656
2019-03-10 06:02:05,244 [salt.state       :1780][INFO    ][15173] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:02:05.244579
2019-03-10 06:02:05,245 [salt.state       :1813][INFO    ][15173] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-10 06:02:05,300 [salt.state       :300 ][INFO    ][15173] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-10 06:02:05,301 [salt.state       :1951][INFO    ][15173] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:02:05.300944 duration_in_ms=56.365
2019-03-10 06:02:05,301 [salt.state       :1780][INFO    ][15173] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:02:05.301523
2019-03-10 06:02:05,301 [salt.state       :1813][INFO    ][15173] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-10 06:02:05,356 [salt.state       :300 ][INFO    ][15173] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-10 06:02:05,357 [salt.state       :1951][INFO    ][15173] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:02:05.357141 duration_in_ms=55.618
2019-03-10 06:02:05,357 [salt.state       :1780][INFO    ][15173] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:02:05.357708
2019-03-10 06:02:05,358 [salt.state       :1813][INFO    ][15173] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-10 06:02:05,432 [salt.state       :300 ][INFO    ][15173] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-10 06:02:05,432 [salt.state       :1951][INFO    ][15173] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:02:05.432730 duration_in_ms=75.022
2019-03-10 06:02:05,433 [salt.state       :1780][INFO    ][15173] Running state [/root/.pgpass] at time 06:02:05.433026
2019-03-10 06:02:05,433 [salt.state       :1813][INFO    ][15173] Executing state file.managed for [/root/.pgpass]
2019-03-10 06:02:05,483 [salt.state       :300 ][INFO    ][15173] File /root/.pgpass is in the correct state
2019-03-10 06:02:05,483 [salt.state       :1951][INFO    ][15173] Completed state [/root/.pgpass] at time 06:02:05.483729 duration_in_ms=50.702
2019-03-10 06:02:05,489 [salt.state       :1780][INFO    ][15173] Running state [maas-region syncdb --noinput] at time 06:02:05.489753
2019-03-10 06:02:05,490 [salt.state       :1813][INFO    ][15173] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-10 06:02:05,490 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15173] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-10 06:02:08,395 [salt.state       :300 ][INFO    ][15173] {'pid': 15205, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, sessions, auth, sites, contenttypes, metadataserver, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-10 06:02:08,396 [salt.state       :1951][INFO    ][15173] Completed state [maas-region syncdb --noinput] at time 06:02:08.396445 duration_in_ms=2906.69
2019-03-10 06:02:08,397 [salt.state       :2022][WARNING ][15173] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-10 06:02:08,400 [salt.state       :1780][INFO    ][15173] Running state [maas-regiond] at time 06:02:08.400339
2019-03-10 06:02:08,401 [salt.state       :1813][INFO    ][15173] Executing state service.running for [maas-regiond]
2019-03-10 06:02:08,404 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15173] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-10 06:02:08,442 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15173] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-10 06:02:08,459 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15173] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-10 06:02:08,479 [salt.state       :300 ][INFO    ][15173] The service maas-regiond is already running
2019-03-10 06:02:08,480 [salt.state       :1951][INFO    ][15173] Completed state [maas-regiond] at time 06:02:08.480504 duration_in_ms=80.165
2019-03-10 06:02:08,484 [salt.state       :1780][INFO    ][15173] Running state [bind9] at time 06:02:08.483930
2019-03-10 06:02:08,484 [salt.state       :1813][INFO    ][15173] Executing state service.running for [bind9]
2019-03-10 06:02:08,485 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15173] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-10 06:02:08,505 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15173] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-10 06:02:08,526 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15173] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-10 06:02:08,544 [salt.state       :300 ][INFO    ][15173] The service bind9 is already running
2019-03-10 06:02:08,544 [salt.state       :1951][INFO    ][15173] Completed state [bind9] at time 06:02:08.544872 duration_in_ms=60.943
2019-03-10 06:02:08,548 [salt.state       :1780][INFO    ][15173] Running state [apache2] at time 06:02:08.548861
2019-03-10 06:02:08,549 [salt.state       :1813][INFO    ][15173] Executing state service.running for [apache2]
2019-03-10 06:02:08,550 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15173] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-10 06:02:08,570 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15173] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-10 06:02:08,590 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15173] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-10 06:02:08,618 [salt.state       :300 ][INFO    ][15173] The service apache2 is already running
2019-03-10 06:02:08,622 [salt.state       :1951][INFO    ][15173] Completed state [apache2] at time 06:02:08.618810 duration_in_ms=69.949
2019-03-10 06:02:08,624 [salt.state       :1780][INFO    ][15173] Running state [maasng.wait_for_http_code] at time 06:02:08.624128
2019-03-10 06:02:08,624 [salt.state       :1813][INFO    ][15173] Executing state module.run for [maasng.wait_for_http_code]
2019-03-10 06:02:08,625 [salt.utils.decorators:613 ][WARNING ][15173] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 06:02:08,756 [salt.state       :300 ][INFO    ][15173] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-10 06:02:08,757 [salt.state       :1951][INFO    ][15173] Completed state [maasng.wait_for_http_code] at time 06:02:08.757298 duration_in_ms=133.17
2019-03-10 06:02:08,758 [salt.state       :1780][INFO    ][15173] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:02:08.758491
2019-03-10 06:02:08,759 [salt.state       :1813][INFO    ][15173] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-10 06:02:08,759 [salt.state       :300 ][INFO    ][15173] /var/lib/maas/.setup_admin exists
2019-03-10 06:02:08,760 [salt.state       :1951][INFO    ][15173] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:02:08.759954 duration_in_ms=1.464
2019-03-10 06:02:08,761 [salt.state       :1780][INFO    ][15173] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:02:08.761058
2019-03-10 06:02:08,761 [salt.state       :1813][INFO    ][15173] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-10 06:02:08,762 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15173] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-10 06:02:10,682 [salt.state       :300 ][INFO    ][15173] {'pid': 15226, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-10 06:02:10,684 [salt.state       :1951][INFO    ][15173] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:02:10.684431 duration_in_ms=1923.373
2019-03-10 06:02:10,693 [salt.state       :1780][INFO    ][15173] Running state [maas_region_boot_source_resources_mirror] at time 06:02:10.693494
2019-03-10 06:02:10,694 [salt.state       :1813][INFO    ][15173] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-10 06:02:10,788 [salt.state       :300 ][INFO    ][15173] {'changes': {}}
2019-03-10 06:02:10,789 [salt.state       :1951][INFO    ][15173] Completed state [maas_region_boot_source_resources_mirror] at time 06:02:10.788981 duration_in_ms=95.488
2019-03-10 06:02:10,790 [salt.state       :1780][INFO    ][15173] Running state [maasng.boot_resources_import] at time 06:02:10.789935
2019-03-10 06:02:10,790 [salt.state       :1813][INFO    ][15173] Executing state module.run for [maasng.boot_resources_import]
2019-03-10 06:02:10,791 [salt.utils.decorators:613 ][WARNING ][15173] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 06:02:10,877 [salt.loaded.ext.module.maasng:1600][INFO    ][15173] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-10 06:02:15,928 [salt.loaded.ext.module.maasng:1600][INFO    ][15173] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-10 06:02:17,781 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060217772096
2019-03-10 06:02:17,802 [salt.minion      :1432][INFO    ][15261] Starting a new job with PID 15261
2019-03-10 06:02:17,837 [salt.minion      :1711][INFO    ][15261] Returning information for job: 20190310060217772096
2019-03-10 06:02:20,980 [salt.loaded.ext.module.maasng:1600][INFO    ][15173] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-03-10 06:02:26,083 [salt.state       :300 ][INFO    ][15173] {'ret': True}
2019-03-10 06:02:26,083 [salt.state       :1951][INFO    ][15173] Completed state [maasng.boot_resources_import] at time 06:02:26.083420 duration_in_ms=15293.484
2019-03-10 06:02:26,084 [salt.state       :1780][INFO    ][15173] Running state [maas_region_boot_sources_selection_xenial] at time 06:02:26.084295
2019-03-10 06:02:26,084 [salt.state       :1813][INFO    ][15173] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-10 06:02:26,266 [salt.state       :300 ][INFO    ][15173] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-10 06:02:26,267 [salt.state       :1951][INFO    ][15173] Completed state [maas_region_boot_sources_selection_xenial] at time 06:02:26.267009 duration_in_ms=182.713
2019-03-10 06:02:26,268 [salt.state       :1780][INFO    ][15173] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:02:26.268150
2019-03-10 06:02:26,268 [salt.state       :1813][INFO    ][15173] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-10 06:02:26,269 [salt.utils.decorators:613 ][WARNING ][15173] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 06:02:26,269 [salt.loaded.ext.module.maasng:1771][INFO    ][15173] boot-sources sync initiated for ALL Rack's
2019-03-10 06:02:27,269 [salt.state       :300 ][INFO    ][15173] {'ret': True}
2019-03-10 06:02:27,269 [salt.state       :1951][INFO    ][15173] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:02:27.269482 duration_in_ms=1001.33
2019-03-10 06:02:27,272 [salt.state       :1780][INFO    ][15173] Running state [maas.process_maas_config] at time 06:02:27.272767
2019-03-10 06:02:27,273 [salt.state       :1813][INFO    ][15173] Executing state module.run for [maas.process_maas_config]
2019-03-10 06:02:27,273 [salt.utils.decorators:613 ][WARNING ][15173] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 06:02:27,274 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=enable_http_proxy value=True
2019-03-10 06:02:27,322 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=upstream_dns value=8.8.8.8
2019-03-10 06:02:27,374 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=commissioning_distro_series value=xenial
2019-03-10 06:02:27,437 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=default_osystem value=ubuntu
2019-03-10 06:02:27,496 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=active_discovery_interval value=600
2019-03-10 06:02:27,541 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=dnssec_validation value=no
2019-03-10 06:02:27,584 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=maas_name value=mas01
2019-03-10 06:02:27,630 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=network_discovery value=enabled
2019-03-10 06:02:27,728 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=enable_third_party_drivers value=True
2019-03-10 06:02:27,777 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=default_storage_layout value=lvm
2019-03-10 06:02:29,358 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=ntp_external_only value=True
2019-03-10 06:02:29,411 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-10 06:02:29,458 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=default_distro_series value=xenial
2019-03-10 06:02:29,517 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-03-10 06:02:29,647 [salt.state       :300 ][INFO    ][15173] {'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-03-10 06:02:29,648 [salt.state       :1951][INFO    ][15173] Completed state [maas.process_maas_config] at time 06:02:29.648095 duration_in_ms=2375.326
2019-03-10 06:02:29,649 [salt.state       :1780][INFO    ][15173] Running state [pxe_admin] at time 06:02:29.649193
2019-03-10 06:02:29,649 [salt.state       :1813][INFO    ][15173] Executing state maasng.fabric_present for [pxe_admin]
2019-03-10 06:02:29,700 [salt.loaded.ext.module.maasng:945 ][INFO    ][15173] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, 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'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'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, 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'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'name': u'untagged', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-10 06:02:29,772 [salt.loaded.ext.module.maasng:1008][WARNING ][15173] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-10 06:02:29,772 [salt.loaded.ext.module.maasng:1011][WARNING ][15173] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-10 06:02:29,849 [salt.state       :300 ][INFO    ][15173] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-10 06:02:29,849 [salt.state       :1951][INFO    ][15173] Completed state [pxe_admin] at time 06:02:29.849361 duration_in_ms=200.168
2019-03-10 06:02:29,849 [salt.state       :1780][INFO    ][15173] Running state [vlan 0] at time 06:02:29.849789
2019-03-10 06:02:29,850 [salt.state       :1813][INFO    ][15173] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-10 06:02:29,901 [salt.loaded.ext.module.maasng:945 ][INFO    ][15173] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, 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'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'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, 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'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'name': u'untagged', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-10 06:02:29,989 [salt.loaded.ext.module.maasng:945 ][INFO    ][15173] [{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'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 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'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 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'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-10 06:02:30,245 [salt.loaded.ext.module.maasng:945 ][INFO    ][15173] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, 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'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'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, 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'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'name': u'untagged', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-10 06:02:30,321 [salt.state       :300 ][INFO    ][15173] {'new': 'Vlan untagged was updated'}
2019-03-10 06:02:30,321 [salt.state       :1951][INFO    ][15173] Completed state [vlan 0] at time 06:02:30.321571 duration_in_ms=471.782
2019-03-10 06:02:30,322 [salt.state       :1780][INFO    ][15173] Running state [192.168.11.0/24] at time 06:02:30.322712
2019-03-10 06:02:30,323 [salt.state       :1813][INFO    ][15173] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-10 06:02:30,512 [salt.loaded.ext.module.maasng:945 ][INFO    ][15173] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-10 06:02:30,512 [salt.loaded.ext.module.maasng:1235][WARNING ][15173] Ignoring parameter vlan:0
2019-03-10 06:02:30,576 [salt.state       :300 ][INFO    ][15173] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-10 06:02:30,577 [salt.state       :1951][INFO    ][15173] Completed state [192.168.11.0/24] at time 06:02:30.577218 duration_in_ms=254.506
2019-03-10 06:02:30,578 [salt.state       :1780][INFO    ][15173] Running state [maas_create_iprange_1] at time 06:02:30.578236
2019-03-10 06:02:30,578 [salt.state       :1813][INFO    ][15173] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-10 06:02:30,626 [salt.state       :300 ][INFO    ][15173] Iprange maas_create_iprange_1 already exist.
2019-03-10 06:02:30,626 [salt.state       :1951][INFO    ][15173] Completed state [maas_create_iprange_1] at time 06:02:30.626488 duration_in_ms=48.252
2019-03-10 06:02:30,628 [salt.state       :1780][INFO    ][15173] Running state [vlan 0] at time 06:02:30.626808
2019-03-10 06:02:30,628 [salt.state       :1813][INFO    ][15173] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-10 06:02:30,670 [salt.loaded.ext.module.maasng:945 ][INFO    ][15173] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, 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'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'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, 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'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'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-10 06:02:30,748 [salt.loaded.ext.module.maasng:945 ][INFO    ][15173] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ddyq84', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-03-10 06:02:30,972 [salt.loaded.ext.module.maasng:945 ][INFO    ][15173] [{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'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 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'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'ddyq84', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-10 06:02:31,050 [salt.state       :300 ][INFO    ][15173] {'new': 'Vlan untagged was updated'}
2019-03-10 06:02:31,050 [salt.state       :1951][INFO    ][15173] Completed state [vlan 0] at time 06:02:31.050705 duration_in_ms=423.897
2019-03-10 06:02:31,052 [salt.state       :1780][INFO    ][15173] Running state [opnfv] at time 06:02:31.052039
2019-03-10 06:02:31,052 [salt.state       :1813][INFO    ][15173] Executing state maasng.sshkey_present for [opnfv]
2019-03-10 06:02:31,093 [salt.loaded.ext.module.maasng:1903][INFO    ][15173] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'keysource': u''}]
2019-03-10 06:02:31,094 [salt.state       :300 ][INFO    ][15173] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-03-10 06:02:31,098 [salt.state       :1951][INFO    ][15173] Completed state [opnfv] at time 06:02:31.097627 duration_in_ms=45.587
2019-03-10 06:02:31,099 [salt.state       :1780][INFO    ][15173] Running state [maas.process_tags] at time 06:02:31.099014
2019-03-10 06:02:31,099 [salt.state       :1813][INFO    ][15173] Executing state module.run for [maas.process_tags]
2019-03-10 06:02:31,100 [salt.utils.decorators:613 ][WARNING ][15173] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 06:02:31,139 [salt.loaded.ext.module.maas:92  ][INFO    ][15173] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-03-10 06:02:31,196 [salt.state       :300 ][INFO    ][15173] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-03-10 06:02:31,197 [salt.state       :1951][INFO    ][15173] Completed state [maas.process_tags] at time 06:02:31.196915 duration_in_ms=97.899
2019-03-10 06:02:31,202 [salt.minion      :1711][INFO    ][15173] Returning information for job: 20190310060202682819
2019-03-10 06:02:31,976 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command state.apply with jid 20190310060231966626
2019-03-10 06:02:31,990 [salt.minion      :1432][INFO    ][15636] Starting a new job with PID 15636
2019-03-10 06:02:37,797 [salt.state       :915 ][INFO    ][15636] Loading fresh modules for state activity
2019-03-10 06:02:37,900 [salt.state       :1780][INFO    ][15636] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:02:37.900460
2019-03-10 06:02:37,900 [salt.state       :1813][INFO    ][15636] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-10 06:02:37,902 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15636] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-10 06:02:39,942 [salt.state       :300 ][INFO    ][15636] {'pid': 15660, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-10 06:02:39,943 [salt.state       :1951][INFO    ][15636] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:02:39.943199 duration_in_ms=2042.739
2019-03-10 06:02:39,945 [salt.state       :1780][INFO    ][15636] Running state [maas.process_machines] at time 06:02:39.945126
2019-03-10 06:02:39,945 [salt.state       :1813][INFO    ][15636] Executing state module.run for [maas.process_machines]
2019-03-10 06:02:39,946 [salt.utils.decorators:613 ][WARNING ][15636] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 06:02:40,422 [salt.loaded.ext.module.maas:412 ][WARNING ][15636] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-10 06:02:40,422 [salt.loaded.ext.module.maas:92  ][INFO    ][15636] 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=sepn47 architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-10 06:02:41,358 [salt.loaded.ext.module.maas:412 ][WARNING ][15636] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-10 06:02:41,359 [salt.loaded.ext.module.maas:92  ][INFO    ][15636] 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=mecgnf architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-10 06:02:42,310 [salt.loaded.ext.module.maas:412 ][WARNING ][15636] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-10 06:02:42,310 [salt.loaded.ext.module.maas:92  ][INFO    ][15636] 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=c8ta8m architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-10 06:02:43,267 [salt.loaded.ext.module.maas:412 ][WARNING ][15636] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-10 06:02:43,268 [salt.loaded.ext.module.maas:92  ][INFO    ][15636] 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=qgyexn architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-10 06:02:44,194 [salt.loaded.ext.module.maas:412 ][WARNING ][15636] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-10 06:02:44,194 [salt.loaded.ext.module.maas:92  ][INFO    ][15636] 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=k7nnbx architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-10 06:02:45,158 [salt.state       :300 ][INFO    ][15636] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-10 06:02:45,158 [salt.state       :1951][INFO    ][15636] Completed state [maas.process_machines] at time 06:02:45.158571 duration_in_ms=5213.444
2019-03-10 06:02:45,164 [salt.minion      :1711][INFO    ][15636] Returning information for job: 20190310060231966626
2019-03-10 06:03:19,024 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command state.apply with jid 20190310060319013371
2019-03-10 06:03:19,043 [salt.minion      :1432][INFO    ][15942] Starting a new job with PID 15942
2019-03-10 06:03:24,582 [salt.state       :915 ][INFO    ][15942] Loading fresh modules for state activity
2019-03-10 06:03:24,674 [salt.state       :1780][INFO    ][15942] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:03:24.674618
2019-03-10 06:03:24,675 [salt.state       :1813][INFO    ][15942] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-10 06:03:24,677 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15942] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-10 06:03:26,602 [salt.state       :300 ][INFO    ][15942] {'pid': 15949, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-10 06:03:26,603 [salt.state       :1951][INFO    ][15942] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:03:26.603454 duration_in_ms=1928.836
2019-03-10 06:03:26,608 [salt.state       :1780][INFO    ][15942] Running state [maas.wait_for_machine_status] at time 06:03:26.608177
2019-03-10 06:03:26,608 [salt.state       :1813][INFO    ][15942] Executing state module.run for [maas.wait_for_machine_status]
2019-03-10 06:03:26,608 [salt.utils.decorators:613 ][WARNING ][15942] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 06:03:29,371 [salt.state       :300 ][INFO    ][15942] {'ret': True}
2019-03-10 06:03:29,371 [salt.state       :1951][INFO    ][15942] Completed state [maas.wait_for_machine_status] at time 06:03:29.371875 duration_in_ms=2763.697
2019-03-10 06:03:29,375 [salt.minion      :1711][INFO    ][15942] Returning information for job: 20190310060319013371
2019-03-10 06:03:30,049 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command state.apply with jid 20190310060330040049
2019-03-10 06:03:30,069 [salt.minion      :1432][INFO    ][15962] Starting a new job with PID 15962
2019-03-10 06:03:30,800 [salt.state       :915 ][INFO    ][15962] Loading fresh modules for state activity
2019-03-10 06:03:30,946 [salt.state       :1780][INFO    ][15962] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:03:30.946350
2019-03-10 06:03:30,946 [salt.state       :1813][INFO    ][15962] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-10 06:03:30,949 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15962] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-10 06:03:32,900 [salt.state       :300 ][INFO    ][15962] {'pid': 15969, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-10 06:03:32,902 [salt.state       :1951][INFO    ][15962] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:03:32.901792 duration_in_ms=1955.441
2019-03-10 06:03:32,906 [salt.state       :1780][INFO    ][15962] Running state [maas_machines_storage_cmp002_lvm] at time 06:03:32.906062
2019-03-10 06:03:32,906 [salt.state       :1813][INFO    ][15962] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-10 06:03:33,453 [salt.state       :300 ][INFO    ][15962] Machine cmp002 is not in Ready state.
2019-03-10 06:03:33,454 [salt.state       :1951][INFO    ][15962] Completed state [maas_machines_storage_cmp002_lvm] at time 06:03:33.453925 duration_in_ms=547.86
2019-03-10 06:03:33,454 [salt.state       :1780][INFO    ][15962] Running state [maas_machines_storage_cmp001_lvm] at time 06:03:33.454632
2019-03-10 06:03:33,455 [salt.state       :1813][INFO    ][15962] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-10 06:03:33,964 [salt.state       :300 ][INFO    ][15962] Machine cmp001 is not in Ready state.
2019-03-10 06:03:33,964 [salt.state       :1951][INFO    ][15962] Completed state [maas_machines_storage_cmp001_lvm] at time 06:03:33.964723 duration_in_ms=510.091
2019-03-10 06:03:33,967 [salt.minion      :1711][INFO    ][15962] Returning information for job: 20190310060330040049
2019-03-10 06:03:34,642 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command state.apply with jid 20190310060334629751
2019-03-10 06:03:34,662 [salt.minion      :1432][INFO    ][15992] Starting a new job with PID 15992
2019-03-10 06:03:35,368 [salt.state       :915 ][INFO    ][15992] Loading fresh modules for state activity
2019-03-10 06:03:35,457 [salt.state       :1780][INFO    ][15992] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:03:35.457697
2019-03-10 06:03:35,458 [salt.state       :1813][INFO    ][15992] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-10 06:03:35,460 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15992] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-10 06:03:37,408 [salt.state       :300 ][INFO    ][15992] {'pid': 15999, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-10 06:03:37,409 [salt.state       :1951][INFO    ][15992] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:03:37.409009 duration_in_ms=1951.311
2019-03-10 06:03:37,412 [salt.state       :1780][INFO    ][15992] Running state [maas.deploy_machines] at time 06:03:37.412140
2019-03-10 06:03:37,412 [salt.state       :1813][INFO    ][15992] Executing state module.run for [maas.deploy_machines]
2019-03-10 06:03:37,414 [salt.utils.decorators:613 ][WARNING ][15992] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 06:03:37,969 [salt.loaded.ext.module.maas:684 ][INFO    ][15992] deploymachines hwe_kernel=hwe-16.04 system_id=k7nnbx distro_series=xenial
2019-03-10 06:03:40,188 [salt.state       :300 ][INFO    ][15992] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-03-10 06:03:40,188 [salt.state       :1951][INFO    ][15992] Completed state [maas.deploy_machines] at time 06:03:40.188501 duration_in_ms=2776.361
2019-03-10 06:03:40,195 [salt.minion      :1711][INFO    ][15992] Returning information for job: 20190310060334629751
2019-03-10 06:03:40,873 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command state.apply with jid 20190310060340865005
2019-03-10 06:03:40,893 [salt.minion      :1432][INFO    ][16059] Starting a new job with PID 16059
2019-03-10 06:03:46,632 [salt.state       :915 ][INFO    ][16059] Loading fresh modules for state activity
2019-03-10 06:03:46,718 [salt.state       :1780][INFO    ][16059] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:03:46.718281
2019-03-10 06:03:46,718 [salt.state       :1813][INFO    ][16059] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-10 06:03:46,721 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16059] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-10 06:03:48,600 [salt.state       :300 ][INFO    ][16059] {'pid': 16106, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-10 06:03:48,601 [salt.state       :1951][INFO    ][16059] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:03:48.601024 duration_in_ms=1882.744
2019-03-10 06:03:48,603 [salt.state       :1780][INFO    ][16059] Running state [maas.wait_for_machine_status] at time 06:03:48.603894
2019-03-10 06:03:48,604 [salt.state       :1813][INFO    ][16059] Executing state module.run for [maas.wait_for_machine_status]
2019-03-10 06:03:48,604 [salt.utils.decorators:613 ][WARNING ][16059] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-10 06:03:50,947 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.6666379s left)
2019-03-10 06:03:55,933 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060355891587
2019-03-10 06:03:55,953 [salt.minion      :1432][INFO    ][16135] Starting a new job with PID 16135
2019-03-10 06:03:55,988 [salt.minion      :1711][INFO    ][16135] Returning information for job: 20190310060355891587
2019-03-10 06:04:23,682 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.93259788s left)
2019-03-10 06:04:25,992 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060425977173
2019-03-10 06:04:26,013 [salt.minion      :1432][INFO    ][16162] Starting a new job with PID 16162
2019-03-10 06:04:26,045 [salt.minion      :1711][INFO    ][16162] Returning information for job: 20190310060425977173
2019-03-10 06:04:56,076 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060456065566
2019-03-10 06:04:56,093 [salt.minion      :1432][INFO    ][16257] Starting a new job with PID 16257
2019-03-10 06:04:56,123 [salt.minion      :1711][INFO    ][16257] Returning information for job: 20190310060456065566
2019-03-10 06:04:56,296 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.31784892s left)
2019-03-10 06:05:26,133 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060526121442
2019-03-10 06:05:26,156 [salt.minion      :1432][INFO    ][16279] Starting a new job with PID 16279
2019-03-10 06:05:26,188 [salt.minion      :1711][INFO    ][16279] Returning information for job: 20190310060526121442
2019-03-10 06:05:29,078 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.53639889s left)
2019-03-10 06:05:56,212 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060556198594
2019-03-10 06:05:56,231 [salt.minion      :1432][INFO    ][16358] Starting a new job with PID 16358
2019-03-10 06:05:56,268 [salt.minion      :1711][INFO    ][16358] Returning information for job: 20190310060556198594
2019-03-10 06:06:01,532 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2117.082479s left)
2019-03-10 06:06:26,285 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060626269545
2019-03-10 06:06:26,303 [salt.minion      :1432][INFO    ][16387] Starting a new job with PID 16387
2019-03-10 06:06:26,337 [salt.minion      :1711][INFO    ][16387] Returning information for job: 20190310060626269545
2019-03-10 06:06:34,178 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2084.43661404s left)
2019-03-10 06:06:56,360 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060656347128
2019-03-10 06:06:56,384 [salt.minion      :1432][INFO    ][16466] Starting a new job with PID 16466
2019-03-10 06:06:56,414 [salt.minion      :1711][INFO    ][16466] Returning information for job: 20190310060656347128
2019-03-10 06:07:06,479 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2052.13482785s left)
2019-03-10 06:07:26,432 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060726420007
2019-03-10 06:07:26,456 [salt.minion      :1432][INFO    ][16491] Starting a new job with PID 16491
2019-03-10 06:07:26,488 [salt.minion      :1711][INFO    ][16491] Returning information for job: 20190310060726420007
2019-03-10 06:07:39,259 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2019.35500693s left)
2019-03-10 06:07:56,508 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060756501185
2019-03-10 06:07:56,522 [salt.minion      :1432][INFO    ][16590] Starting a new job with PID 16590
2019-03-10 06:07:56,556 [salt.minion      :1711][INFO    ][16590] Returning information for job: 20190310060756501185
2019-03-10 06:08:11,641 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1986.97360396s left)
2019-03-10 06:08:26,574 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060826558244
2019-03-10 06:08:26,598 [salt.minion      :1432][INFO    ][16618] Starting a new job with PID 16618
2019-03-10 06:08:26,628 [salt.minion      :1711][INFO    ][16618] Returning information for job: 20190310060826558244
2019-03-10 06:08:44,504 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1954.1102829s left)
2019-03-10 06:08:56,687 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060856679289
2019-03-10 06:08:56,711 [salt.minion      :1432][INFO    ][16787] Starting a new job with PID 16787
2019-03-10 06:08:56,744 [salt.minion      :1711][INFO    ][16787] Returning information for job: 20190310060856679289
2019-03-10 06:09:16,924 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1921.68987799s left)
2019-03-10 06:09:26,796 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060926782010
2019-03-10 06:09:26,817 [salt.minion      :1432][INFO    ][16837] Starting a new job with PID 16837
2019-03-10 06:09:26,850 [salt.minion      :1711][INFO    ][16837] Returning information for job: 20190310060926782010
2019-03-10 06:09:49,794 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1888.81994295s left)
2019-03-10 06:09:56,913 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310060956897321
2019-03-10 06:09:56,935 [salt.minion      :1432][INFO    ][16992] Starting a new job with PID 16992
2019-03-10 06:09:56,964 [salt.minion      :1711][INFO    ][16992] Returning information for job: 20190310060956897321
2019-03-10 06:10:22,393 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1856.22084689s left)
2019-03-10 06:10:27,008 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310061027001023
2019-03-10 06:10:27,020 [salt.minion      :1432][INFO    ][17023] Starting a new job with PID 17023
2019-03-10 06:10:27,047 [salt.minion      :1711][INFO    ][17023] Returning information for job: 20190310061027001023
2019-03-10 06:10:55,201 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1823.41366601s left)
2019-03-10 06:10:57,132 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310061057119034
2019-03-10 06:10:57,154 [salt.minion      :1432][INFO    ][17140] Starting a new job with PID 17140
2019-03-10 06:10:57,185 [salt.minion      :1711][INFO    ][17140] Returning information for job: 20190310061057119034
2019-03-10 06:11:27,248 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310061127239279
2019-03-10 06:11:27,265 [salt.minion      :1432][INFO    ][17200] Starting a new job with PID 17200
2019-03-10 06:11:27,296 [salt.minion      :1711][INFO    ][17200] Returning information for job: 20190310061127239279
2019-03-10 06:11:27,718 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1790.89623499s left)
2019-03-10 06:11:57,373 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310061157362065
2019-03-10 06:11:57,394 [salt.minion      :1432][INFO    ][17324] Starting a new job with PID 17324
2019-03-10 06:11:57,423 [salt.minion      :1711][INFO    ][17324] Returning information for job: 20190310061157362065
2019-03-10 06:12:00,606 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1758.00847006s left)
2019-03-10 06:12:27,502 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310061227491250
2019-03-10 06:12:27,523 [salt.minion      :1432][INFO    ][17348] Starting a new job with PID 17348
2019-03-10 06:12:27,552 [salt.minion      :1711][INFO    ][17348] Returning information for job: 20190310061227491250
2019-03-10 06:12:33,042 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1725.57208991s left)
2019-03-10 06:12:57,633 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310061257620044
2019-03-10 06:12:57,656 [salt.minion      :1432][INFO    ][17425] Starting a new job with PID 17425
2019-03-10 06:12:57,687 [salt.minion      :1711][INFO    ][17425] Returning information for job: 20190310061257620044
2019-03-10 06:13:05,968 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1692.64590192s left)
2019-03-10 06:13:27,789 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310061327775292
2019-03-10 06:13:27,809 [salt.minion      :1432][INFO    ][17451] Starting a new job with PID 17451
2019-03-10 06:13:27,839 [salt.minion      :1711][INFO    ][17451] Returning information for job: 20190310061327775292
2019-03-10 06:13:38,372 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1660.241822s left)
2019-03-10 06:13:57,938 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310061357924379
2019-03-10 06:13:57,957 [salt.minion      :1432][INFO    ][17529] Starting a new job with PID 17529
2019-03-10 06:13:57,988 [salt.minion      :1711][INFO    ][17529] Returning information for job: 20190310061357924379
2019-03-10 06:14:11,128 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1627.48588586s left)
2019-03-10 06:14:28,109 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310061428098826
2019-03-10 06:14:28,130 [salt.minion      :1432][INFO    ][17555] Starting a new job with PID 17555
2019-03-10 06:14:28,162 [salt.minion      :1711][INFO    ][17555] Returning information for job: 20190310061428098826
2019-03-10 06:14:43,852 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1594.76227689s left)
2019-03-10 06:14:58,265 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310061458251297
2019-03-10 06:14:58,284 [salt.minion      :1432][INFO    ][17801] Starting a new job with PID 17801
2019-03-10 06:14:58,314 [salt.minion      :1711][INFO    ][17801] Returning information for job: 20190310061458251297
2019-03-10 06:15:16,714 [salt.loaded.ext.module.maas:1023][INFO    ][16059] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1561.90037084s left)
2019-03-10 06:15:28,447 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190310061528430822
2019-03-10 06:15:28,469 [salt.minion      :1432][INFO    ][17849] Starting a new job with PID 17849
2019-03-10 06:15:28,499 [salt.minion      :1711][INFO    ][17849] Returning information for job: 20190310061528430822
2019-03-10 06:15:49,149 [salt.state       :300 ][INFO    ][16059] {'ret': True}
2019-03-10 06:15:49,149 [salt.state       :1951][INFO    ][16059] Completed state [maas.wait_for_machine_status] at time 06:15:49.149607 duration_in_ms=720545.711
2019-03-10 06:15:49,151 [salt.minion      :1711][INFO    ][16059] Returning information for job: 20190310060340865005
2019-03-10 06:15:52,853 [salt.utils.schedule:1377][INFO    ][6305] Running scheduled job: __mine_interval
2019-03-10 07:15:52,853 [salt.utils.schedule:1377][INFO    ][6305] Running scheduled job: __mine_interval
2019-03-10 07:44:37,657 [salt.minion      :1308][INFO    ][6305] User sudo_ubuntu Executing command cp.push_dir with jid 20190310074437644171
2019-03-10 07:44:37,777 [salt.minion      :1432][INFO    ][27121] Starting a new job with PID 27121
