2019-05-27 02:02:35,361 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-27 02:03:25,401 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-27 02:04:15,441 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-27 02:05:05,488 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-27 02:05:55,538 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-27 02:08:04,433 [salt.utils.decorators:613 ][WARNING ][2694] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 02:08:05,029 [salt.utils.decorators:613 ][WARNING ][2694] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 02:08:07,165 [salt.loaded.int.states.file:2298][WARNING ][2832] 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-05-27 02:08:12,711 [salt.state       :2022][WARNING ][2944] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-27 02:08:15,257 [salt.utils.decorators:613 ][WARNING ][2944] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 02:23:19,378 [salt.utils.decorators:613 ][WARNING ][2944] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 02:48:17,467 [salt.utils.decorators:613 ][WARNING ][2944] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 03:03:22,523 [salt.utils.decorators:613 ][WARNING ][2944] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 03:03:26,700 [salt.loaded.ext.module.maasng:1008][WARNING ][2944] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-27 03:03:26,700 [salt.loaded.ext.module.maasng:1011][WARNING ][2944] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-27 03:03:27,383 [salt.loaded.ext.module.maasng:1235][WARNING ][2944] Ignoring parameter vlan:0
2019-05-27 03:03:34,122 [salt.utils.decorators:613 ][WARNING ][6035] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 03:03:34,187 [salt.loaded.ext.module.maas:412 ][WARNING ][6035] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:03:38,727 [salt.loaded.ext.module.maas:412 ][WARNING ][6035] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:03:42,949 [salt.loaded.ext.module.maas:412 ][WARNING ][6035] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:03:45,720 [salt.loaded.ext.module.maas:412 ][WARNING ][6035] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:03:49,750 [salt.loaded.ext.module.maas:412 ][WARNING ][6035] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:03:57,207 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6977] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-27 03:03:57,239 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6977] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-27 03:03:57,262 [salt.utils.parsers:1051][WARNING ][361] Minion received a SIGTERM. Exiting.
2019-05-27 03:03:58,323 [salt.cli.daemons :293 ][INFO    ][7022] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-05-27 03:03:58,419 [salt.cli.daemons :82  ][INFO    ][7022] Starting up the Salt Minion
2019-05-27 03:03:58,420 [salt.utils.event :1017][INFO    ][7022] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-05-27 03:03:59,357 [salt.minion      :976 ][INFO    ][7022] Creating minion process manager
2019-05-27 03:04:00,852 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7022] Executing command ['date', '+%z'] in directory '/root'
2019-05-27 03:04:00,871 [salt.utils.schedule:568 ][INFO    ][7022] Updating job settings for scheduled job: __mine_interval
2019-05-27 03:04:00,872 [salt.minion      :1108][INFO    ][7022] Added mine.update to scheduler
2019-05-27 03:04:00,876 [salt.minion      :1975][INFO    ][7022] Minion is starting as user 'root'
2019-05-27 03:04:00,889 [salt.minion      :2336][INFO    ][7022] Minion is ready to receive requests!
2019-05-27 03:04:25,178 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527030425164600
2019-05-27 03:04:25,201 [salt.minion      :1432][INFO    ][7150] Starting a new job with PID 7150
2019-05-27 03:04:28,986 [salt.state       :915 ][INFO    ][7150] Loading fresh modules for state activity
2019-05-27 03:04:29,035 [salt.fileclient  :1219][INFO    ][7150] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-27 03:04:29,081 [salt.state       :1780][INFO    ][7150] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:04:29.081096
2019-05-27 03:04:29,081 [salt.state       :1813][INFO    ][7150] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-27 03:04:29,083 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7150] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-27 03:04:30,544 [salt.state       :300 ][INFO    ][7150] {'pid': 7157, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-27 03:04:30,544 [salt.state       :1951][INFO    ][7150] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:04:30.544740 duration_in_ms=1463.645
2019-05-27 03:04:30,545 [salt.state       :1780][INFO    ][7150] Running state [maas.wait_for_machine_status] at time 03:04:30.545874
2019-05-27 03:04:30,546 [salt.state       :1813][INFO    ][7150] Executing state module.run for [maas.wait_for_machine_status]
2019-05-27 03:04:30,546 [salt.utils.decorators:613 ][WARNING ][7150] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 03:04:31,501 [salt.loaded.ext.module.maas:1023][INFO    ][7150] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.04927206s left)
2019-05-27 03:04:40,266 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527030440254002
2019-05-27 03:04:40,289 [salt.minion      :1432][INFO    ][7175] Starting a new job with PID 7175
2019-05-27 03:04:40,313 [salt.minion      :1711][INFO    ][7175] Returning information for job: 20190527030440254002
2019-05-27 03:05:02,434 [salt.loaded.ext.module.maas:1023][INFO    ][7150] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.116256s left)
2019-05-27 03:05:10,314 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527030510301302
2019-05-27 03:05:10,337 [salt.minion      :1432][INFO    ][7202] Starting a new job with PID 7202
2019-05-27 03:05:10,359 [salt.minion      :1711][INFO    ][7202] Returning information for job: 20190527030510301302
2019-05-27 03:05:33,393 [salt.loaded.ext.module.maas:1023][INFO    ][7150] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.15729809s left)
2019-05-27 03:05:40,355 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527030540343299
2019-05-27 03:05:40,375 [salt.minion      :1432][INFO    ][7251] Starting a new job with PID 7251
2019-05-27 03:05:40,397 [salt.minion      :1711][INFO    ][7251] Returning information for job: 20190527030540343299
2019-05-27 03:06:04,363 [salt.loaded.ext.module.maas:1023][INFO    ][7150] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.18786693s left)
2019-05-27 03:06:10,395 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527030610382750
2019-05-27 03:06:10,419 [salt.minion      :1432][INFO    ][7279] Starting a new job with PID 7279
2019-05-27 03:06:10,441 [salt.minion      :1711][INFO    ][7279] Returning information for job: 20190527030610382750
2019-05-27 03:06:35,507 [salt.loaded.ext.module.maas:1023][INFO    ][7150] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.04317904s left)
2019-05-27 03:06:40,442 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527030640429601
2019-05-27 03:06:40,465 [salt.minion      :1432][INFO    ][7333] Starting a new job with PID 7333
2019-05-27 03:06:40,489 [salt.minion      :1711][INFO    ][7333] Returning information for job: 20190527030640429601
2019-05-27 03:07:06,483 [salt.loaded.ext.module.maas:1023][INFO    ][7150] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.06746197s left)
2019-05-27 03:07:10,567 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527030710555091
2019-05-27 03:07:10,591 [salt.minion      :1432][INFO    ][7379] Starting a new job with PID 7379
2019-05-27 03:07:10,613 [salt.minion      :1711][INFO    ][7379] Returning information for job: 20190527030710555091
2019-05-27 03:07:37,461 [salt.loaded.ext.module.maas:1023][INFO    ][7150] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.08968806s left)
2019-05-27 03:07:40,585 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527030740572695
2019-05-27 03:07:40,608 [salt.minion      :1432][INFO    ][7434] Starting a new job with PID 7434
2019-05-27 03:07:40,629 [salt.minion      :1711][INFO    ][7434] Returning information for job: 20190527030740572695
2019-05-27 03:08:08,437 [salt.loaded.ext.module.maas:1023][INFO    ][7150] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.11301303s left)
2019-05-27 03:08:10,664 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527030810650689
2019-05-27 03:08:10,686 [salt.minion      :1432][INFO    ][7480] Starting a new job with PID 7480
2019-05-27 03:08:10,707 [salt.minion      :1711][INFO    ][7480] Returning information for job: 20190527030810650689
2019-05-27 03:08:40,849 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527030840836044
2019-05-27 03:08:40,873 [salt.minion      :1432][INFO    ][7730] Starting a new job with PID 7730
2019-05-27 03:08:40,895 [salt.minion      :1711][INFO    ][7730] Returning information for job: 20190527030840836044
2019-05-27 03:09:10,925 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527030910913106
2019-05-27 03:09:10,947 [salt.minion      :1432][INFO    ][7763] Starting a new job with PID 7763
2019-05-27 03:09:10,969 [salt.minion      :1711][INFO    ][7763] Returning information for job: 20190527030910913106
2019-05-27 03:09:41,004 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527030940997964
2019-05-27 03:09:41,028 [salt.minion      :1432][INFO    ][7803] Starting a new job with PID 7803
2019-05-27 03:09:41,051 [salt.minion      :1711][INFO    ][7803] Returning information for job: 20190527030940997964
2019-05-27 03:10:11,088 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031011077117
2019-05-27 03:10:11,111 [salt.minion      :1432][INFO    ][7830] Starting a new job with PID 7830
2019-05-27 03:10:11,133 [salt.minion      :1711][INFO    ][7830] Returning information for job: 20190527031011077117
2019-05-27 03:10:41,172 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031041163053
2019-05-27 03:10:41,195 [salt.minion      :1432][INFO    ][7860] Starting a new job with PID 7860
2019-05-27 03:10:41,220 [salt.minion      :1711][INFO    ][7860] Returning information for job: 20190527031041163053
2019-05-27 03:11:11,251 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031111241253
2019-05-27 03:11:11,273 [salt.minion      :1432][INFO    ][7882] Starting a new job with PID 7882
2019-05-27 03:11:11,295 [salt.minion      :1711][INFO    ][7882] Returning information for job: 20190527031111241253
2019-05-27 03:11:41,341 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031141328898
2019-05-27 03:11:41,364 [salt.minion      :1432][INFO    ][7917] Starting a new job with PID 7917
2019-05-27 03:11:41,385 [salt.minion      :1711][INFO    ][7917] Returning information for job: 20190527031141328898
2019-05-27 03:12:11,437 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031211424455
2019-05-27 03:12:11,459 [salt.minion      :1432][INFO    ][7934] Starting a new job with PID 7934
2019-05-27 03:12:11,480 [salt.minion      :1711][INFO    ][7934] Returning information for job: 20190527031211424455
2019-05-27 03:12:41,623 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031241612372
2019-05-27 03:12:41,642 [salt.minion      :1432][INFO    ][7959] Starting a new job with PID 7959
2019-05-27 03:12:41,657 [salt.minion      :1711][INFO    ][7959] Returning information for job: 20190527031241612372
2019-05-27 03:13:11,819 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031311806377
2019-05-27 03:13:11,836 [salt.minion      :1432][INFO    ][7978] Starting a new job with PID 7978
2019-05-27 03:13:11,848 [salt.minion      :1711][INFO    ][7978] Returning information for job: 20190527031311806377
2019-05-27 03:13:42,002 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031341990740
2019-05-27 03:13:42,025 [salt.minion      :1432][INFO    ][8001] Starting a new job with PID 8001
2019-05-27 03:13:42,048 [salt.minion      :1711][INFO    ][8001] Returning information for job: 20190527031341990740
2019-05-27 03:14:11,591 [salt.state       :302 ][ERROR   ][7150] Module function maas.wait_for_machine_status threw an exception. Exception: HTTP Error 401: OK
2019-05-27 03:14:11,591 [salt.state       :1951][INFO    ][7150] Completed state [maas.wait_for_machine_status] at time 03:14:11.591645 duration_in_ms=581045.768
2019-05-27 03:14:11,595 [salt.minion      :1711][INFO    ][7150] Returning information for job: 20190527030425164600
2019-05-27 03:14:22,278 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command pillar.get with jid 20190527031422272115
2019-05-27 03:14:22,295 [salt.minion      :1432][INFO    ][8196] Starting a new job with PID 8196
2019-05-27 03:14:22,302 [salt.minion      :1711][INFO    ][8196] Returning information for job: 20190527031422272115
2019-05-27 03:14:22,798 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command service.status with jid 20190527031422785856
2019-05-27 03:14:22,819 [salt.minion      :1432][INFO    ][8201] Starting a new job with PID 8201
2019-05-27 03:14:23,181 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][8201] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-27 03:14:23,215 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][8201] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-27 03:14:23,234 [salt.minion      :1711][INFO    ][8201] Returning information for job: 20190527031422785856
2019-05-27 03:14:23,799 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527031423786720
2019-05-27 03:14:23,823 [salt.minion      :1432][INFO    ][8213] Starting a new job with PID 8213
2019-05-27 03:14:27,656 [salt.state       :915 ][INFO    ][8213] Loading fresh modules for state activity
2019-05-27 03:14:28,057 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command 'salt-minion --version' in directory '/root'
2019-05-27 03:14:28,380 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command 'salt-minion --version' in directory '/root'
2019-05-27 03:14:29,266 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command 'salt-minion --version' in directory '/root'
2019-05-27 03:14:29,622 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command 'salt-minion --version' in directory '/root'
2019-05-27 03:14:30,954 [salt.state       :1780][INFO    ][8213] Running state [salt-minion] at time 03:14:30.954865
2019-05-27 03:14:30,955 [salt.state       :1813][INFO    ][8213] Executing state pkg.installed for [salt-minion]
2019-05-27 03:14:30,955 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-27 03:14:31,034 [salt.state       :300 ][INFO    ][8213] All specified packages are already installed
2019-05-27 03:14:31,035 [salt.state       :1951][INFO    ][8213] Completed state [salt-minion] at time 03:14:31.035006 duration_in_ms=80.141
2019-05-27 03:14:31,035 [salt.state       :1780][INFO    ][8213] Running state [salt_minion_dependency_packages] at time 03:14:31.035275
2019-05-27 03:14:31,035 [salt.state       :1813][INFO    ][8213] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-27 03:14:31,040 [salt.state       :300 ][INFO    ][8213] All specified packages are already installed
2019-05-27 03:14:31,040 [salt.state       :1951][INFO    ][8213] Completed state [salt_minion_dependency_packages] at time 03:14:31.040751 duration_in_ms=5.476
2019-05-27 03:14:31,043 [salt.state       :1780][INFO    ][8213] Running state [/etc/salt/minion.d/minion.conf] at time 03:14:31.043316
2019-05-27 03:14:31,043 [salt.state       :1813][INFO    ][8213] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-27 03:14:31,234 [salt.state       :300 ][INFO    ][8213] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-27 03:14:31,234 [salt.state       :1951][INFO    ][8213] Completed state [/etc/salt/minion.d/minion.conf] at time 03:14:31.234591 duration_in_ms=191.274
2019-05-27 03:14:31,234 [salt.state       :1780][INFO    ][8213] Running state [python-netaddr] at time 03:14:31.234784
2019-05-27 03:14:31,234 [salt.state       :1813][INFO    ][8213] Executing state pkg.installed for [python-netaddr]
2019-05-27 03:14:31,239 [salt.state       :300 ][INFO    ][8213] All specified packages are already installed
2019-05-27 03:14:31,239 [salt.state       :1951][INFO    ][8213] Completed state [python-netaddr] at time 03:14:31.239791 duration_in_ms=5.007
2019-05-27 03:14:31,242 [salt.state       :1780][INFO    ][8213] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:14:31.241974
2019-05-27 03:14:31,242 [salt.state       :1813][INFO    ][8213] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-27 03:14:31,251 [salt.state       :300 ][INFO    ][8213] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-27 03:14:31,251 [salt.state       :1951][INFO    ][8213] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:14:31.251836 duration_in_ms=9.863
2019-05-27 03:14:31,252 [salt.state       :1780][INFO    ][8213] Running state [salt-minion] at time 03:14:31.252517
2019-05-27 03:14:31,252 [salt.state       :1813][INFO    ][8213] Executing state service.running for [salt-minion]
2019-05-27 03:14:31,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-27 03:14:31,293 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-27 03:14:31,311 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-27 03:14:31,328 [salt.state       :300 ][INFO    ][8213] The service salt-minion is already running
2019-05-27 03:14:31,329 [salt.state       :1951][INFO    ][8213] Completed state [salt-minion] at time 03:14:31.329111 duration_in_ms=76.593
2019-05-27 03:14:31,331 [salt.state       :1780][INFO    ][8213] Running state [/etc/salt/grains.d] at time 03:14:31.331442
2019-05-27 03:14:31,332 [salt.state       :1813][INFO    ][8213] Executing state file.directory for [/etc/salt/grains.d]
2019-05-27 03:14:31,333 [salt.state       :300 ][INFO    ][8213] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-27 03:14:31,333 [salt.state       :1951][INFO    ][8213] Completed state [/etc/salt/grains.d] at time 03:14:31.333711 duration_in_ms=2.268
2019-05-27 03:14:31,334 [salt.state       :1780][INFO    ][8213] Running state [/etc/salt/grains] at time 03:14:31.334742
2019-05-27 03:14:31,335 [salt.state       :1813][INFO    ][8213] Executing state file.managed for [/etc/salt/grains]
2019-05-27 03:14:31,335 [salt.state       :300 ][INFO    ][8213] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-27 03:14:31,336 [salt.state       :1951][INFO    ][8213] Completed state [/etc/salt/grains] at time 03:14:31.336262 duration_in_ms=1.52
2019-05-27 03:14:31,337 [salt.state       :1780][INFO    ][8213] Running state [/etc/salt/grains.d/placeholder] at time 03:14:31.336937
2019-05-27 03:14:31,337 [salt.state       :1813][INFO    ][8213] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-27 03:14:31,338 [salt.state       :300 ][INFO    ][8213] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-27 03:14:31,338 [salt.state       :1951][INFO    ][8213] Completed state [/etc/salt/grains.d/placeholder] at time 03:14:31.338366 duration_in_ms=1.429
2019-05-27 03:14:31,339 [salt.state       :1780][INFO    ][8213] Running state [/etc/salt/grains.d/sphinx] at time 03:14:31.339048
2019-05-27 03:14:31,339 [salt.state       :1813][INFO    ][8213] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-27 03:14:31,360 [salt.state       :300 ][INFO    ][8213] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-27 03:14:31,360 [salt.state       :1951][INFO    ][8213] Completed state [/etc/salt/grains.d/sphinx] at time 03:14:31.360663 duration_in_ms=21.614
2019-05-27 03:14:31,363 [salt.state       :1780][INFO    ][8213] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:14:31.363803
2019-05-27 03:14:31,364 [salt.state       :1813][INFO    ][8213] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-27 03:14:31,364 [salt.state       :300 ][INFO    ][8213] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-27 03:14:31,365 [salt.state       :1951][INFO    ][8213] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:14:31.365023 duration_in_ms=1.221
2019-05-27 03:14:31,365 [salt.state       :1780][INFO    ][8213] Running state [/etc/salt/grains.d/dns_records] at time 03:14:31.365706
2019-05-27 03:14:31,366 [salt.state       :1813][INFO    ][8213] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-27 03:14:31,378 [salt.state       :300 ][INFO    ][8213] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-27 03:14:31,378 [salt.state       :1951][INFO    ][8213] Completed state [/etc/salt/grains.d/dns_records] at time 03:14:31.378593 duration_in_ms=12.886
2019-05-27 03:14:31,380 [salt.state       :1780][INFO    ][8213] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:14:31.380051
2019-05-27 03:14:31,380 [salt.state       :1813][INFO    ][8213] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-27 03:14:31,381 [salt.state       :300 ][INFO    ][8213] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-27 03:14:31,381 [salt.state       :1951][INFO    ][8213] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:14:31.381359 duration_in_ms=1.308
2019-05-27 03:14:31,382 [salt.state       :1780][INFO    ][8213] Running state [/etc/salt/grains.d/salt] at time 03:14:31.382115
2019-05-27 03:14:31,382 [salt.state       :1813][INFO    ][8213] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-27 03:14:31,396 [salt.state       :300 ][INFO    ][8213] File /etc/salt/grains.d/salt is in the correct state
2019-05-27 03:14:31,396 [salt.state       :1951][INFO    ][8213] Completed state [/etc/salt/grains.d/salt] at time 03:14:31.396668 duration_in_ms=14.552
2019-05-27 03:14:31,398 [salt.state       :1780][INFO    ][8213] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:14:31.398177
2019-05-27 03:14:31,398 [salt.state       :1813][INFO    ][8213] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-27 03:14:31,399 [salt.state       :300 ][INFO    ][8213] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-27 03:14:31,399 [salt.state       :1951][INFO    ][8213] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:14:31.399586 duration_in_ms=1.409
2019-05-27 03:14:31,402 [salt.state       :1780][INFO    ][8213] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:14:31.402890
2019-05-27 03:14:31,403 [salt.state       :1813][INFO    ][8213] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-27 03:14:31,404 [salt.state       :300 ][INFO    ][8213] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-27 03:14:31,404 [salt.state       :1951][INFO    ][8213] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:14:31.404295 duration_in_ms=1.405
2019-05-27 03:14:31,405 [salt.state       :1780][INFO    ][8213] Running state [mine.update] at time 03:14:31.405444
2019-05-27 03:14:31,405 [salt.state       :1813][INFO    ][8213] Executing state module.wait for [mine.update]
2019-05-27 03:14:31,406 [salt.state       :300 ][INFO    ][8213] No changes made for mine.update
2019-05-27 03:14:31,406 [salt.state       :1951][INFO    ][8213] Completed state [mine.update] at time 03:14:31.406755 duration_in_ms=1.311
2019-05-27 03:14:31,407 [salt.state       :1780][INFO    ][8213] Running state [ca-certificates] at time 03:14:31.407141
2019-05-27 03:14:31,407 [salt.state       :1813][INFO    ][8213] Executing state pkg.installed for [ca-certificates]
2019-05-27 03:14:31,419 [salt.state       :300 ][INFO    ][8213] All specified packages are already installed
2019-05-27 03:14:31,419 [salt.state       :1951][INFO    ][8213] Completed state [ca-certificates] at time 03:14:31.419807 duration_in_ms=12.666
2019-05-27 03:14:31,420 [salt.state       :1780][INFO    ][8213] Running state [update-ca-certificates] at time 03:14:31.420853
2019-05-27 03:14:31,421 [salt.state       :1813][INFO    ][8213] Executing state cmd.wait for [update-ca-certificates]
2019-05-27 03:14:31,421 [salt.state       :300 ][INFO    ][8213] No changes made for update-ca-certificates
2019-05-27 03:14:31,422 [salt.state       :1951][INFO    ][8213] Completed state [update-ca-certificates] at time 03:14:31.422034 duration_in_ms=1.18
2019-05-27 03:14:31,422 [salt.state       :1780][INFO    ][8213] Running state [iptables] at time 03:14:31.422388
2019-05-27 03:14:31,422 [salt.state       :1813][INFO    ][8213] Executing state pkg.installed for [iptables]
2019-05-27 03:14:31,433 [salt.state       :300 ][INFO    ][8213] All specified packages are already installed
2019-05-27 03:14:31,433 [salt.state       :1951][INFO    ][8213] Completed state [iptables] at time 03:14:31.433399 duration_in_ms=11.011
2019-05-27 03:14:31,433 [salt.state       :1780][INFO    ][8213] Running state [iptables-persistent] at time 03:14:31.433768
2019-05-27 03:14:31,434 [salt.state       :1813][INFO    ][8213] Executing state pkg.installed for [iptables-persistent]
2019-05-27 03:14:31,443 [salt.state       :300 ][INFO    ][8213] All specified packages are already installed
2019-05-27 03:14:31,444 [salt.state       :1951][INFO    ][8213] Completed state [iptables-persistent] at time 03:14:31.444220 duration_in_ms=10.452
2019-05-27 03:14:31,445 [salt.state       :1780][INFO    ][8213] Running state [iptables_modules_v4_load] at time 03:14:31.445581
2019-05-27 03:14:31,445 [salt.state       :1813][INFO    ][8213] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-27 03:14:31,446 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command 'lsmod' in directory '/root'
2019-05-27 03:14:31,472 [salt.state       :300 ][INFO    ][8213] Kernel modules iptable_filter, ip_tables are already present
2019-05-27 03:14:31,473 [salt.state       :1951][INFO    ][8213] Completed state [iptables_modules_v4_load] at time 03:14:31.472989 duration_in_ms=27.407
2019-05-27 03:14:31,473 [salt.state       :1780][INFO    ][8213] Running state [/etc/iptables/rules.v4] at time 03:14:31.473884
2019-05-27 03:14:31,474 [salt.state       :1813][INFO    ][8213] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-27 03:14:31,594 [salt.state       :300 ][INFO    ][8213] File /etc/iptables/rules.v4 is in the correct state
2019-05-27 03:14:31,594 [salt.state       :1951][INFO    ][8213] Completed state [/etc/iptables/rules.v4] at time 03:14:31.594336 duration_in_ms=120.453
2019-05-27 03:14:31,595 [salt.state       :1780][INFO    ][8213] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:14:31.595567
2019-05-27 03:14:31,595 [salt.state       :1813][INFO    ][8213] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-27 03:14:31,596 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-27 03:14:31,618 [salt.state       :300 ][INFO    ][8213] onlyif execution failed
2019-05-27 03:14:31,618 [salt.state       :1951][INFO    ][8213] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:14:31.618434 duration_in_ms=22.866
2019-05-27 03:14:31,619 [salt.state       :1780][INFO    ][8213] Running state [netfilter-persistent] at time 03:14:31.619625
2019-05-27 03:14:31,620 [salt.state       :1813][INFO    ][8213] Executing state service.running for [netfilter-persistent]
2019-05-27 03:14:31,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-27 03:14:31,641 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-27 03:14:31,659 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-27 03:14:31,676 [salt.state       :300 ][INFO    ][8213] The service netfilter-persistent is already running
2019-05-27 03:14:31,676 [salt.state       :1951][INFO    ][8213] Completed state [netfilter-persistent] at time 03:14:31.676857 duration_in_ms=57.231
2019-05-27 03:14:31,677 [salt.state       :1780][INFO    ][8213] Running state [iptables_extra.remove_stale_tables] at time 03:14:31.677782
2019-05-27 03:14:31,678 [salt.state       :1813][INFO    ][8213] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-27 03:14:31,678 [salt.state       :300 ][INFO    ][8213] No changes made for iptables_extra.remove_stale_tables
2019-05-27 03:14:31,678 [salt.state       :1951][INFO    ][8213] Completed state [iptables_extra.remove_stale_tables] at time 03:14:31.678793 duration_in_ms=1.011
2019-05-27 03:14:31,679 [salt.state       :1780][INFO    ][8213] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:14:31.679064
2019-05-27 03:14:31,679 [salt.state       :1813][INFO    ][8213] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-27 03:14:31,680 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8213] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-27 03:14:31,694 [salt.state       :300 ][INFO    ][8213] onlyif execution failed
2019-05-27 03:14:31,695 [salt.state       :1951][INFO    ][8213] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:14:31.694942 duration_in_ms=15.878
2019-05-27 03:14:31,696 [salt.state       :1780][INFO    ][8213] Running state [/etc/iptables/rules.v6] at time 03:14:31.696053
2019-05-27 03:14:31,696 [salt.state       :1813][INFO    ][8213] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-27 03:14:31,697 [salt.state       :300 ][INFO    ][8213] File /etc/iptables/rules.v6 is not present
2019-05-27 03:14:31,697 [salt.state       :1951][INFO    ][8213] Completed state [/etc/iptables/rules.v6] at time 03:14:31.697218 duration_in_ms=1.165
2019-05-27 03:14:31,698 [salt.state       :1780][INFO    ][8213] Running state [iptables_extra.flush_all] at time 03:14:31.697988
2019-05-27 03:14:31,698 [salt.state       :1813][INFO    ][8213] Executing state module.wait for [iptables_extra.flush_all]
2019-05-27 03:14:31,698 [salt.state       :300 ][INFO    ][8213] No changes made for iptables_extra.flush_all
2019-05-27 03:14:31,698 [salt.state       :1951][INFO    ][8213] Completed state [iptables_extra.flush_all] at time 03:14:31.698907 duration_in_ms=0.919
2019-05-27 03:14:31,702 [salt.minion      :1711][INFO    ][8213] Returning information for job: 20190527031423786720
2019-05-27 03:14:32,278 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527031432265440
2019-05-27 03:14:32,299 [salt.minion      :1432][INFO    ][8288] Starting a new job with PID 8288
2019-05-27 03:14:33,064 [salt.state       :915 ][INFO    ][8288] Loading fresh modules for state activity
2019-05-27 03:14:33,682 [salt.state       :1780][INFO    ][8288] Running state [maas-rack-controller] at time 03:14:33.682813
2019-05-27 03:14:33,683 [salt.state       :1813][INFO    ][8288] Executing state pkg.installed for [maas-rack-controller]
2019-05-27 03:14:33,683 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8288] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-27 03:14:33,760 [salt.state       :300 ][INFO    ][8288] All specified packages are already installed
2019-05-27 03:14:33,760 [salt.state       :1951][INFO    ][8288] Completed state [maas-rack-controller] at time 03:14:33.760598 duration_in_ms=77.786
2019-05-27 03:14:33,760 [salt.state       :1780][INFO    ][8288] Running state [ipmitool] at time 03:14:33.760838
2019-05-27 03:14:33,761 [salt.state       :1813][INFO    ][8288] Executing state pkg.installed for [ipmitool]
2019-05-27 03:14:33,765 [salt.state       :300 ][INFO    ][8288] All specified packages are already installed
2019-05-27 03:14:33,765 [salt.state       :1951][INFO    ][8288] Completed state [ipmitool] at time 03:14:33.765745 duration_in_ms=4.907
2019-05-27 03:14:33,767 [salt.state       :1780][INFO    ][8288] Running state [/etc/maas/rackd.conf] at time 03:14:33.767913
2019-05-27 03:14:33,768 [salt.state       :1813][INFO    ][8288] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-27 03:14:33,768 [salt.state       :300 ][INFO    ][8288] No changes needed to be made
2019-05-27 03:14:33,769 [salt.state       :1951][INFO    ][8288] Completed state [/etc/maas/rackd.conf] at time 03:14:33.769044 duration_in_ms=1.131
2019-05-27 03:14:33,769 [salt.state       :1780][INFO    ][8288] Running state [/etc/maas/rackd.conf] at time 03:14:33.769211
2019-05-27 03:14:33,769 [salt.state       :1813][INFO    ][8288] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-27 03:14:33,769 [salt.loaded.int.states.file:2298][WARNING ][8288] 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-05-27 03:14:33,769 [salt.state       :300 ][INFO    ][8288] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-27 03:14:33,770 [salt.state       :1951][INFO    ][8288] Completed state [/etc/maas/rackd.conf] at time 03:14:33.770098 duration_in_ms=0.888
2019-05-27 03:14:33,770 [salt.state       :1780][INFO    ][8288] Running state [maas-rackd] at time 03:14:33.770769
2019-05-27 03:14:33,770 [salt.state       :1813][INFO    ][8288] Executing state service.running for [maas-rackd]
2019-05-27 03:14:33,771 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8288] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-27 03:14:33,802 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8288] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-27 03:14:33,817 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8288] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-27 03:14:33,830 [salt.state       :300 ][INFO    ][8288] The service maas-rackd is already running
2019-05-27 03:14:33,830 [salt.state       :1951][INFO    ][8288] Completed state [maas-rackd] at time 03:14:33.830751 duration_in_ms=59.982
2019-05-27 03:14:33,831 [salt.minion      :1711][INFO    ][8288] Returning information for job: 20190527031432265440
2019-05-27 03:14:34,349 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527031434342410
2019-05-27 03:14:34,370 [salt.minion      :1432][INFO    ][8311] Starting a new job with PID 8311
2019-05-27 03:14:35,078 [salt.state       :915 ][INFO    ][8311] Loading fresh modules for state activity
2019-05-27 03:14:35,737 [salt.state       :1780][INFO    ][8311] Running state [maas-region-controller] at time 03:14:35.737460
2019-05-27 03:14:35,737 [salt.state       :1813][INFO    ][8311] Executing state pkg.installed for [maas-region-controller]
2019-05-27 03:14:35,738 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8311] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-27 03:14:35,835 [salt.state       :300 ][INFO    ][8311] All specified packages are already installed
2019-05-27 03:14:35,835 [salt.state       :1951][INFO    ][8311] Completed state [maas-region-controller] at time 03:14:35.835497 duration_in_ms=98.035
2019-05-27 03:14:35,836 [salt.state       :1780][INFO    ][8311] Running state [python-oauth] at time 03:14:35.836007
2019-05-27 03:14:35,836 [salt.state       :1813][INFO    ][8311] Executing state pkg.installed for [python-oauth]
2019-05-27 03:14:35,843 [salt.state       :300 ][INFO    ][8311] All specified packages are already installed
2019-05-27 03:14:35,843 [salt.state       :1951][INFO    ][8311] Completed state [python-oauth] at time 03:14:35.843898 duration_in_ms=7.891
2019-05-27 03:14:35,847 [salt.state       :1780][INFO    ][8311] Running state [/etc/maas/regiond.conf] at time 03:14:35.847236
2019-05-27 03:14:35,847 [salt.state       :1813][INFO    ][8311] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-27 03:14:35,881 [salt.state       :300 ][INFO    ][8311] No changes needed to be made
2019-05-27 03:14:35,882 [salt.state       :1951][INFO    ][8311] Completed state [/etc/maas/regiond.conf] at time 03:14:35.882128 duration_in_ms=34.891
2019-05-27 03:14:35,882 [salt.state       :1780][INFO    ][8311] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:14:35.882745
2019-05-27 03:14:35,883 [salt.state       :1813][INFO    ][8311] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-27 03:14:35,947 [salt.state       :300 ][INFO    ][8311] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-27 03:14:35,948 [salt.state       :1951][INFO    ][8311] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:14:35.948010 duration_in_ms=65.263
2019-05-27 03:14:35,949 [salt.state       :1780][INFO    ][8311] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:14:35.949161
2019-05-27 03:14:35,949 [salt.state       :1813][INFO    ][8311] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-27 03:14:35,967 [salt.state       :300 ][INFO    ][8311] No changes needed to be made
2019-05-27 03:14:35,968 [salt.state       :1951][INFO    ][8311] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:14:35.968303 duration_in_ms=19.141
2019-05-27 03:14:35,969 [salt.state       :1780][INFO    ][8311] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:14:35.969349
2019-05-27 03:14:35,969 [salt.state       :1813][INFO    ][8311] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-27 03:14:36,021 [salt.state       :300 ][INFO    ][8311] No changes needed to be made
2019-05-27 03:14:36,022 [salt.state       :1951][INFO    ][8311] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:14:36.022274 duration_in_ms=52.924
2019-05-27 03:14:36,023 [salt.state       :1780][INFO    ][8311] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:14:36.023433
2019-05-27 03:14:36,024 [salt.state       :1813][INFO    ][8311] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-27 03:14:36,060 [salt.state       :300 ][INFO    ][8311] No changes needed to be made
2019-05-27 03:14:36,061 [salt.state       :1951][INFO    ][8311] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:14:36.061172 duration_in_ms=37.738
2019-05-27 03:14:36,062 [salt.state       :1780][INFO    ][8311] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:14:36.062054
2019-05-27 03:14:36,062 [salt.state       :1813][INFO    ][8311] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-27 03:14:36,075 [salt.state       :300 ][INFO    ][8311] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-27 03:14:36,076 [salt.state       :1951][INFO    ][8311] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:14:36.076057 duration_in_ms=14.001
2019-05-27 03:14:36,077 [salt.state       :1780][INFO    ][8311] Running state [a2enmod headers] at time 03:14:36.077853
2019-05-27 03:14:36,078 [salt.state       :1813][INFO    ][8311] Executing state cmd.run for [a2enmod headers]
2019-05-27 03:14:36,079 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8311] Executing command 'a2enmod headers' in directory '/root'
2019-05-27 03:14:36,154 [salt.state       :300 ][INFO    ][8311] {'pid': 8334, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-27 03:14:36,155 [salt.state       :1951][INFO    ][8311] Completed state [a2enmod headers] at time 03:14:36.155391 duration_in_ms=77.537
2019-05-27 03:14:36,156 [salt.state       :1780][INFO    ][8311] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:14:36.156111
2019-05-27 03:14:36,156 [salt.state       :1813][INFO    ][8311] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-27 03:14:36,176 [salt.state       :300 ][INFO    ][8311] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-27 03:14:36,176 [salt.state       :1951][INFO    ][8311] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:14:36.176549 duration_in_ms=20.438
2019-05-27 03:14:36,177 [salt.state       :1780][INFO    ][8311] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:14:36.177682
2019-05-27 03:14:36,178 [salt.state       :1813][INFO    ][8311] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-27 03:14:36,259 [salt.state       :300 ][INFO    ][8311] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-27 03:14:36,260 [salt.state       :1951][INFO    ][8311] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:14:36.260742 duration_in_ms=83.048
2019-05-27 03:14:36,261 [salt.state       :1780][INFO    ][8311] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:14:36.261703
2019-05-27 03:14:36,262 [salt.state       :1813][INFO    ][8311] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-27 03:14:36,318 [salt.state       :300 ][INFO    ][8311] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-27 03:14:36,319 [salt.state       :1951][INFO    ][8311] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:14:36.319010 duration_in_ms=57.306
2019-05-27 03:14:36,319 [salt.state       :1780][INFO    ][8311] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:14:36.319599
2019-05-27 03:14:36,319 [salt.state       :1813][INFO    ][8311] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-27 03:14:36,372 [salt.state       :300 ][INFO    ][8311] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-27 03:14:36,372 [salt.state       :1951][INFO    ][8311] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:14:36.372695 duration_in_ms=53.095
2019-05-27 03:14:36,373 [salt.state       :1780][INFO    ][8311] Running state [/root/.pgpass] at time 03:14:36.373017
2019-05-27 03:14:36,373 [salt.state       :1813][INFO    ][8311] Executing state file.managed for [/root/.pgpass]
2019-05-27 03:14:36,408 [salt.state       :300 ][INFO    ][8311] File /root/.pgpass is in the correct state
2019-05-27 03:14:36,408 [salt.state       :1951][INFO    ][8311] Completed state [/root/.pgpass] at time 03:14:36.408730 duration_in_ms=35.712
2019-05-27 03:14:36,412 [salt.state       :1780][INFO    ][8311] Running state [maas-region syncdb --noinput] at time 03:14:36.412494
2019-05-27 03:14:36,412 [salt.state       :1813][INFO    ][8311] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-27 03:14:36,413 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8311] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-27 03:14:38,335 [salt.state       :300 ][INFO    ][8311] {'pid': 8347, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, contenttypes, piston3, sites, auth, maasserver, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-27 03:14:38,336 [salt.state       :1951][INFO    ][8311] Completed state [maas-region syncdb --noinput] at time 03:14:38.336372 duration_in_ms=1923.875
2019-05-27 03:14:38,337 [salt.state       :2022][WARNING ][8311] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-27 03:14:38,340 [salt.state       :1780][INFO    ][8311] Running state [maas-regiond] at time 03:14:38.340042
2019-05-27 03:14:38,340 [salt.state       :1813][INFO    ][8311] Executing state service.running for [maas-regiond]
2019-05-27 03:14:38,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8311] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-27 03:14:38,379 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8311] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-27 03:14:38,397 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8311] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-27 03:14:38,414 [salt.state       :300 ][INFO    ][8311] The service maas-regiond is already running
2019-05-27 03:14:38,415 [salt.state       :1951][INFO    ][8311] Completed state [maas-regiond] at time 03:14:38.415181 duration_in_ms=75.138
2019-05-27 03:14:38,417 [salt.state       :1780][INFO    ][8311] Running state [bind9] at time 03:14:38.417373
2019-05-27 03:14:38,417 [salt.state       :1813][INFO    ][8311] Executing state service.running for [bind9]
2019-05-27 03:14:38,418 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8311] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-27 03:14:38,437 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8311] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-27 03:14:38,454 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8311] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-27 03:14:38,471 [salt.state       :300 ][INFO    ][8311] The service bind9 is already running
2019-05-27 03:14:38,471 [salt.state       :1951][INFO    ][8311] Completed state [bind9] at time 03:14:38.471860 duration_in_ms=54.486
2019-05-27 03:14:38,474 [salt.state       :1780][INFO    ][8311] Running state [apache2] at time 03:14:38.474024
2019-05-27 03:14:38,474 [salt.state       :1813][INFO    ][8311] Executing state service.running for [apache2]
2019-05-27 03:14:38,475 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8311] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-27 03:14:38,494 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8311] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-27 03:14:38,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8311] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-27 03:14:38,533 [salt.state       :300 ][INFO    ][8311] The service apache2 is already running
2019-05-27 03:14:38,533 [salt.state       :1951][INFO    ][8311] Completed state [apache2] at time 03:14:38.533700 duration_in_ms=59.675
2019-05-27 03:14:38,535 [salt.state       :1780][INFO    ][8311] Running state [maasng.wait_for_http_code] at time 03:14:38.535568
2019-05-27 03:14:38,536 [salt.state       :1813][INFO    ][8311] Executing state module.run for [maasng.wait_for_http_code]
2019-05-27 03:14:38,536 [salt.utils.decorators:613 ][WARNING ][8311] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 03:14:38,700 [salt.state       :300 ][INFO    ][8311] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-27 03:14:38,701 [salt.state       :1951][INFO    ][8311] Completed state [maasng.wait_for_http_code] at time 03:14:38.701343 duration_in_ms=165.774
2019-05-27 03:14:38,702 [salt.state       :1780][INFO    ][8311] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:14:38.702735
2019-05-27 03:14:38,703 [salt.state       :1813][INFO    ][8311] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-27 03:14:38,704 [salt.state       :300 ][INFO    ][8311] /var/lib/maas/.setup_admin exists
2019-05-27 03:14:38,704 [salt.state       :1951][INFO    ][8311] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:14:38.704481 duration_in_ms=1.746
2019-05-27 03:14:38,705 [salt.state       :1780][INFO    ][8311] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:14:38.705656
2019-05-27 03:14:38,706 [salt.state       :1813][INFO    ][8311] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-27 03:14:38,707 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8311] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-27 03:14:40,238 [salt.state       :300 ][INFO    ][8311] {'pid': 8375, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-27 03:14:40,239 [salt.state       :1951][INFO    ][8311] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:14:40.239116 duration_in_ms=1533.46
2019-05-27 03:14:40,247 [salt.state       :1780][INFO    ][8311] Running state [maas_region_boot_source_resources_mirror] at time 03:14:40.247797
2019-05-27 03:14:40,248 [salt.state       :1813][INFO    ][8311] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-27 03:14:40,337 [salt.state       :300 ][INFO    ][8311] {'changes': {}}
2019-05-27 03:14:40,338 [salt.state       :1951][INFO    ][8311] Completed state [maas_region_boot_source_resources_mirror] at time 03:14:40.338420 duration_in_ms=90.622
2019-05-27 03:14:40,339 [salt.state       :1780][INFO    ][8311] Running state [maasng.boot_resources_import] at time 03:14:40.339644
2019-05-27 03:14:40,340 [salt.state       :1813][INFO    ][8311] Executing state module.run for [maasng.boot_resources_import]
2019-05-27 03:14:40,340 [salt.utils.decorators:613 ][WARNING ][8311] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 03:14:40,446 [salt.loaded.ext.module.maasng:1600][INFO    ][8311] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-27 03:14:45,513 [salt.loaded.ext.module.maasng:1600][INFO    ][8311] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-27 03:14:49,379 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031449367507
2019-05-27 03:14:49,407 [salt.minion      :1432][INFO    ][8432] Starting a new job with PID 8432
2019-05-27 03:14:49,428 [salt.minion      :1711][INFO    ][8432] Returning information for job: 20190527031449367507
2019-05-27 03:14:50,582 [salt.loaded.ext.module.maasng:1600][INFO    ][8311] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-05-27 03:14:55,700 [salt.state       :300 ][INFO    ][8311] {'ret': True}
2019-05-27 03:14:55,700 [salt.state       :1951][INFO    ][8311] Completed state [maasng.boot_resources_import] at time 03:14:55.700906 duration_in_ms=15361.262
2019-05-27 03:14:55,701 [salt.state       :1780][INFO    ][8311] Running state [maas_region_boot_sources_selection_xenial] at time 03:14:55.701838
2019-05-27 03:14:55,702 [salt.state       :1813][INFO    ][8311] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-27 03:14:55,909 [salt.state       :300 ][INFO    ][8311] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-27 03:14:55,909 [salt.state       :1951][INFO    ][8311] Completed state [maas_region_boot_sources_selection_xenial] at time 03:14:55.909534 duration_in_ms=207.696
2019-05-27 03:14:55,910 [salt.state       :1780][INFO    ][8311] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 03:14:55.910483
2019-05-27 03:14:55,910 [salt.state       :1813][INFO    ][8311] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-27 03:14:55,911 [salt.utils.decorators:613 ][WARNING ][8311] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 03:14:55,911 [salt.loaded.ext.module.maasng:1771][INFO    ][8311] boot-sources sync initiated for ALL Rack's
2019-05-27 03:14:56,801 [salt.state       :300 ][INFO    ][8311] {'ret': True}
2019-05-27 03:14:56,802 [salt.state       :1951][INFO    ][8311] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 03:14:56.802029 duration_in_ms=891.546
2019-05-27 03:14:56,804 [salt.state       :1780][INFO    ][8311] Running state [maas.process_maas_config] at time 03:14:56.804067
2019-05-27 03:14:56,804 [salt.state       :1813][INFO    ][8311] Executing state module.run for [maas.process_maas_config]
2019-05-27 03:14:56,805 [salt.utils.decorators:613 ][WARNING ][8311] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 03:14:56,806 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=enable_http_proxy value=True
2019-05-27 03:14:56,873 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=upstream_dns value=8.8.8.8
2019-05-27 03:14:56,933 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=commissioning_distro_series value=xenial
2019-05-27 03:14:57,000 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=default_osystem value=ubuntu
2019-05-27 03:14:57,187 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=active_discovery_interval value=600
2019-05-27 03:14:57,305 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=dnssec_validation value=no
2019-05-27 03:14:59,919 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=maas_name value=mas01
2019-05-27 03:14:59,994 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=network_discovery value=enabled
2019-05-27 03:15:00,112 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=enable_third_party_drivers value=True
2019-05-27 03:15:00,165 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=default_storage_layout value=lvm
2019-05-27 03:15:00,227 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=ntp_external_only value=True
2019-05-27 03:15:00,279 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-27 03:15:00,336 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=default_distro_series value=xenial
2019-05-27 03:15:00,395 [salt.loaded.ext.module.maas:92  ][INFO    ][8311] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-05-27 03:15:00,517 [salt.state       :300 ][INFO    ][8311] {'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-05-27 03:15:00,517 [salt.state       :1951][INFO    ][8311] Completed state [maas.process_maas_config] at time 03:15:00.517761 duration_in_ms=3713.694
2019-05-27 03:15:00,518 [salt.state       :1780][INFO    ][8311] Running state [pxe_admin] at time 03:15:00.518732
2019-05-27 03:15:00,519 [salt.state       :1813][INFO    ][8311] Executing state maasng.fabric_present for [pxe_admin]
2019-05-27 03:15:00,580 [salt.loaded.ext.module.maasng:945 ][INFO    ][8311] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'h6frag', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 03:15:00,638 [salt.loaded.ext.module.maasng:1008][WARNING ][8311] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-27 03:15:00,638 [salt.loaded.ext.module.maasng:1011][WARNING ][8311] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-27 03:15:00,713 [salt.state       :300 ][INFO    ][8311] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-27 03:15:00,713 [salt.state       :1951][INFO    ][8311] Completed state [pxe_admin] at time 03:15:00.713442 duration_in_ms=194.71
2019-05-27 03:15:00,713 [salt.state       :1780][INFO    ][8311] Running state [vlan 0] at time 03:15:00.713859
2019-05-27 03:15:00,714 [salt.state       :1813][INFO    ][8311] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-27 03:15:00,776 [salt.loaded.ext.module.maasng:945 ][INFO    ][8311] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'h6frag', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 03:15:00,925 [salt.loaded.ext.module.maasng:945 ][INFO    ][8311] [{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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'h6frag', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 03:15:01,209 [salt.loaded.ext.module.maasng:945 ][INFO    ][8311] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'h6frag', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 03:15:01,294 [salt.state       :300 ][INFO    ][8311] {'new': 'Vlan untagged was updated'}
2019-05-27 03:15:01,295 [salt.state       :1951][INFO    ][8311] Completed state [vlan 0] at time 03:15:01.294917 duration_in_ms=581.056
2019-05-27 03:15:01,296 [salt.state       :1780][INFO    ][8311] Running state [192.168.11.0/24] at time 03:15:01.296827
2019-05-27 03:15:01,297 [salt.state       :1813][INFO    ][8311] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-27 03:15:01,503 [salt.loaded.ext.module.maasng:945 ][INFO    ][8311] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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': u'h6frag', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 03:15:01,504 [salt.loaded.ext.module.maasng:1235][WARNING ][8311] Ignoring parameter vlan:0
2019-05-27 03:15:01,579 [salt.state       :300 ][INFO    ][8311] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-27 03:15:01,580 [salt.state       :1951][INFO    ][8311] Completed state [192.168.11.0/24] at time 03:15:01.580083 duration_in_ms=283.255
2019-05-27 03:15:01,581 [salt.state       :1780][INFO    ][8311] Running state [maas_create_iprange_1] at time 03:15:01.581426
2019-05-27 03:15:01,581 [salt.state       :1813][INFO    ][8311] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-27 03:15:01,635 [salt.state       :300 ][INFO    ][8311] Iprange maas_create_iprange_1 already exist.
2019-05-27 03:15:01,635 [salt.state       :1951][INFO    ][8311] Completed state [maas_create_iprange_1] at time 03:15:01.635673 duration_in_ms=54.246
2019-05-27 03:15:01,636 [salt.state       :1780][INFO    ][8311] Running state [vlan 0] at time 03:15:01.636066
2019-05-27 03:15:01,636 [salt.state       :1813][INFO    ][8311] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-27 03:15:01,686 [salt.loaded.ext.module.maasng:945 ][INFO    ][8311] [{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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'h6frag', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 03:15:01,800 [salt.loaded.ext.module.maasng:945 ][INFO    ][8311] [{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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'h6frag', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 03:15:02,059 [salt.loaded.ext.module.maasng:945 ][INFO    ][8311] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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': u'h6frag', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 03:15:02,138 [salt.state       :300 ][INFO    ][8311] {'new': 'Vlan untagged was updated'}
2019-05-27 03:15:02,138 [salt.state       :1951][INFO    ][8311] Completed state [vlan 0] at time 03:15:02.138789 duration_in_ms=502.722
2019-05-27 03:15:02,139 [salt.state       :1780][INFO    ][8311] Running state [opnfv] at time 03:15:02.139544
2019-05-27 03:15:02,139 [salt.state       :1813][INFO    ][8311] Executing state maasng.sshkey_present for [opnfv]
2019-05-27 03:15:02,191 [salt.loaded.ext.module.maasng:1903][INFO    ][8311] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-05-27 03:15:02,191 [salt.state       :300 ][INFO    ][8311] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-05-27 03:15:02,191 [salt.state       :1951][INFO    ][8311] Completed state [opnfv] at time 03:15:02.191843 duration_in_ms=52.299
2019-05-27 03:15:02,194 [salt.minion      :1711][INFO    ][8311] Returning information for job: 20190527031434342410
2019-05-27 03:15:02,572 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527031502565924
2019-05-27 03:15:02,589 [salt.minion      :1432][INFO    ][8781] Starting a new job with PID 8781
2019-05-27 03:15:06,282 [salt.state       :915 ][INFO    ][8781] Loading fresh modules for state activity
2019-05-27 03:15:06,375 [salt.state       :1780][INFO    ][8781] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:15:06.375203
2019-05-27 03:15:06,375 [salt.state       :1813][INFO    ][8781] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-27 03:15:06,377 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8781] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-27 03:15:07,703 [salt.state       :300 ][INFO    ][8781] {'pid': 8806, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-27 03:15:07,704 [salt.state       :1951][INFO    ][8781] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:15:07.703977 duration_in_ms=1328.774
2019-05-27 03:15:07,706 [salt.state       :1780][INFO    ][8781] Running state [maas.process_machines] at time 03:15:07.706402
2019-05-27 03:15:07,706 [salt.state       :1813][INFO    ][8781] Executing state module.run for [maas.process_machines]
2019-05-27 03:15:07,708 [salt.utils.decorators:613 ][WARNING ][8781] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 03:15:07,882 [salt.loaded.ext.module.maas:412 ][WARNING ][8781] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:15:07,883 [salt.loaded.ext.module.maas:92  ][INFO    ][8781] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=wfe34b architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:15:08,753 [salt.loaded.ext.module.maas:412 ][WARNING ][8781] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:15:08,754 [salt.loaded.ext.module.maas:92  ][INFO    ][8781] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=6x36tk architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:15:09,628 [salt.loaded.ext.module.maas:412 ][WARNING ][8781] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:15:09,629 [salt.loaded.ext.module.maas:92  ][INFO    ][8781] machine hostname=kvm01 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=y8erpc architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:15:10,426 [salt.loaded.ext.module.maas:412 ][WARNING ][8781] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:15:10,427 [salt.loaded.ext.module.maas:92  ][INFO    ][8781] machine hostname=kvm03 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=mmkgw7 architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:15:11,355 [salt.loaded.ext.module.maas:412 ][WARNING ][8781] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:15:11,356 [salt.loaded.ext.module.maas:92  ][INFO    ][8781] machine hostname=kvm02 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=h8kg7f architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:15:12,260 [salt.state       :300 ][INFO    ][8781] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-27 03:15:12,261 [salt.state       :1951][INFO    ][8781] Completed state [maas.process_machines] at time 03:15:12.261115 duration_in_ms=4554.711
2019-05-27 03:15:12,264 [salt.minion      :1711][INFO    ][8781] Returning information for job: 20190527031502565924
2019-05-27 03:15:52,271 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527031552257385
2019-05-27 03:15:52,293 [salt.minion      :1432][INFO    ][9076] Starting a new job with PID 9076
2019-05-27 03:15:55,847 [salt.state       :915 ][INFO    ][9076] Loading fresh modules for state activity
2019-05-27 03:15:55,936 [salt.state       :1780][INFO    ][9076] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:15:55.936268
2019-05-27 03:15:55,936 [salt.state       :1813][INFO    ][9076] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-27 03:15:55,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9076] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-27 03:15:57,375 [salt.state       :300 ][INFO    ][9076] {'pid': 9084, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-27 03:15:57,376 [salt.state       :1951][INFO    ][9076] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:15:57.376219 duration_in_ms=1439.952
2019-05-27 03:15:57,377 [salt.state       :1780][INFO    ][9076] Running state [maas.wait_for_machine_status] at time 03:15:57.377582
2019-05-27 03:15:57,377 [salt.state       :1813][INFO    ][9076] Executing state module.run for [maas.wait_for_machine_status]
2019-05-27 03:15:57,378 [salt.utils.decorators:613 ][WARNING ][9076] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 03:15:57,580 [salt.loaded.ext.module.maas:981 ][INFO    ][9076] Machine wfe34b deleted
2019-05-27 03:15:57,982 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:15:57,983 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:15:59,341 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:15:59,342 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=6x36tk architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:00,189 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:00,190 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm01 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=y8erpc architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:00,896 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:00,897 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm03 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=mmkgw7 architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:01,560 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:01,562 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm02 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=h8kg7f architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:02,681 [salt.loaded.ext.module.maas:981 ][INFO    ][9076] Machine 6x36tk deleted
2019-05-27 03:16:03,059 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:03,060 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=4q6cfa architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:03,932 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:03,933 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=cmp001 power_type=ipmi mac_addresses=00:25:b5:a0:00:5a power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:05,450 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:05,451 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm01 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=y8erpc architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:06,312 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:06,313 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm03 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=mmkgw7 architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:07,187 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:07,187 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm02 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=h8kg7f architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:07,357 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031607345360
2019-05-27 03:16:07,379 [salt.minion      :1432][INFO    ][9507] Starting a new job with PID 9507
2019-05-27 03:16:07,399 [salt.minion      :1711][INFO    ][9507] Returning information for job: 20190527031607345360
2019-05-27 03:16:08,209 [salt.loaded.ext.module.maas:981 ][INFO    ][9076] Machine y8erpc deleted
2019-05-27 03:16:08,613 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:08,613 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=4q6cfa architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:09,505 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:09,506 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=ecxxnr architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:10,233 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:10,233 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:11,713 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:11,714 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm03 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=mmkgw7 architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:12,635 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:12,636 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm02 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=h8kg7f architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:13,637 [salt.loaded.ext.module.maas:981 ][INFO    ][9076] Machine mmkgw7 deleted
2019-05-27 03:16:13,977 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:13,977 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=4q6cfa architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:14,750 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:14,751 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=ecxxnr architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:15,619 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:15,620 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm01 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=xxnpae architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:16,482 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:16,482 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm03 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:18,205 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:18,206 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm02 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=h8kg7f architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:19,295 [salt.loaded.ext.module.maas:981 ][INFO    ][9076] Machine h8kg7f deleted
2019-05-27 03:16:19,733 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:19,733 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=4q6cfa architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:20,609 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:20,609 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=ecxxnr architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:21,463 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:21,463 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm01 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=xxnpae architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:22,337 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:22,338 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm03 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=ddttbg architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:23,040 [salt.loaded.ext.module.maas:412 ][WARNING ][9076] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 03:16:23,040 [salt.loaded.ext.module.maas:92  ][INFO    ][9076] machine hostname=kvm02 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 03:16:24,447 [salt.loaded.ext.module.maas:1023][INFO    ][9076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1472.934443s left)
2019-05-27 03:16:37,495 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031637444640
2019-05-27 03:16:37,515 [salt.minion      :1432][INFO    ][10298] Starting a new job with PID 10298
2019-05-27 03:16:37,534 [salt.minion      :1711][INFO    ][10298] Returning information for job: 20190527031637444640
2019-05-27 03:16:55,568 [salt.loaded.ext.module.maas:1023][INFO    ][9076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1441.81364489s left)
2019-05-27 03:17:07,589 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031707577287
2019-05-27 03:17:07,613 [salt.minion      :1432][INFO    ][10329] Starting a new job with PID 10329
2019-05-27 03:17:07,636 [salt.minion      :1711][INFO    ][10329] Returning information for job: 20190527031707577287
2019-05-27 03:17:26,436 [salt.loaded.ext.module.maas:1023][INFO    ][9076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1410.94568181s left)
2019-05-27 03:17:37,636 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031737623072
2019-05-27 03:17:37,659 [salt.minion      :1432][INFO    ][10381] Starting a new job with PID 10381
2019-05-27 03:17:37,681 [salt.minion      :1711][INFO    ][10381] Returning information for job: 20190527031737623072
2019-05-27 03:17:57,536 [salt.loaded.ext.module.maas:1023][INFO    ][9076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1379.84540081s left)
2019-05-27 03:18:07,684 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031807672171
2019-05-27 03:18:07,708 [salt.minion      :1432][INFO    ][10473] Starting a new job with PID 10473
2019-05-27 03:18:07,730 [salt.minion      :1711][INFO    ][10473] Returning information for job: 20190527031807672171
2019-05-27 03:18:29,037 [salt.loaded.ext.module.maas:1023][INFO    ][9076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1348.34428096s left)
2019-05-27 03:18:37,737 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031837724156
2019-05-27 03:18:37,761 [salt.minion      :1432][INFO    ][10748] Starting a new job with PID 10748
2019-05-27 03:18:37,784 [salt.minion      :1711][INFO    ][10748] Returning information for job: 20190527031837724156
2019-05-27 03:19:00,655 [salt.loaded.ext.module.maas:1023][INFO    ][9076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1316.72666287s left)
2019-05-27 03:19:07,804 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031907791888
2019-05-27 03:19:07,828 [salt.minion      :1432][INFO    ][10896] Starting a new job with PID 10896
2019-05-27 03:19:07,850 [salt.minion      :1711][INFO    ][10896] Returning information for job: 20190527031907791888
2019-05-27 03:19:32,263 [salt.loaded.ext.module.maas:1023][INFO    ][9076] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1285.11865783s left)
2019-05-27 03:19:37,861 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527031937848309
2019-05-27 03:19:37,883 [salt.minion      :1432][INFO    ][11457] Starting a new job with PID 11457
2019-05-27 03:19:37,908 [salt.minion      :1711][INFO    ][11457] Returning information for job: 20190527031937848309
2019-05-27 03:20:05,114 [salt.loaded.ext.module.maas:1023][INFO    ][9076] Waiting status:Ready|Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1252.26804185s left)
2019-05-27 03:20:07,934 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032007921935
2019-05-27 03:20:07,957 [salt.minion      :1432][INFO    ][11652] Starting a new job with PID 11652
2019-05-27 03:20:07,980 [salt.minion      :1711][INFO    ][11652] Returning information for job: 20190527032007921935
2019-05-27 03:20:38,002 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032037991044
2019-05-27 03:20:38,024 [salt.minion      :1432][INFO    ][11764] Starting a new job with PID 11764
2019-05-27 03:20:38,046 [salt.minion      :1711][INFO    ][11764] Returning information for job: 20190527032037991044
2019-05-27 03:20:38,563 [salt.state       :300 ][INFO    ][9076] {'ret': True}
2019-05-27 03:20:38,563 [salt.state       :1951][INFO    ][9076] Completed state [maas.wait_for_machine_status] at time 03:20:38.563355 duration_in_ms=281185.771
2019-05-27 03:20:38,566 [salt.minion      :1711][INFO    ][9076] Returning information for job: 20190527031552257385
2019-05-27 03:20:39,080 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527032039070249
2019-05-27 03:20:39,098 [salt.minion      :1432][INFO    ][11776] Starting a new job with PID 11776
2019-05-27 03:20:42,519 [salt.state       :915 ][INFO    ][11776] Loading fresh modules for state activity
2019-05-27 03:20:42,564 [salt.fileclient  :1219][INFO    ][11776] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-27 03:20:42,642 [salt.state       :1780][INFO    ][11776] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:42.641994
2019-05-27 03:20:42,642 [salt.state       :1813][INFO    ][11776] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-27 03:20:42,643 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11776] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-27 03:20:44,104 [salt.state       :300 ][INFO    ][11776] {'pid': 11784, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-27 03:20:44,105 [salt.state       :1951][INFO    ][11776] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:44.105763 duration_in_ms=1463.766
2019-05-27 03:20:44,109 [salt.state       :1780][INFO    ][11776] Running state [maas_machines_storage_cmp002_lvm] at time 03:20:44.109201
2019-05-27 03:20:44,109 [salt.state       :1813][INFO    ][11776] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-27 03:20:45,641 [salt.loaded.ext.module.maasng:610 ][INFO    ][11776] 4q6cfa
2019-05-27 03:20:45,641 [salt.loaded.ext.module.maasng:626 ][INFO    ][11776] sda
2019-05-27 03:20:46,317 [salt.loaded.ext.module.maasng:361 ][INFO    ][11776] 4q6cfa
2019-05-27 03:20:46,437 [salt.loaded.ext.module.maasng:367 ][INFO    ][11776] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'4q6cfa', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/blockdevices/2/', u'partitions': [{u'uuid': u'300cb1dc-d223-4091-83c1-11095b011df7', u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4q6cfa', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'55a696fb-789b-463f-bd45-68de1c523711'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 2, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, {u'size': 2397988454400, u'model': None, u'block_size': 4096, u'uuid': u'0f8fe239-76cb-44f7-9320-347c4bf932f8', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'03332711-a270-4ce3-a1e0-77c79ce67623'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4q6cfa', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/blockdevices/7/', u'partitions': [], u'used_size': 2397988454400, u'id': 7, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-05-27 03:20:46,438 [salt.loaded.ext.module.maasng:632 ][INFO    ][11776] vgroot
2019-05-27 03:20:46,439 [salt.loaded.ext.module.maasng:635 ][INFO    ][11776] lvroot
2019-05-27 03:20:46,439 [salt.loaded.ext.module.maasng:639 ][INFO    ][11776] 107374182400
2019-05-27 03:20:47,122 [salt.loaded.ext.module.maasng:645 ][INFO    ][11776] {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'disable_ipv4': False, 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'cmp002.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'partition_table_type': u'GPT', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'4q6cfa', u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'd86bf010-a9c8-4c44-9c1b-bd618ef1e43f', u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4q6cfa', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'1fd98e93-60e4-4aee-8fc5-0c5fa6f1a4e3', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'4q6cfa', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/blockdevices/2/', u'used_size': 2397998940160, u'id': 2, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'd86bf010-a9c8-4c44-9c1b-bd618ef1e43f', u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4q6cfa', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'1fd98e93-60e4-4aee-8fc5-0c5fa6f1a4e3', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'3ab61306-e5e6-4067-9b45-444d8536055c', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'81a0b41f-2d97-4c1c-9555-a2288552900e', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4q6cfa', 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'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/4q6cfa/', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'4q6cfa', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.39'], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 2397998940160, u'partition_table_type': u'GPT', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'4q6cfa', u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'd86bf010-a9c8-4c44-9c1b-bd618ef1e43f', u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4q6cfa', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'1fd98e93-60e4-4aee-8fc5-0c5fa6f1a4e3', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'4q6cfa', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 12, u'node_type_name': u'Machine', u'current_testing_result_id': 13, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'virtualblockdevice_set': [{u'size': 107374182400, u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'3ab61306-e5e6-4067-9b45-444d8536055c', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'81a0b41f-2d97-4c1c-9555-a2288552900e', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4q6cfa', u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/blockdevices/11/', 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'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'h6frag', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'h6frag', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 25}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 9, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'h6frag', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'4q6cfa', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/interfaces/9/'}, u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'h6frag', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'h6frag', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 25}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 9, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'h6frag', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'4q6cfa', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/interfaces/9/'}, {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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 17, u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'system_id': u'4q6cfa', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 18, u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'system_id': u'4q6cfa', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 28}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 19, u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'system_id': u'4q6cfa', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4q6cfa/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-27 03:20:47,124 [salt.state       :300 ][INFO    ][11776] {'new': {'storage_layout': 'lvm'}}
2019-05-27 03:20:47,124 [salt.state       :1951][INFO    ][11776] Completed state [maas_machines_storage_cmp002_lvm] at time 03:20:47.124862 duration_in_ms=3015.659
2019-05-27 03:20:47,125 [salt.state       :1780][INFO    ][11776] Running state [maas_machines_storage_cmp001_lvm] at time 03:20:47.125436
2019-05-27 03:20:47,125 [salt.state       :1813][INFO    ][11776] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-27 03:20:48,578 [salt.loaded.ext.module.maasng:610 ][INFO    ][11776] ecxxnr
2019-05-27 03:20:48,579 [salt.loaded.ext.module.maasng:626 ][INFO    ][11776] sda
2019-05-27 03:20:49,272 [salt.loaded.ext.module.maasng:361 ][INFO    ][11776] ecxxnr
2019-05-27 03:20:49,398 [salt.loaded.ext.module.maasng:367 ][INFO    ][11776] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ecxxnr', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/blockdevices/1/', u'partitions': [{u'uuid': u'c3997cef-745d-43e1-a04e-6129fa327b50', u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ecxxnr', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'de8b1950-c8cf-4525-95ce-e97935f52ac5'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, {u'size': 2397988454400, u'model': None, u'block_size': 4096, u'uuid': u'982dea15-f0d7-45cf-b2c7-636e87ed2a66', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'4ae11905-38b6-427a-9c25-de6a0f827de4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ecxxnr', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/blockdevices/6/', u'partitions': [], u'used_size': 2397988454400, u'id': 6, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-05-27 03:20:49,398 [salt.loaded.ext.module.maasng:632 ][INFO    ][11776] vgroot
2019-05-27 03:20:49,399 [salt.loaded.ext.module.maasng:635 ][INFO    ][11776] lvroot
2019-05-27 03:20:49,399 [salt.loaded.ext.module.maasng:639 ][INFO    ][11776] 107374182400
2019-05-27 03:20:49,951 [salt.loaded.ext.module.maasng:645 ][INFO    ][11776] {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'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'h6frag', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 21, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'h6frag', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'h6frag', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'ecxxnr', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/interfaces/10/'}, u'ip_addresses': [u'192.168.11.38'], u'cpu_count': 16, u'owner_data': {}, u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'51ccd0c5-b561-40e8-b350-4f0a952c2f6d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ecxxnr', u'device_id': 1, u'filesystem': {u'uuid': u'5d957234-7dfc-41f0-a1ac-04fc343e6e70', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ecxxnr', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/blockdevices/1/'}, {u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'31c23592-779e-4c08-b631-195652c42a07', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ecxxnr', u'partition_table_type': None, u'filesystem': {u'uuid': u'b0a1a084-69d0-4e5a-8bdb-38dca66185ba', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/blockdevices/12/'}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/ecxxnr/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'ecxxnr', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'51ccd0c5-b561-40e8-b350-4f0a952c2f6d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ecxxnr', u'device_id': 1, u'filesystem': {u'uuid': u'5d957234-7dfc-41f0-a1ac-04fc343e6e70', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ecxxnr', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/blockdevices/1/'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ecxxnr', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'51ccd0c5-b561-40e8-b350-4f0a952c2f6d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ecxxnr', u'device_id': 1, u'filesystem': {u'uuid': u'5d957234-7dfc-41f0-a1ac-04fc343e6e70', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ecxxnr', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/blockdevices/1/'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'h6frag', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 21, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'h6frag', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'h6frag', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'ecxxnr', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/interfaces/10/'}, {u'name': u'enp7s0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ecxxnr', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/interfaces/14/'}, {u'name': u'enp9s0', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ecxxnr', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/interfaces/15/'}, {u'name': u'enp8s0', u'links': [{u'id': 24, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ecxxnr', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/interfaces/16/'}], u'current_testing_result_id': 15, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'31c23592-779e-4c08-b631-195652c42a07', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ecxxnr', u'partition_table_type': None, u'filesystem': {u'uuid': u'b0a1a084-69d0-4e5a-8bdb-38dca66185ba', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/ecxxnr/blockdevices/12/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 14, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-27 03:20:49,953 [salt.state       :300 ][INFO    ][11776] {'new': {'storage_layout': 'lvm'}}
2019-05-27 03:20:49,954 [salt.state       :1951][INFO    ][11776] Completed state [maas_machines_storage_cmp001_lvm] at time 03:20:49.954277 duration_in_ms=2828.84
2019-05-27 03:20:49,958 [salt.minion      :1711][INFO    ][11776] Returning information for job: 20190527032039070249
2019-05-27 03:20:50,574 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527032050564360
2019-05-27 03:20:50,597 [salt.minion      :1432][INFO    ][11803] Starting a new job with PID 11803
2019-05-27 03:20:51,348 [salt.state       :915 ][INFO    ][11803] Loading fresh modules for state activity
2019-05-27 03:20:51,400 [salt.fileclient  :1219][INFO    ][11803] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-27 03:20:51,445 [salt.state       :1780][INFO    ][11803] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:51.445238
2019-05-27 03:20:51,445 [salt.state       :1813][INFO    ][11803] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-27 03:20:51,447 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11803] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-27 03:20:52,892 [salt.state       :300 ][INFO    ][11803] {'pid': 11810, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-27 03:20:52,892 [salt.state       :1951][INFO    ][11803] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:52.892557 duration_in_ms=1447.32
2019-05-27 03:20:52,893 [salt.state       :1780][INFO    ][11803] Running state [maas.deploy_machines] at time 03:20:52.893726
2019-05-27 03:20:52,893 [salt.state       :1813][INFO    ][11803] Executing state module.run for [maas.deploy_machines]
2019-05-27 03:20:52,894 [salt.utils.decorators:613 ][WARNING ][11803] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 03:20:53,647 [salt.loaded.ext.module.maas:684 ][INFO    ][11803] deploymachines hwe_kernel=ga-16.04 system_id=4q6cfa distro_series=xenial
2019-05-27 03:20:56,314 [salt.loaded.ext.module.maas:684 ][INFO    ][11803] deploymachines hwe_kernel=ga-16.04 system_id=ecxxnr distro_series=xenial
2019-05-27 03:20:59,016 [salt.loaded.ext.module.maas:684 ][INFO    ][11803] deploymachines hwe_kernel=ga-16.04 system_id=xxnpae distro_series=xenial
2019-05-27 03:21:01,826 [salt.loaded.ext.module.maas:684 ][INFO    ][11803] deploymachines hwe_kernel=ga-16.04 system_id=ddttbg distro_series=xenial
2019-05-27 03:21:04,219 [salt.loaded.ext.module.maas:684 ][INFO    ][11803] deploymachines hwe_kernel=ga-16.04 system_id=yfnp3h distro_series=xenial
2019-05-27 03:21:05,627 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032105614125
2019-05-27 03:21:05,651 [salt.minion      :1432][INFO    ][12064] Starting a new job with PID 12064
2019-05-27 03:21:05,673 [salt.minion      :1711][INFO    ][12064] Returning information for job: 20190527032105614125
2019-05-27 03:21:06,312 [salt.state       :300 ][INFO    ][11803] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-27 03:21:06,313 [salt.state       :1951][INFO    ][11803] Completed state [maas.deploy_machines] at time 03:21:06.313149 duration_in_ms=13419.421
2019-05-27 03:21:06,316 [salt.minion      :1711][INFO    ][11803] Returning information for job: 20190527032050564360
2019-05-27 03:21:06,899 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527032106887360
2019-05-27 03:21:06,922 [salt.minion      :1432][INFO    ][12081] Starting a new job with PID 12081
2019-05-27 03:21:10,644 [salt.state       :915 ][INFO    ][12081] Loading fresh modules for state activity
2019-05-27 03:21:10,696 [salt.fileclient  :1219][INFO    ][12081] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-27 03:21:10,742 [salt.state       :1780][INFO    ][12081] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:21:10.741973
2019-05-27 03:21:10,742 [salt.state       :1813][INFO    ][12081] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-27 03:21:10,744 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12081] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-27 03:21:12,101 [salt.state       :300 ][INFO    ][12081] {'pid': 12108, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-27 03:21:12,102 [salt.state       :1951][INFO    ][12081] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:21:12.102071 duration_in_ms=1360.098
2019-05-27 03:21:12,104 [salt.state       :1780][INFO    ][12081] Running state [maas.wait_for_machine_status] at time 03:21:12.104818
2019-05-27 03:21:12,105 [salt.state       :1813][INFO    ][12081] Executing state module.run for [maas.wait_for_machine_status]
2019-05-27 03:21:12,105 [salt.utils.decorators:613 ][WARNING ][12081] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 03:21:15,910 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.20426512s left)
2019-05-27 03:21:21,933 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032121920186
2019-05-27 03:21:21,957 [salt.minion      :1432][INFO    ][12149] Starting a new job with PID 12149
2019-05-27 03:21:21,978 [salt.minion      :1711][INFO    ][12149] Returning information for job: 20190527032121920186
2019-05-27 03:21:49,236 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.87836313s left)
2019-05-27 03:21:51,977 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032151964996
2019-05-27 03:21:52,000 [salt.minion      :1432][INFO    ][12169] Starting a new job with PID 12169
2019-05-27 03:21:52,023 [salt.minion      :1711][INFO    ][12169] Returning information for job: 20190527032151964996
2019-05-27 03:22:22,086 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032222073019
2019-05-27 03:22:22,109 [salt.minion      :1432][INFO    ][12225] Starting a new job with PID 12225
2019-05-27 03:22:22,133 [salt.minion      :1711][INFO    ][12225] Returning information for job: 20190527032222073019
2019-05-27 03:22:22,731 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.38267303s left)
2019-05-27 03:22:52,130 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032252117439
2019-05-27 03:22:52,154 [salt.minion      :1432][INFO    ][12331] Starting a new job with PID 12331
2019-05-27 03:22:52,178 [salt.minion      :1711][INFO    ][12331] Returning information for job: 20190527032252117439
2019-05-27 03:22:55,523 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.591048s left)
2019-05-27 03:23:22,192 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032322179305
2019-05-27 03:23:22,216 [salt.minion      :1432][INFO    ][12828] Starting a new job with PID 12828
2019-05-27 03:23:22,238 [salt.minion      :1711][INFO    ][12828] Returning information for job: 20190527032322179305
2019-05-27 03:23:29,338 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.77614903s left)
2019-05-27 03:23:52,254 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032352243296
2019-05-27 03:23:52,276 [salt.minion      :1432][INFO    ][12869] Starting a new job with PID 12869
2019-05-27 03:23:52,298 [salt.minion      :1711][INFO    ][12869] Returning information for job: 20190527032352243296
2019-05-27 03:24:02,789 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.325387s left)
2019-05-27 03:24:22,311 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032422298416
2019-05-27 03:24:22,335 [salt.minion      :1432][INFO    ][13414] Starting a new job with PID 13414
2019-05-27 03:24:22,358 [salt.minion      :1711][INFO    ][13414] Returning information for job: 20190527032422298416
2019-05-27 03:24:36,089 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.02454805s left)
2019-05-27 03:24:52,376 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032452364073
2019-05-27 03:24:52,399 [salt.minion      :1432][INFO    ][13434] Starting a new job with PID 13434
2019-05-27 03:24:52,421 [salt.minion      :1711][INFO    ][13434] Returning information for job: 20190527032452364073
2019-05-27 03:25:09,446 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.66805911s left)
2019-05-27 03:25:22,438 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032522426278
2019-05-27 03:25:22,462 [salt.minion      :1432][INFO    ][13559] Starting a new job with PID 13559
2019-05-27 03:25:22,485 [salt.minion      :1711][INFO    ][13559] Returning information for job: 20190527032522426278
2019-05-27 03:25:43,111 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.00302219s left)
2019-05-27 03:25:52,507 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032552494941
2019-05-27 03:25:52,533 [salt.minion      :1432][INFO    ][13579] Starting a new job with PID 13579
2019-05-27 03:25:52,555 [salt.minion      :1711][INFO    ][13579] Returning information for job: 20190527032552494941
2019-05-27 03:26:16,347 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.76729512s left)
2019-05-27 03:26:22,582 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032622569691
2019-05-27 03:26:22,608 [salt.minion      :1432][INFO    ][13988] Starting a new job with PID 13988
2019-05-27 03:26:22,634 [salt.minion      :1711][INFO    ][13988] Returning information for job: 20190527032622569691
2019-05-27 03:26:49,770 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.34395123s left)
2019-05-27 03:26:52,664 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032652652245
2019-05-27 03:26:52,691 [salt.minion      :1432][INFO    ][14012] Starting a new job with PID 14012
2019-05-27 03:26:52,712 [salt.minion      :1711][INFO    ][14012] Returning information for job: 20190527032652652245
2019-05-27 03:27:22,743 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032722729772
2019-05-27 03:27:22,766 [salt.minion      :1432][INFO    ][14264] Starting a new job with PID 14264
2019-05-27 03:27:22,789 [salt.minion      :1711][INFO    ][14264] Returning information for job: 20190527032722729772
2019-05-27 03:27:23,622 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1878.49180722s left)
2019-05-27 03:27:52,830 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032752817237
2019-05-27 03:27:52,853 [salt.minion      :1432][INFO    ][14310] Starting a new job with PID 14310
2019-05-27 03:27:52,880 [salt.minion      :1711][INFO    ][14310] Returning information for job: 20190527032752817237
2019-05-27 03:27:57,313 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1844.80085301s left)
2019-05-27 03:28:22,929 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032822916684
2019-05-27 03:28:22,952 [salt.minion      :1432][INFO    ][14696] Starting a new job with PID 14696
2019-05-27 03:28:22,976 [salt.minion      :1711][INFO    ][14696] Returning information for job: 20190527032822916684
2019-05-27 03:28:30,858 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1811.25545907s left)
2019-05-27 03:28:53,028 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032853015077
2019-05-27 03:28:53,052 [salt.minion      :1432][INFO    ][14724] Starting a new job with PID 14724
2019-05-27 03:28:53,077 [salt.minion      :1711][INFO    ][14724] Returning information for job: 20190527032853015077
2019-05-27 03:29:04,450 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1777.66361523s left)
2019-05-27 03:29:23,137 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032923124316
2019-05-27 03:29:23,161 [salt.minion      :1432][INFO    ][14785] Starting a new job with PID 14785
2019-05-27 03:29:23,188 [salt.minion      :1711][INFO    ][14785] Returning information for job: 20190527032923124316
2019-05-27 03:29:37,811 [salt.loaded.ext.module.maas:1023][INFO    ][12081] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1744.303195s left)
2019-05-27 03:29:53,211 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527032953204890
2019-05-27 03:29:53,231 [salt.minion      :1432][INFO    ][14975] Starting a new job with PID 14975
2019-05-27 03:29:53,255 [salt.minion      :1711][INFO    ][14975] Returning information for job: 20190527032953204890
2019-05-27 03:30:11,540 [salt.state       :300 ][INFO    ][12081] {'ret': True}
2019-05-27 03:30:11,540 [salt.state       :1951][INFO    ][12081] Completed state [maas.wait_for_machine_status] at time 03:30:11.540678 duration_in_ms=539435.857
2019-05-27 03:30:11,544 [salt.minion      :1711][INFO    ][12081] Returning information for job: 20190527032106887360
2019-05-27 04:04:01,890 [salt.utils.schedule:1377][INFO    ][7022] Running scheduled job: __mine_interval
2019-05-27 04:10:08,251 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command pillar.get with jid 20190527041008238868
2019-05-27 04:10:08,278 [salt.minion      :1432][INFO    ][18136] Starting a new job with PID 18136
2019-05-27 04:10:08,290 [salt.minion      :1711][INFO    ][18136] Returning information for job: 20190527041008238868
2019-05-27 04:10:08,872 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command service.status with jid 20190527041008857949
2019-05-27 04:10:08,894 [salt.minion      :1432][INFO    ][18158] Starting a new job with PID 18158
2019-05-27 04:10:09,288 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][18158] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-27 04:10:09,320 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][18158] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-27 04:10:09,336 [salt.minion      :1711][INFO    ][18158] Returning information for job: 20190527041008857949
2019-05-27 04:10:09,831 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527041009819371
2019-05-27 04:10:09,850 [salt.minion      :1432][INFO    ][18169] Starting a new job with PID 18169
2019-05-27 04:10:13,619 [salt.state       :915 ][INFO    ][18169] Loading fresh modules for state activity
2019-05-27 04:10:14,016 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command 'salt-minion --version' in directory '/root'
2019-05-27 04:10:14,365 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command 'salt-minion --version' in directory '/root'
2019-05-27 04:10:15,130 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command 'salt-minion --version' in directory '/root'
2019-05-27 04:10:15,454 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command 'salt-minion --version' in directory '/root'
2019-05-27 04:10:16,769 [salt.state       :1780][INFO    ][18169] Running state [salt-minion] at time 04:10:16.769884
2019-05-27 04:10:16,770 [salt.state       :1813][INFO    ][18169] Executing state pkg.installed for [salt-minion]
2019-05-27 04:10:16,770 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-27 04:10:16,874 [salt.state       :300 ][INFO    ][18169] All specified packages are already installed
2019-05-27 04:10:16,874 [salt.state       :1951][INFO    ][18169] Completed state [salt-minion] at time 04:10:16.874388 duration_in_ms=104.504
2019-05-27 04:10:16,874 [salt.state       :1780][INFO    ][18169] Running state [salt_minion_dependency_packages] at time 04:10:16.874843
2019-05-27 04:10:16,875 [salt.state       :1813][INFO    ][18169] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-27 04:10:16,883 [salt.state       :300 ][INFO    ][18169] All specified packages are already installed
2019-05-27 04:10:16,884 [salt.state       :1951][INFO    ][18169] Completed state [salt_minion_dependency_packages] at time 04:10:16.884013 duration_in_ms=9.17
2019-05-27 04:10:16,887 [salt.state       :1780][INFO    ][18169] Running state [/etc/salt/minion.d/minion.conf] at time 04:10:16.887885
2019-05-27 04:10:16,888 [salt.state       :1813][INFO    ][18169] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-27 04:10:17,110 [salt.state       :300 ][INFO    ][18169] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-27 04:10:17,111 [salt.state       :1951][INFO    ][18169] Completed state [/etc/salt/minion.d/minion.conf] at time 04:10:17.111034 duration_in_ms=223.15
2019-05-27 04:10:17,111 [salt.state       :1780][INFO    ][18169] Running state [python-netaddr] at time 04:10:17.111264
2019-05-27 04:10:17,111 [salt.state       :1813][INFO    ][18169] Executing state pkg.installed for [python-netaddr]
2019-05-27 04:10:17,117 [salt.state       :300 ][INFO    ][18169] All specified packages are already installed
2019-05-27 04:10:17,117 [salt.state       :1951][INFO    ][18169] Completed state [python-netaddr] at time 04:10:17.117153 duration_in_ms=5.889
2019-05-27 04:10:17,119 [salt.state       :1780][INFO    ][18169] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 04:10:17.119700
2019-05-27 04:10:17,119 [salt.state       :1813][INFO    ][18169] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-27 04:10:17,129 [salt.state       :300 ][INFO    ][18169] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-27 04:10:17,129 [salt.state       :1951][INFO    ][18169] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 04:10:17.129631 duration_in_ms=9.931
2019-05-27 04:10:17,130 [salt.state       :1780][INFO    ][18169] Running state [salt-minion] at time 04:10:17.130414
2019-05-27 04:10:17,130 [salt.state       :1813][INFO    ][18169] Executing state service.running for [salt-minion]
2019-05-27 04:10:17,131 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-27 04:10:17,166 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-27 04:10:17,182 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-27 04:10:17,199 [salt.state       :300 ][INFO    ][18169] The service salt-minion is already running
2019-05-27 04:10:17,199 [salt.state       :1951][INFO    ][18169] Completed state [salt-minion] at time 04:10:17.199520 duration_in_ms=69.106
2019-05-27 04:10:17,200 [salt.state       :1780][INFO    ][18169] Running state [/etc/salt/grains.d] at time 04:10:17.200830
2019-05-27 04:10:17,201 [salt.state       :1813][INFO    ][18169] Executing state file.directory for [/etc/salt/grains.d]
2019-05-27 04:10:17,201 [salt.state       :300 ][INFO    ][18169] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-27 04:10:17,202 [salt.state       :1951][INFO    ][18169] Completed state [/etc/salt/grains.d] at time 04:10:17.202155 duration_in_ms=1.325
2019-05-27 04:10:17,202 [salt.state       :1780][INFO    ][18169] Running state [/etc/salt/grains] at time 04:10:17.202750
2019-05-27 04:10:17,203 [salt.state       :1813][INFO    ][18169] Executing state file.managed for [/etc/salt/grains]
2019-05-27 04:10:17,203 [salt.state       :300 ][INFO    ][18169] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-27 04:10:17,203 [salt.state       :1951][INFO    ][18169] Completed state [/etc/salt/grains] at time 04:10:17.203709 duration_in_ms=0.96
2019-05-27 04:10:17,204 [salt.state       :1780][INFO    ][18169] Running state [/etc/salt/grains.d/placeholder] at time 04:10:17.204104
2019-05-27 04:10:17,204 [salt.state       :1813][INFO    ][18169] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-27 04:10:17,204 [salt.state       :300 ][INFO    ][18169] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-27 04:10:17,205 [salt.state       :1951][INFO    ][18169] Completed state [/etc/salt/grains.d/placeholder] at time 04:10:17.205017 duration_in_ms=0.913
2019-05-27 04:10:17,205 [salt.state       :1780][INFO    ][18169] Running state [/etc/salt/grains.d/sphinx] at time 04:10:17.205410
2019-05-27 04:10:17,205 [salt.state       :1813][INFO    ][18169] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-27 04:10:17,224 [salt.state       :300 ][INFO    ][18169] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-27 04:10:17,224 [salt.state       :1951][INFO    ][18169] Completed state [/etc/salt/grains.d/sphinx] at time 04:10:17.224642 duration_in_ms=19.232
2019-05-27 04:10:17,226 [salt.state       :1780][INFO    ][18169] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 04:10:17.226575
2019-05-27 04:10:17,226 [salt.state       :1813][INFO    ][18169] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-27 04:10:17,227 [salt.state       :300 ][INFO    ][18169] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-27 04:10:17,227 [salt.state       :1951][INFO    ][18169] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 04:10:17.227351 duration_in_ms=0.776
2019-05-27 04:10:17,227 [salt.state       :1780][INFO    ][18169] Running state [/etc/salt/grains.d/dns_records] at time 04:10:17.227792
2019-05-27 04:10:17,228 [salt.state       :1813][INFO    ][18169] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-27 04:10:17,242 [salt.state       :300 ][INFO    ][18169] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-27 04:10:17,242 [salt.state       :1951][INFO    ][18169] Completed state [/etc/salt/grains.d/dns_records] at time 04:10:17.242633 duration_in_ms=14.841
2019-05-27 04:10:17,243 [salt.state       :1780][INFO    ][18169] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 04:10:17.243441
2019-05-27 04:10:17,243 [salt.state       :1813][INFO    ][18169] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-27 04:10:17,244 [salt.state       :300 ][INFO    ][18169] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-27 04:10:17,244 [salt.state       :1951][INFO    ][18169] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 04:10:17.244235 duration_in_ms=0.794
2019-05-27 04:10:17,244 [salt.state       :1780][INFO    ][18169] Running state [/etc/salt/grains.d/salt] at time 04:10:17.244670
2019-05-27 04:10:17,244 [salt.state       :1813][INFO    ][18169] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-27 04:10:17,266 [salt.state       :300 ][INFO    ][18169] File /etc/salt/grains.d/salt is in the correct state
2019-05-27 04:10:17,266 [salt.state       :1951][INFO    ][18169] Completed state [/etc/salt/grains.d/salt] at time 04:10:17.266555 duration_in_ms=21.885
2019-05-27 04:10:17,267 [salt.state       :1780][INFO    ][18169] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 04:10:17.267319
2019-05-27 04:10:17,267 [salt.state       :1813][INFO    ][18169] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-27 04:10:17,267 [salt.state       :300 ][INFO    ][18169] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-27 04:10:17,268 [salt.state       :1951][INFO    ][18169] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 04:10:17.268107 duration_in_ms=0.788
2019-05-27 04:10:17,269 [salt.state       :1780][INFO    ][18169] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 04:10:17.269739
2019-05-27 04:10:17,270 [salt.state       :1813][INFO    ][18169] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-27 04:10:17,270 [salt.state       :300 ][INFO    ][18169] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-27 04:10:17,270 [salt.state       :1951][INFO    ][18169] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 04:10:17.270561 duration_in_ms=0.822
2019-05-27 04:10:17,271 [salt.state       :1780][INFO    ][18169] Running state [mine.update] at time 04:10:17.271161
2019-05-27 04:10:17,271 [salt.state       :1813][INFO    ][18169] Executing state module.wait for [mine.update]
2019-05-27 04:10:17,271 [salt.state       :300 ][INFO    ][18169] No changes made for mine.update
2019-05-27 04:10:17,271 [salt.state       :1951][INFO    ][18169] Completed state [mine.update] at time 04:10:17.271901 duration_in_ms=0.74
2019-05-27 04:10:17,272 [salt.state       :1780][INFO    ][18169] Running state [ca-certificates] at time 04:10:17.272128
2019-05-27 04:10:17,272 [salt.state       :1813][INFO    ][18169] Executing state pkg.installed for [ca-certificates]
2019-05-27 04:10:17,278 [salt.state       :300 ][INFO    ][18169] All specified packages are already installed
2019-05-27 04:10:17,279 [salt.state       :1951][INFO    ][18169] Completed state [ca-certificates] at time 04:10:17.279001 duration_in_ms=6.873
2019-05-27 04:10:17,279 [salt.state       :1780][INFO    ][18169] Running state [update-ca-certificates] at time 04:10:17.279604
2019-05-27 04:10:17,279 [salt.state       :1813][INFO    ][18169] Executing state cmd.wait for [update-ca-certificates]
2019-05-27 04:10:17,280 [salt.state       :300 ][INFO    ][18169] No changes made for update-ca-certificates
2019-05-27 04:10:17,280 [salt.state       :1951][INFO    ][18169] Completed state [update-ca-certificates] at time 04:10:17.280344 duration_in_ms=0.74
2019-05-27 04:10:17,280 [salt.state       :1780][INFO    ][18169] Running state [iptables] at time 04:10:17.280574
2019-05-27 04:10:17,280 [salt.state       :1813][INFO    ][18169] Executing state pkg.installed for [iptables]
2019-05-27 04:10:17,286 [salt.state       :300 ][INFO    ][18169] All specified packages are already installed
2019-05-27 04:10:17,286 [salt.state       :1951][INFO    ][18169] Completed state [iptables] at time 04:10:17.286751 duration_in_ms=6.177
2019-05-27 04:10:17,287 [salt.state       :1780][INFO    ][18169] Running state [iptables-persistent] at time 04:10:17.286975
2019-05-27 04:10:17,287 [salt.state       :1813][INFO    ][18169] Executing state pkg.installed for [iptables-persistent]
2019-05-27 04:10:17,292 [salt.state       :300 ][INFO    ][18169] All specified packages are already installed
2019-05-27 04:10:17,293 [salt.state       :1951][INFO    ][18169] Completed state [iptables-persistent] at time 04:10:17.292977 duration_in_ms=6.002
2019-05-27 04:10:17,293 [salt.state       :1780][INFO    ][18169] Running state [iptables_modules_v4_load] at time 04:10:17.293842
2019-05-27 04:10:17,294 [salt.state       :1813][INFO    ][18169] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-27 04:10:17,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command 'lsmod' in directory '/root'
2019-05-27 04:10:17,316 [salt.state       :300 ][INFO    ][18169] Kernel modules iptable_filter, ip_tables are already present
2019-05-27 04:10:17,317 [salt.state       :1951][INFO    ][18169] Completed state [iptables_modules_v4_load] at time 04:10:17.317066 duration_in_ms=23.224
2019-05-27 04:10:17,317 [salt.state       :1780][INFO    ][18169] Running state [/etc/iptables/rules.v4] at time 04:10:17.317635
2019-05-27 04:10:17,317 [salt.state       :1813][INFO    ][18169] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-27 04:10:17,392 [salt.state       :300 ][INFO    ][18169] File /etc/iptables/rules.v4 is in the correct state
2019-05-27 04:10:17,392 [salt.state       :1951][INFO    ][18169] Completed state [/etc/iptables/rules.v4] at time 04:10:17.392353 duration_in_ms=74.718
2019-05-27 04:10:17,393 [salt.state       :1780][INFO    ][18169] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 04:10:17.393316
2019-05-27 04:10:17,393 [salt.state       :1813][INFO    ][18169] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-27 04:10:17,393 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-27 04:10:17,412 [salt.state       :300 ][INFO    ][18169] onlyif execution failed
2019-05-27 04:10:17,412 [salt.state       :1951][INFO    ][18169] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 04:10:17.412323 duration_in_ms=19.007
2019-05-27 04:10:17,413 [salt.state       :1780][INFO    ][18169] Running state [netfilter-persistent] at time 04:10:17.413026
2019-05-27 04:10:17,413 [salt.state       :1813][INFO    ][18169] Executing state service.running for [netfilter-persistent]
2019-05-27 04:10:17,413 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-27 04:10:17,434 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-27 04:10:17,453 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-27 04:10:17,470 [salt.state       :300 ][INFO    ][18169] The service netfilter-persistent is already running
2019-05-27 04:10:17,470 [salt.state       :1951][INFO    ][18169] Completed state [netfilter-persistent] at time 04:10:17.470802 duration_in_ms=57.775
2019-05-27 04:10:17,471 [salt.state       :1780][INFO    ][18169] Running state [iptables_extra.remove_stale_tables] at time 04:10:17.471551
2019-05-27 04:10:17,471 [salt.state       :1813][INFO    ][18169] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-27 04:10:17,472 [salt.state       :300 ][INFO    ][18169] No changes made for iptables_extra.remove_stale_tables
2019-05-27 04:10:17,472 [salt.state       :1951][INFO    ][18169] Completed state [iptables_extra.remove_stale_tables] at time 04:10:17.472389 duration_in_ms=0.838
2019-05-27 04:10:17,472 [salt.state       :1780][INFO    ][18169] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 04:10:17.472636
2019-05-27 04:10:17,472 [salt.state       :1813][INFO    ][18169] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-27 04:10:17,473 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18169] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-27 04:10:17,486 [salt.state       :300 ][INFO    ][18169] onlyif execution failed
2019-05-27 04:10:17,487 [salt.state       :1951][INFO    ][18169] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 04:10:17.486937 duration_in_ms=14.3
2019-05-27 04:10:17,488 [salt.state       :1780][INFO    ][18169] Running state [/etc/iptables/rules.v6] at time 04:10:17.488505
2019-05-27 04:10:17,489 [salt.state       :1813][INFO    ][18169] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-27 04:10:17,489 [salt.state       :300 ][INFO    ][18169] File /etc/iptables/rules.v6 is not present
2019-05-27 04:10:17,490 [salt.state       :1951][INFO    ][18169] Completed state [/etc/iptables/rules.v6] at time 04:10:17.490120 duration_in_ms=1.616
2019-05-27 04:10:17,491 [salt.state       :1780][INFO    ][18169] Running state [iptables_extra.flush_all] at time 04:10:17.491238
2019-05-27 04:10:17,491 [salt.state       :1813][INFO    ][18169] Executing state module.wait for [iptables_extra.flush_all]
2019-05-27 04:10:17,492 [salt.state       :300 ][INFO    ][18169] No changes made for iptables_extra.flush_all
2019-05-27 04:10:17,492 [salt.state       :1951][INFO    ][18169] Completed state [iptables_extra.flush_all] at time 04:10:17.492515 duration_in_ms=1.277
2019-05-27 04:10:17,497 [salt.minion      :1711][INFO    ][18169] Returning information for job: 20190527041009819371
2019-05-27 04:10:18,091 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527041018078603
2019-05-27 04:10:18,112 [salt.minion      :1432][INFO    ][18269] Starting a new job with PID 18269
2019-05-27 04:10:18,860 [salt.state       :915 ][INFO    ][18269] Loading fresh modules for state activity
2019-05-27 04:10:19,389 [salt.state       :1780][INFO    ][18269] Running state [maas-rack-controller] at time 04:10:19.389047
2019-05-27 04:10:19,389 [salt.state       :1813][INFO    ][18269] Executing state pkg.installed for [maas-rack-controller]
2019-05-27 04:10:19,389 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18269] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-27 04:10:19,495 [salt.state       :300 ][INFO    ][18269] All specified packages are already installed
2019-05-27 04:10:19,496 [salt.state       :1951][INFO    ][18269] Completed state [maas-rack-controller] at time 04:10:19.495998 duration_in_ms=106.951
2019-05-27 04:10:19,496 [salt.state       :1780][INFO    ][18269] Running state [ipmitool] at time 04:10:19.496404
2019-05-27 04:10:19,496 [salt.state       :1813][INFO    ][18269] Executing state pkg.installed for [ipmitool]
2019-05-27 04:10:19,504 [salt.state       :300 ][INFO    ][18269] All specified packages are already installed
2019-05-27 04:10:19,505 [salt.state       :1951][INFO    ][18269] Completed state [ipmitool] at time 04:10:19.505131 duration_in_ms=8.727
2019-05-27 04:10:19,508 [salt.state       :1780][INFO    ][18269] Running state [/etc/maas/rackd.conf] at time 04:10:19.508797
2019-05-27 04:10:19,509 [salt.state       :1813][INFO    ][18269] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-27 04:10:19,510 [salt.state       :300 ][INFO    ][18269] No changes needed to be made
2019-05-27 04:10:19,510 [salt.state       :1951][INFO    ][18269] Completed state [/etc/maas/rackd.conf] at time 04:10:19.510709 duration_in_ms=1.912
2019-05-27 04:10:19,511 [salt.state       :1780][INFO    ][18269] Running state [/etc/maas/rackd.conf] at time 04:10:19.510990
2019-05-27 04:10:19,511 [salt.state       :1813][INFO    ][18269] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-27 04:10:19,511 [salt.loaded.int.states.file:2298][WARNING ][18269] 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-05-27 04:10:19,512 [salt.state       :300 ][INFO    ][18269] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-27 04:10:19,512 [salt.state       :1951][INFO    ][18269] Completed state [/etc/maas/rackd.conf] at time 04:10:19.512521 duration_in_ms=1.531
2019-05-27 04:10:19,513 [salt.state       :1780][INFO    ][18269] Running state [maas-rackd] at time 04:10:19.513702
2019-05-27 04:10:19,514 [salt.state       :1813][INFO    ][18269] Executing state service.running for [maas-rackd]
2019-05-27 04:10:19,514 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18269] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-27 04:10:19,549 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18269] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-27 04:10:19,567 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18269] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-27 04:10:19,584 [salt.state       :300 ][INFO    ][18269] The service maas-rackd is already running
2019-05-27 04:10:19,584 [salt.state       :1951][INFO    ][18269] Completed state [maas-rackd] at time 04:10:19.584781 duration_in_ms=71.078
2019-05-27 04:10:19,586 [salt.minion      :1711][INFO    ][18269] Returning information for job: 20190527041018078603
2019-05-27 04:10:20,180 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527041020167601
2019-05-27 04:10:20,203 [salt.minion      :1432][INFO    ][18292] Starting a new job with PID 18292
2019-05-27 04:10:20,952 [salt.state       :915 ][INFO    ][18292] Loading fresh modules for state activity
2019-05-27 04:10:21,721 [salt.state       :1780][INFO    ][18292] Running state [maas-region-controller] at time 04:10:21.721305
2019-05-27 04:10:21,721 [salt.state       :1813][INFO    ][18292] Executing state pkg.installed for [maas-region-controller]
2019-05-27 04:10:21,722 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18292] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-27 04:10:21,789 [salt.state       :300 ][INFO    ][18292] All specified packages are already installed
2019-05-27 04:10:21,789 [salt.state       :1951][INFO    ][18292] Completed state [maas-region-controller] at time 04:10:21.789900 duration_in_ms=68.595
2019-05-27 04:10:21,790 [salt.state       :1780][INFO    ][18292] Running state [python-oauth] at time 04:10:21.790192
2019-05-27 04:10:21,790 [salt.state       :1813][INFO    ][18292] Executing state pkg.installed for [python-oauth]
2019-05-27 04:10:21,795 [salt.state       :300 ][INFO    ][18292] All specified packages are already installed
2019-05-27 04:10:21,795 [salt.state       :1951][INFO    ][18292] Completed state [python-oauth] at time 04:10:21.795804 duration_in_ms=5.612
2019-05-27 04:10:21,798 [salt.state       :1780][INFO    ][18292] Running state [/etc/maas/regiond.conf] at time 04:10:21.798317
2019-05-27 04:10:21,798 [salt.state       :1813][INFO    ][18292] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-27 04:10:21,829 [salt.state       :300 ][INFO    ][18292] No changes needed to be made
2019-05-27 04:10:21,829 [salt.state       :1951][INFO    ][18292] Completed state [/etc/maas/regiond.conf] at time 04:10:21.829859 duration_in_ms=31.541
2019-05-27 04:10:21,830 [salt.state       :1780][INFO    ][18292] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 04:10:21.830223
2019-05-27 04:10:21,830 [salt.state       :1813][INFO    ][18292] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-27 04:10:21,889 [salt.state       :300 ][INFO    ][18292] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-27 04:10:21,890 [salt.state       :1951][INFO    ][18292] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 04:10:21.889974 duration_in_ms=59.75
2019-05-27 04:10:21,890 [salt.state       :1780][INFO    ][18292] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 04:10:21.890721
2019-05-27 04:10:21,891 [salt.state       :1813][INFO    ][18292] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-27 04:10:21,913 [salt.state       :300 ][INFO    ][18292] No changes needed to be made
2019-05-27 04:10:21,914 [salt.state       :1951][INFO    ][18292] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 04:10:21.914045 duration_in_ms=23.323
2019-05-27 04:10:21,915 [salt.state       :1780][INFO    ][18292] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 04:10:21.915214
2019-05-27 04:10:21,915 [salt.state       :1813][INFO    ][18292] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-27 04:10:21,945 [salt.state       :300 ][INFO    ][18292] No changes needed to be made
2019-05-27 04:10:21,945 [salt.state       :1951][INFO    ][18292] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 04:10:21.945907 duration_in_ms=30.694
2019-05-27 04:10:21,946 [salt.state       :1780][INFO    ][18292] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 04:10:21.946565
2019-05-27 04:10:21,946 [salt.state       :1813][INFO    ][18292] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-27 04:10:21,980 [salt.state       :300 ][INFO    ][18292] No changes needed to be made
2019-05-27 04:10:21,981 [salt.state       :1951][INFO    ][18292] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 04:10:21.981230 duration_in_ms=34.664
2019-05-27 04:10:21,981 [salt.state       :1780][INFO    ][18292] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 04:10:21.981832
2019-05-27 04:10:21,982 [salt.state       :1813][INFO    ][18292] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-27 04:10:21,996 [salt.state       :300 ][INFO    ][18292] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-27 04:10:21,997 [salt.state       :1951][INFO    ][18292] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 04:10:21.997217 duration_in_ms=15.384
2019-05-27 04:10:21,999 [salt.state       :1780][INFO    ][18292] Running state [a2enmod headers] at time 04:10:21.999658
2019-05-27 04:10:22,000 [salt.state       :1813][INFO    ][18292] Executing state cmd.run for [a2enmod headers]
2019-05-27 04:10:22,000 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18292] Executing command 'a2enmod headers' in directory '/root'
2019-05-27 04:10:22,058 [salt.state       :300 ][INFO    ][18292] {'pid': 18314, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-27 04:10:22,059 [salt.state       :1951][INFO    ][18292] Completed state [a2enmod headers] at time 04:10:22.059253 duration_in_ms=59.595
2019-05-27 04:10:22,059 [salt.state       :1780][INFO    ][18292] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 04:10:22.059875
2019-05-27 04:10:22,060 [salt.state       :1813][INFO    ][18292] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-27 04:10:22,079 [salt.state       :300 ][INFO    ][18292] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-27 04:10:22,080 [salt.state       :1951][INFO    ][18292] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 04:10:22.080037 duration_in_ms=20.162
2019-05-27 04:10:22,081 [salt.state       :1780][INFO    ][18292] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 04:10:22.080928
2019-05-27 04:10:22,081 [salt.state       :1813][INFO    ][18292] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-27 04:10:22,183 [salt.state       :300 ][INFO    ][18292] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-27 04:10:22,183 [salt.state       :1951][INFO    ][18292] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 04:10:22.183868 duration_in_ms=102.94
2019-05-27 04:10:22,184 [salt.state       :1780][INFO    ][18292] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 04:10:22.184669
2019-05-27 04:10:22,185 [salt.state       :1813][INFO    ][18292] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-27 04:10:22,255 [salt.state       :300 ][INFO    ][18292] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-27 04:10:22,255 [salt.state       :1951][INFO    ][18292] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 04:10:22.255448 duration_in_ms=70.78
2019-05-27 04:10:22,256 [salt.state       :1780][INFO    ][18292] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 04:10:22.256083
2019-05-27 04:10:22,256 [salt.state       :1813][INFO    ][18292] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-27 04:10:22,326 [salt.state       :300 ][INFO    ][18292] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-27 04:10:22,327 [salt.state       :1951][INFO    ][18292] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 04:10:22.327201 duration_in_ms=71.118
2019-05-27 04:10:22,327 [salt.state       :1780][INFO    ][18292] Running state [/root/.pgpass] at time 04:10:22.327549
2019-05-27 04:10:22,327 [salt.state       :1813][INFO    ][18292] Executing state file.managed for [/root/.pgpass]
2019-05-27 04:10:22,387 [salt.state       :300 ][INFO    ][18292] File /root/.pgpass is in the correct state
2019-05-27 04:10:22,387 [salt.state       :1951][INFO    ][18292] Completed state [/root/.pgpass] at time 04:10:22.387361 duration_in_ms=59.811
2019-05-27 04:10:22,395 [salt.state       :1780][INFO    ][18292] Running state [maas-region syncdb --noinput] at time 04:10:22.395142
2019-05-27 04:10:22,395 [salt.state       :1813][INFO    ][18292] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-27 04:10:22,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18292] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-27 04:10:24,297 [salt.state       :300 ][INFO    ][18292] {'pid': 18327, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, auth, maasserver, metadataserver, contenttypes, sites, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-27 04:10:24,298 [salt.state       :1951][INFO    ][18292] Completed state [maas-region syncdb --noinput] at time 04:10:24.298521 duration_in_ms=1903.379
2019-05-27 04:10:24,299 [salt.state       :2022][WARNING ][18292] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-27 04:10:24,301 [salt.state       :1780][INFO    ][18292] Running state [maas-regiond] at time 04:10:24.301795
2019-05-27 04:10:24,302 [salt.state       :1813][INFO    ][18292] Executing state service.running for [maas-regiond]
2019-05-27 04:10:24,303 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18292] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-27 04:10:24,343 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18292] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-27 04:10:24,362 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18292] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-27 04:10:24,380 [salt.state       :300 ][INFO    ][18292] The service maas-regiond is already running
2019-05-27 04:10:24,380 [salt.state       :1951][INFO    ][18292] Completed state [maas-regiond] at time 04:10:24.380602 duration_in_ms=78.806
2019-05-27 04:10:24,382 [salt.state       :1780][INFO    ][18292] Running state [bind9] at time 04:10:24.382901
2019-05-27 04:10:24,383 [salt.state       :1813][INFO    ][18292] Executing state service.running for [bind9]
2019-05-27 04:10:24,384 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18292] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-27 04:10:24,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18292] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-27 04:10:24,419 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18292] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-27 04:10:24,436 [salt.state       :300 ][INFO    ][18292] The service bind9 is already running
2019-05-27 04:10:24,436 [salt.state       :1951][INFO    ][18292] Completed state [bind9] at time 04:10:24.436676 duration_in_ms=53.773
2019-05-27 04:10:24,438 [salt.state       :1780][INFO    ][18292] Running state [apache2] at time 04:10:24.438681
2019-05-27 04:10:24,439 [salt.state       :1813][INFO    ][18292] Executing state service.running for [apache2]
2019-05-27 04:10:24,439 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18292] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-27 04:10:24,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18292] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-27 04:10:24,474 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18292] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-27 04:10:24,494 [salt.state       :300 ][INFO    ][18292] The service apache2 is already running
2019-05-27 04:10:24,495 [salt.state       :1951][INFO    ][18292] Completed state [apache2] at time 04:10:24.495147 duration_in_ms=56.467
2019-05-27 04:10:24,496 [salt.state       :1780][INFO    ][18292] Running state [maasng.wait_for_http_code] at time 04:10:24.496716
2019-05-27 04:10:24,497 [salt.state       :1813][INFO    ][18292] Executing state module.run for [maasng.wait_for_http_code]
2019-05-27 04:10:24,497 [salt.utils.decorators:613 ][WARNING ][18292] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 04:10:24,633 [salt.state       :300 ][INFO    ][18292] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-27 04:10:24,634 [salt.state       :1951][INFO    ][18292] Completed state [maasng.wait_for_http_code] at time 04:10:24.634332 duration_in_ms=137.615
2019-05-27 04:10:24,635 [salt.state       :1780][INFO    ][18292] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 04:10:24.635574
2019-05-27 04:10:24,636 [salt.state       :1813][INFO    ][18292] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-27 04:10:24,636 [salt.state       :300 ][INFO    ][18292] /var/lib/maas/.setup_admin exists
2019-05-27 04:10:24,637 [salt.state       :1951][INFO    ][18292] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 04:10:24.637046 duration_in_ms=1.472
2019-05-27 04:10:24,638 [salt.state       :1780][INFO    ][18292] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:10:24.638121
2019-05-27 04:10:24,638 [salt.state       :1813][INFO    ][18292] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-27 04:10:24,639 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18292] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-27 04:10:26,137 [salt.state       :300 ][INFO    ][18292] {'pid': 18349, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-27 04:10:26,138 [salt.state       :1951][INFO    ][18292] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:10:26.138359 duration_in_ms=1500.238
2019-05-27 04:10:26,146 [salt.state       :1780][INFO    ][18292] Running state [maas_region_boot_source_resources_mirror] at time 04:10:26.146039
2019-05-27 04:10:26,146 [salt.state       :1813][INFO    ][18292] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-27 04:10:26,263 [salt.state       :300 ][INFO    ][18292] {'changes': {}}
2019-05-27 04:10:26,264 [salt.state       :1951][INFO    ][18292] Completed state [maas_region_boot_source_resources_mirror] at time 04:10:26.263996 duration_in_ms=117.957
2019-05-27 04:10:26,265 [salt.state       :1780][INFO    ][18292] Running state [maasng.boot_resources_import] at time 04:10:26.264995
2019-05-27 04:10:26,265 [salt.state       :1813][INFO    ][18292] Executing state module.run for [maasng.boot_resources_import]
2019-05-27 04:10:26,266 [salt.utils.decorators:613 ][WARNING ][18292] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 04:10:26,384 [salt.loaded.ext.module.maasng:1600][INFO    ][18292] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-27 04:10:31,444 [salt.loaded.ext.module.maasng:1600][INFO    ][18292] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-27 04:10:35,225 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command saltutil.find_job with jid 20190527041035212714
2019-05-27 04:10:35,248 [salt.minion      :1432][INFO    ][18376] Starting a new job with PID 18376
2019-05-27 04:10:35,275 [salt.minion      :1711][INFO    ][18376] Returning information for job: 20190527041035212714
2019-05-27 04:10:36,494 [salt.loaded.ext.module.maasng:1600][INFO    ][18292] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-05-27 04:10:41,619 [salt.state       :300 ][INFO    ][18292] {'ret': True}
2019-05-27 04:10:41,620 [salt.state       :1951][INFO    ][18292] Completed state [maasng.boot_resources_import] at time 04:10:41.620161 duration_in_ms=15355.164
2019-05-27 04:10:41,621 [salt.state       :1780][INFO    ][18292] Running state [maas_region_boot_sources_selection_xenial] at time 04:10:41.621379
2019-05-27 04:10:41,621 [salt.state       :1813][INFO    ][18292] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-27 04:10:41,823 [salt.state       :300 ][INFO    ][18292] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-27 04:10:41,823 [salt.state       :1951][INFO    ][18292] Completed state [maas_region_boot_sources_selection_xenial] at time 04:10:41.823569 duration_in_ms=202.19
2019-05-27 04:10:41,824 [salt.state       :1780][INFO    ][18292] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 04:10:41.824875
2019-05-27 04:10:41,825 [salt.state       :1813][INFO    ][18292] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-27 04:10:41,825 [salt.utils.decorators:613 ][WARNING ][18292] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 04:10:41,826 [salt.loaded.ext.module.maasng:1771][INFO    ][18292] boot-sources sync initiated for ALL Rack's
2019-05-27 04:10:42,816 [salt.state       :300 ][INFO    ][18292] {'ret': True}
2019-05-27 04:10:42,817 [salt.state       :1951][INFO    ][18292] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 04:10:42.817211 duration_in_ms=992.333
2019-05-27 04:10:42,819 [salt.state       :1780][INFO    ][18292] Running state [maas.process_maas_config] at time 04:10:42.819243
2019-05-27 04:10:42,819 [salt.state       :1813][INFO    ][18292] Executing state module.run for [maas.process_maas_config]
2019-05-27 04:10:42,820 [salt.utils.decorators:613 ][WARNING ][18292] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 04:10:42,821 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=enable_http_proxy value=True
2019-05-27 04:10:42,884 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=upstream_dns value=8.8.8.8
2019-05-27 04:10:42,948 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=commissioning_distro_series value=xenial
2019-05-27 04:10:46,019 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=default_osystem value=ubuntu
2019-05-27 04:10:46,080 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=active_discovery_interval value=600
2019-05-27 04:10:46,142 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=dnssec_validation value=no
2019-05-27 04:10:46,196 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=maas_name value=mas01
2019-05-27 04:10:46,256 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=network_discovery value=enabled
2019-05-27 04:10:46,397 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=enable_third_party_drivers value=True
2019-05-27 04:10:46,436 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=default_storage_layout value=lvm
2019-05-27 04:10:46,483 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=ntp_external_only value=True
2019-05-27 04:10:46,529 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-27 04:10:46,585 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=default_distro_series value=xenial
2019-05-27 04:10:46,650 [salt.loaded.ext.module.maas:92  ][INFO    ][18292] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-05-27 04:10:46,815 [salt.state       :300 ][INFO    ][18292] {'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-05-27 04:10:46,816 [salt.state       :1951][INFO    ][18292] Completed state [maas.process_maas_config] at time 04:10:46.815955 duration_in_ms=3996.712
2019-05-27 04:10:46,816 [salt.state       :1780][INFO    ][18292] Running state [pxe_admin] at time 04:10:46.816752
2019-05-27 04:10:46,817 [salt.state       :1813][INFO    ][18292] Executing state maasng.fabric_present for [pxe_admin]
2019-05-27 04:10:46,896 [salt.loaded.ext.module.maasng:945 ][INFO    ][18292] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'h6frag', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 04:10:46,998 [salt.loaded.ext.module.maasng:1008][WARNING ][18292] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-27 04:10:46,998 [salt.loaded.ext.module.maasng:1011][WARNING ][18292] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-27 04:10:47,070 [salt.state       :300 ][INFO    ][18292] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-27 04:10:47,071 [salt.state       :1951][INFO    ][18292] Completed state [pxe_admin] at time 04:10:47.071455 duration_in_ms=254.7
2019-05-27 04:10:47,072 [salt.state       :1780][INFO    ][18292] Running state [vlan 0] at time 04:10:47.072191
2019-05-27 04:10:47,072 [salt.state       :1813][INFO    ][18292] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-27 04:10:47,136 [salt.loaded.ext.module.maasng:945 ][INFO    ][18292] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', 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': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'h6frag', u'fabric': u'pxe_admin', 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': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 04:10:47,262 [salt.loaded.ext.module.maasng:945 ][INFO    ][18292] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'h6frag', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 04:10:47,542 [salt.loaded.ext.module.maasng:945 ][INFO    ][18292] [{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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'h6frag', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 04:10:47,633 [salt.state       :300 ][INFO    ][18292] {'new': 'Vlan untagged was updated'}
2019-05-27 04:10:47,633 [salt.state       :1951][INFO    ][18292] Completed state [vlan 0] at time 04:10:47.633438 duration_in_ms=561.247
2019-05-27 04:10:47,635 [salt.state       :1780][INFO    ][18292] Running state [192.168.11.0/24] at time 04:10:47.634971
2019-05-27 04:10:47,635 [salt.state       :1813][INFO    ][18292] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-27 04:10:47,824 [salt.loaded.ext.module.maasng:945 ][INFO    ][18292] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', 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': u'h6frag', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 04:10:47,825 [salt.loaded.ext.module.maasng:1235][WARNING ][18292] Ignoring parameter vlan:0
2019-05-27 04:10:47,897 [salt.state       :300 ][INFO    ][18292] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-27 04:10:47,897 [salt.state       :1951][INFO    ][18292] Completed state [192.168.11.0/24] at time 04:10:47.897571 duration_in_ms=262.598
2019-05-27 04:10:47,898 [salt.state       :1780][INFO    ][18292] Running state [maas_create_iprange_1] at time 04:10:47.898780
2019-05-27 04:10:47,899 [salt.state       :1813][INFO    ][18292] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-27 04:10:47,944 [salt.state       :300 ][INFO    ][18292] Iprange maas_create_iprange_1 already exist.
2019-05-27 04:10:47,945 [salt.state       :1951][INFO    ][18292] Completed state [maas_create_iprange_1] at time 04:10:47.944956 duration_in_ms=46.175
2019-05-27 04:10:47,945 [salt.state       :1780][INFO    ][18292] Running state [vlan 0] at time 04:10:47.945246
2019-05-27 04:10:47,945 [salt.state       :1813][INFO    ][18292] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-27 04:10:47,986 [salt.loaded.ext.module.maasng:945 ][INFO    ][18292] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', 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': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'h6frag', u'fabric': u'pxe_admin', 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': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 04:10:48,094 [salt.loaded.ext.module.maasng:945 ][INFO    ][18292] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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': u'h6frag', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 04:10:48,321 [salt.loaded.ext.module.maasng:945 ][INFO    ][18292] [{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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'h6frag', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-27 04:10:48,387 [salt.state       :300 ][INFO    ][18292] {'new': 'Vlan untagged was updated'}
2019-05-27 04:10:48,387 [salt.state       :1951][INFO    ][18292] Completed state [vlan 0] at time 04:10:48.387784 duration_in_ms=442.537
2019-05-27 04:10:48,388 [salt.state       :1780][INFO    ][18292] Running state [opnfv] at time 04:10:48.388410
2019-05-27 04:10:48,388 [salt.state       :1813][INFO    ][18292] Executing state maasng.sshkey_present for [opnfv]
2019-05-27 04:10:48,435 [salt.loaded.ext.module.maasng:1903][INFO    ][18292] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-05-27 04:10:48,436 [salt.state       :300 ][INFO    ][18292] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-05-27 04:10:48,436 [salt.state       :1951][INFO    ][18292] Completed state [opnfv] at time 04:10:48.436714 duration_in_ms=48.302
2019-05-27 04:10:48,441 [salt.minion      :1711][INFO    ][18292] Returning information for job: 20190527041020167601
2019-05-27 04:10:48,948 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527041048936220
2019-05-27 04:10:48,968 [salt.minion      :1432][INFO    ][18735] Starting a new job with PID 18735
2019-05-27 04:10:52,747 [salt.state       :915 ][INFO    ][18735] Loading fresh modules for state activity
2019-05-27 04:10:52,835 [salt.state       :1780][INFO    ][18735] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:10:52.835163
2019-05-27 04:10:52,835 [salt.state       :1813][INFO    ][18735] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-27 04:10:52,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18735] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-27 04:10:54,057 [salt.state       :300 ][INFO    ][18735] {'pid': 18758, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-27 04:10:54,057 [salt.state       :1951][INFO    ][18735] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:10:54.057892 duration_in_ms=1222.73
2019-05-27 04:10:54,059 [salt.state       :1780][INFO    ][18735] Running state [maas.process_machines] at time 04:10:54.058981
2019-05-27 04:10:54,059 [salt.state       :1813][INFO    ][18735] Executing state module.run for [maas.process_machines]
2019-05-27 04:10:54,059 [salt.utils.decorators:613 ][WARNING ][18735] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 04:10:54,627 [salt.loaded.ext.module.maas:412 ][WARNING ][18735] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 04:10:54,628 [salt.loaded.ext.module.maas:92  ][INFO    ][18735] machine hostname=cmp002 power_type=ipmi mac_addresses=['00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=4q6cfa architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 04:10:55,801 [salt.loaded.ext.module.maas:412 ][WARNING ][18735] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 04:10:55,802 [salt.loaded.ext.module.maas:92  ][INFO    ][18735] machine hostname=cmp001 power_type=ipmi mac_addresses=['00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=ecxxnr architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 04:10:57,044 [salt.loaded.ext.module.maas:412 ][WARNING ][18735] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 04:10:57,045 [salt.loaded.ext.module.maas:92  ][INFO    ][18735] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=xxnpae architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 04:10:58,378 [salt.loaded.ext.module.maas:412 ][WARNING ][18735] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 04:10:58,379 [salt.loaded.ext.module.maas:92  ][INFO    ][18735] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=ddttbg architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 04:10:59,652 [salt.loaded.ext.module.maas:412 ][WARNING ][18735] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-27 04:10:59,653 [salt.loaded.ext.module.maas:92  ][INFO    ][18735] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=yfnp3h architecture=amd64/generic power_parameters_power_user=admin
2019-05-27 04:11:00,732 [salt.state       :300 ][INFO    ][18735] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-27 04:11:00,732 [salt.state       :1951][INFO    ][18735] Completed state [maas.process_machines] at time 04:11:00.732615 duration_in_ms=6673.632
2019-05-27 04:11:00,736 [salt.minion      :1711][INFO    ][18735] Returning information for job: 20190527041048936220
2019-05-27 04:11:45,200 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527041145187410
2019-05-27 04:11:45,222 [salt.minion      :1432][INFO    ][19043] Starting a new job with PID 19043
2019-05-27 04:11:48,842 [salt.state       :915 ][INFO    ][19043] Loading fresh modules for state activity
2019-05-27 04:11:48,925 [salt.state       :1780][INFO    ][19043] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:11:48.925863
2019-05-27 04:11:48,926 [salt.state       :1813][INFO    ][19043] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-27 04:11:48,928 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19043] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-27 04:11:50,281 [salt.state       :300 ][INFO    ][19043] {'pid': 19052, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-27 04:11:50,282 [salt.state       :1951][INFO    ][19043] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:11:50.282197 duration_in_ms=1356.333
2019-05-27 04:11:50,284 [salt.state       :1780][INFO    ][19043] Running state [maas.wait_for_machine_status] at time 04:11:50.284663
2019-05-27 04:11:50,285 [salt.state       :1813][INFO    ][19043] Executing state module.run for [maas.wait_for_machine_status]
2019-05-27 04:11:50,285 [salt.utils.decorators:613 ][WARNING ][19043] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 04:11:53,197 [salt.state       :300 ][INFO    ][19043] {'ret': True}
2019-05-27 04:11:53,197 [salt.state       :1951][INFO    ][19043] Completed state [maas.wait_for_machine_status] at time 04:11:53.197665 duration_in_ms=2913.001
2019-05-27 04:11:53,201 [salt.minion      :1711][INFO    ][19043] Returning information for job: 20190527041145187410
2019-05-27 04:11:53,815 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527041153805585
2019-05-27 04:11:53,838 [salt.minion      :1432][INFO    ][19068] Starting a new job with PID 19068
2019-05-27 04:11:54,602 [salt.state       :915 ][INFO    ][19068] Loading fresh modules for state activity
2019-05-27 04:11:54,727 [salt.state       :1780][INFO    ][19068] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:11:54.727389
2019-05-27 04:11:54,727 [salt.state       :1813][INFO    ][19068] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-27 04:11:54,729 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19068] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-27 04:11:56,038 [salt.state       :300 ][INFO    ][19068] {'pid': 19075, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-27 04:11:56,039 [salt.state       :1951][INFO    ][19068] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:11:56.038983 duration_in_ms=1311.595
2019-05-27 04:11:56,040 [salt.state       :1780][INFO    ][19068] Running state [maas_machines_storage_cmp002_lvm] at time 04:11:56.040218
2019-05-27 04:11:56,040 [salt.state       :1813][INFO    ][19068] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-27 04:11:56,681 [salt.state       :300 ][INFO    ][19068] Machine cmp002 is not in Ready state.
2019-05-27 04:11:56,681 [salt.state       :1951][INFO    ][19068] Completed state [maas_machines_storage_cmp002_lvm] at time 04:11:56.681783 duration_in_ms=641.563
2019-05-27 04:11:56,682 [salt.state       :1780][INFO    ][19068] Running state [maas_machines_storage_cmp001_lvm] at time 04:11:56.682288
2019-05-27 04:11:56,682 [salt.state       :1813][INFO    ][19068] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-27 04:11:57,177 [salt.state       :300 ][INFO    ][19068] Machine cmp001 is not in Ready state.
2019-05-27 04:11:57,177 [salt.state       :1951][INFO    ][19068] Completed state [maas_machines_storage_cmp001_lvm] at time 04:11:57.177481 duration_in_ms=495.192
2019-05-27 04:11:57,181 [salt.minion      :1711][INFO    ][19068] Returning information for job: 20190527041153805585
2019-05-27 04:11:57,652 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527041157640018
2019-05-27 04:11:57,669 [salt.minion      :1432][INFO    ][19085] Starting a new job with PID 19085
2019-05-27 04:11:58,422 [salt.state       :915 ][INFO    ][19085] Loading fresh modules for state activity
2019-05-27 04:11:58,468 [salt.state       :1780][INFO    ][19085] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:11:58.468067
2019-05-27 04:11:58,468 [salt.state       :1813][INFO    ][19085] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-27 04:11:58,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19085] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-27 04:11:59,637 [salt.state       :300 ][INFO    ][19085] {'pid': 19092, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-27 04:11:59,638 [salt.state       :1951][INFO    ][19085] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:11:59.638035 duration_in_ms=1169.967
2019-05-27 04:11:59,639 [salt.state       :1780][INFO    ][19085] Running state [maas.deploy_machines] at time 04:11:59.639050
2019-05-27 04:11:59,639 [salt.state       :1813][INFO    ][19085] Executing state module.run for [maas.deploy_machines]
2019-05-27 04:11:59,639 [salt.utils.decorators:613 ][WARNING ][19085] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 04:12:00,183 [salt.state       :300 ][INFO    ][19085] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-27 04:12:00,183 [salt.state       :1951][INFO    ][19085] Completed state [maas.deploy_machines] at time 04:12:00.183766 duration_in_ms=544.714
2019-05-27 04:12:00,187 [salt.minion      :1711][INFO    ][19085] Returning information for job: 20190527041157640018
2019-05-27 04:12:00,843 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command state.apply with jid 20190527041200798766
2019-05-27 04:12:00,865 [salt.minion      :1432][INFO    ][19103] Starting a new job with PID 19103
2019-05-27 04:12:01,611 [salt.state       :915 ][INFO    ][19103] Loading fresh modules for state activity
2019-05-27 04:12:01,699 [salt.state       :1780][INFO    ][19103] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:12:01.699132
2019-05-27 04:12:01,699 [salt.state       :1813][INFO    ][19103] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-27 04:12:01,701 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19103] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-27 04:12:03,005 [salt.state       :300 ][INFO    ][19103] {'pid': 19111, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-27 04:12:03,006 [salt.state       :1951][INFO    ][19103] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:12:03.005948 duration_in_ms=1306.816
2019-05-27 04:12:03,008 [salt.state       :1780][INFO    ][19103] Running state [maas.wait_for_machine_status] at time 04:12:03.008307
2019-05-27 04:12:03,008 [salt.state       :1813][INFO    ][19103] Executing state module.run for [maas.wait_for_machine_status]
2019-05-27 04:12:03,009 [salt.utils.decorators:613 ][WARNING ][19103] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-27 04:12:06,426 [salt.state       :300 ][INFO    ][19103] {'ret': True}
2019-05-27 04:12:06,427 [salt.state       :1951][INFO    ][19103] Completed state [maas.wait_for_machine_status] at time 04:12:06.427118 duration_in_ms=3418.81
2019-05-27 04:12:06,431 [salt.minion      :1711][INFO    ][19103] Returning information for job: 20190527041200798766
2019-05-27 05:04:01,891 [salt.utils.schedule:1377][INFO    ][7022] Running scheduled job: __mine_interval
2019-05-27 05:44:15,854 [salt.minion      :1308][INFO    ][7022] User sudo_ubuntu Executing command cp.push_dir with jid 20190527054415842836
2019-05-27 05:44:15,878 [salt.minion      :1432][INFO    ][25579] Starting a new job with PID 25579
