2019-07-03 05:07:19,828 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-03 05:08:09,878 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-03 05:08:59,919 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-03 05:09:49,965 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-03 05:10:40,017 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-03 05:12:49,202 [salt.utils.decorators:613 ][WARNING ][2706] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 05:12:49,733 [salt.utils.decorators:613 ][WARNING ][2706] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 05:12:51,757 [salt.loaded.int.states.file:2298][WARNING ][2847] 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-07-03 05:12:57,417 [salt.state       :2022][WARNING ][2955] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-03 05:13:00,109 [salt.utils.decorators:613 ][WARNING ][2955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 05:28:09,505 [salt.utils.decorators:613 ][WARNING ][2955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 05:56:22,258 [salt.utils.decorators:613 ][WARNING ][2955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:11:26,101 [salt.utils.decorators:613 ][WARNING ][2955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:11:29,835 [salt.loaded.ext.module.maasng:1008][WARNING ][2955] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-07-03 06:11:29,835 [salt.loaded.ext.module.maasng:1011][WARNING ][2955] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-07-03 06:11:30,461 [salt.loaded.ext.module.maasng:1235][WARNING ][2955] Ignoring parameter vlan:0
2019-07-03 06:11:31,277 [salt.utils.decorators:613 ][WARNING ][2955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:11:37,291 [salt.utils.decorators:613 ][WARNING ][12069] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:11:37,358 [salt.loaded.ext.module.maas:412 ][WARNING ][12069] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:11:41,814 [salt.loaded.ext.module.maas:412 ][WARNING ][12069] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:11:46,211 [salt.loaded.ext.module.maas:412 ][WARNING ][12069] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:11:49,977 [salt.loaded.ext.module.maas:412 ][WARNING ][12069] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:11:53,929 [salt.loaded.ext.module.maas:412 ][WARNING ][12069] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:12:00,470 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13020] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-03 06:12:00,502 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13020] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-03 06:12:00,526 [salt.utils.parsers:1051][WARNING ][361] Minion received a SIGTERM. Exiting.
2019-07-03 06:12:01,579 [salt.cli.daemons :293 ][INFO    ][13086] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-07-03 06:12:01,671 [salt.cli.daemons :82  ][INFO    ][13086] Starting up the Salt Minion
2019-07-03 06:12:01,672 [salt.utils.event :1017][INFO    ][13086] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-07-03 06:12:02,579 [salt.minion      :976 ][INFO    ][13086] Creating minion process manager
2019-07-03 06:12:04,044 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13086] Executing command ['date', '+%z'] in directory '/root'
2019-07-03 06:12:04,064 [salt.utils.schedule:568 ][INFO    ][13086] Updating job settings for scheduled job: __mine_interval
2019-07-03 06:12:04,065 [salt.minion      :1108][INFO    ][13086] Added mine.update to scheduler
2019-07-03 06:12:04,069 [salt.minion      :1975][INFO    ][13086] Minion is starting as user 'root'
2019-07-03 06:12:04,083 [salt.minion      :2336][INFO    ][13086] Minion is ready to receive requests!
2019-07-03 06:12:29,472 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command state.apply with jid 20190703061229459087
2019-07-03 06:12:29,494 [salt.minion      :1432][INFO    ][13182] Starting a new job with PID 13182
2019-07-03 06:12:33,117 [salt.state       :915 ][INFO    ][13182] Loading fresh modules for state activity
2019-07-03 06:12:33,142 [salt.fileclient  :1219][INFO    ][13182] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-03 06:12:33,167 [salt.state       :1780][INFO    ][13182] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:12:33.167817
2019-07-03 06:12:33,168 [salt.state       :1813][INFO    ][13182] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-03 06:12:33,169 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13182] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-03 06:12:34,520 [salt.state       :300 ][INFO    ][13182] {'pid': 13190, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-03 06:12:34,521 [salt.state       :1951][INFO    ][13182] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:12:34.521342 duration_in_ms=1353.524
2019-07-03 06:12:34,524 [salt.state       :1780][INFO    ][13182] Running state [maas.wait_for_machine_status] at time 06:12:34.524532
2019-07-03 06:12:34,525 [salt.state       :1813][INFO    ][13182] Executing state module.run for [maas.wait_for_machine_status]
2019-07-03 06:12:34,525 [salt.utils.decorators:613 ][WARNING ][13182] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:12:35,229 [salt.loaded.ext.module.maas:1023][INFO    ][13182] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.30581903s left)
2019-07-03 06:12:44,518 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061244505357
2019-07-03 06:12:44,541 [salt.minion      :1432][INFO    ][13203] Starting a new job with PID 13203
2019-07-03 06:12:44,564 [salt.minion      :1711][INFO    ][13203] Returning information for job: 20190703061244505357
2019-07-03 06:13:06,058 [salt.loaded.ext.module.maas:1023][INFO    ][13182] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.47696114s left)
2019-07-03 06:13:14,576 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061314565469
2019-07-03 06:13:14,596 [salt.minion      :1432][INFO    ][13486] Starting a new job with PID 13486
2019-07-03 06:13:14,619 [salt.minion      :1711][INFO    ][13486] Returning information for job: 20190703061314565469
2019-07-03 06:13:44,618 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061344606221
2019-07-03 06:13:44,640 [salt.minion      :1432][INFO    ][13683] Starting a new job with PID 13683
2019-07-03 06:13:44,667 [salt.minion      :1711][INFO    ][13683] Returning information for job: 20190703061344606221
2019-07-03 06:14:14,691 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061414679356
2019-07-03 06:14:14,714 [salt.minion      :1432][INFO    ][13883] Starting a new job with PID 13883
2019-07-03 06:14:14,736 [salt.minion      :1711][INFO    ][13883] Returning information for job: 20190703061414679356
2019-07-03 06:14:44,754 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061444744813
2019-07-03 06:14:44,772 [salt.minion      :1432][INFO    ][13938] Starting a new job with PID 13938
2019-07-03 06:14:44,793 [salt.minion      :1711][INFO    ][13938] Returning information for job: 20190703061444744813
2019-07-03 06:15:14,819 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061514806235
2019-07-03 06:15:14,841 [salt.minion      :1432][INFO    ][14114] Starting a new job with PID 14114
2019-07-03 06:15:14,866 [salt.minion      :1711][INFO    ][14114] Returning information for job: 20190703061514806235
2019-07-03 06:15:44,902 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061544892929
2019-07-03 06:15:44,922 [salt.minion      :1432][INFO    ][14145] Starting a new job with PID 14145
2019-07-03 06:15:44,952 [salt.minion      :1711][INFO    ][14145] Returning information for job: 20190703061544892929
2019-07-03 06:16:14,990 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061614977582
2019-07-03 06:16:15,012 [salt.minion      :1432][INFO    ][14196] Starting a new job with PID 14196
2019-07-03 06:16:15,037 [salt.minion      :1711][INFO    ][14196] Returning information for job: 20190703061614977582
2019-07-03 06:16:45,077 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061645064624
2019-07-03 06:16:45,100 [salt.minion      :1432][INFO    ][14229] Starting a new job with PID 14229
2019-07-03 06:16:45,124 [salt.minion      :1711][INFO    ][14229] Returning information for job: 20190703061645064624
2019-07-03 06:17:15,172 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061715160915
2019-07-03 06:17:15,196 [salt.minion      :1432][INFO    ][14265] Starting a new job with PID 14265
2019-07-03 06:17:15,220 [salt.minion      :1711][INFO    ][14265] Returning information for job: 20190703061715160915
2019-07-03 06:17:45,271 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061745260082
2019-07-03 06:17:45,292 [salt.minion      :1432][INFO    ][14287] Starting a new job with PID 14287
2019-07-03 06:17:45,314 [salt.minion      :1711][INFO    ][14287] Returning information for job: 20190703061745260082
2019-07-03 06:18:15,368 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061815356196
2019-07-03 06:18:15,391 [salt.minion      :1432][INFO    ][14325] Starting a new job with PID 14325
2019-07-03 06:18:15,417 [salt.minion      :1711][INFO    ][14325] Returning information for job: 20190703061815356196
2019-07-03 06:18:45,484 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061845475143
2019-07-03 06:18:45,502 [salt.minion      :1432][INFO    ][14348] Starting a new job with PID 14348
2019-07-03 06:18:45,521 [salt.minion      :1711][INFO    ][14348] Returning information for job: 20190703061845475143
2019-07-03 06:19:04,282 [salt.state       :302 ][ERROR   ][13182] Module function maas.wait_for_machine_status threw an exception. Exception: HTTP Error 401: OK
2019-07-03 06:19:04,282 [salt.state       :1951][INFO    ][13182] Completed state [maas.wait_for_machine_status] at time 06:19:04.282832 duration_in_ms=389758.299
2019-07-03 06:19:04,287 [salt.minion      :1711][INFO    ][13182] Returning information for job: 20190703061229459087
2019-07-03 06:19:15,051 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command pillar.get with jid 20190703061915039698
2019-07-03 06:19:15,069 [salt.minion      :1432][INFO    ][14624] Starting a new job with PID 14624
2019-07-03 06:19:15,076 [salt.minion      :1711][INFO    ][14624] Returning information for job: 20190703061915039698
2019-07-03 06:19:15,596 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command service.status with jid 20190703061915589141
2019-07-03 06:19:15,613 [salt.minion      :1432][INFO    ][14630] Starting a new job with PID 14630
2019-07-03 06:19:16,022 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14630] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-07-03 06:19:16,057 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14630] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-07-03 06:19:16,077 [salt.minion      :1711][INFO    ][14630] Returning information for job: 20190703061915589141
2019-07-03 06:19:16,694 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command state.apply with jid 20190703061916681261
2019-07-03 06:19:16,717 [salt.minion      :1432][INFO    ][14643] Starting a new job with PID 14643
2019-07-03 06:19:20,437 [salt.state       :915 ][INFO    ][14643] Loading fresh modules for state activity
2019-07-03 06:19:20,820 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command 'salt-minion --version' in directory '/root'
2019-07-03 06:19:21,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command 'salt-minion --version' in directory '/root'
2019-07-03 06:19:22,021 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command 'salt-minion --version' in directory '/root'
2019-07-03 06:19:22,353 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command 'salt-minion --version' in directory '/root'
2019-07-03 06:19:23,835 [salt.state       :1780][INFO    ][14643] Running state [salt-minion] at time 06:19:23.834932
2019-07-03 06:19:23,835 [salt.state       :1813][INFO    ][14643] Executing state pkg.installed for [salt-minion]
2019-07-03 06:19:23,835 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-03 06:19:23,936 [salt.state       :300 ][INFO    ][14643] All specified packages are already installed
2019-07-03 06:19:23,937 [salt.state       :1951][INFO    ][14643] Completed state [salt-minion] at time 06:19:23.937220 duration_in_ms=102.289
2019-07-03 06:19:23,937 [salt.state       :1780][INFO    ][14643] Running state [salt_minion_dependency_packages] at time 06:19:23.937558
2019-07-03 06:19:23,937 [salt.state       :1813][INFO    ][14643] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-07-03 06:19:23,944 [salt.state       :300 ][INFO    ][14643] All specified packages are already installed
2019-07-03 06:19:23,944 [salt.state       :1951][INFO    ][14643] Completed state [salt_minion_dependency_packages] at time 06:19:23.944445 duration_in_ms=6.887
2019-07-03 06:19:23,947 [salt.state       :1780][INFO    ][14643] Running state [/etc/salt/minion.d/minion.conf] at time 06:19:23.947629
2019-07-03 06:19:23,947 [salt.state       :1813][INFO    ][14643] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-07-03 06:19:24,140 [salt.state       :300 ][INFO    ][14643] File /etc/salt/minion.d/minion.conf is in the correct state
2019-07-03 06:19:24,140 [salt.state       :1951][INFO    ][14643] Completed state [/etc/salt/minion.d/minion.conf] at time 06:19:24.140828 duration_in_ms=193.197
2019-07-03 06:19:24,141 [salt.state       :1780][INFO    ][14643] Running state [python-netaddr] at time 06:19:24.141239
2019-07-03 06:19:24,141 [salt.state       :1813][INFO    ][14643] Executing state pkg.installed for [python-netaddr]
2019-07-03 06:19:24,153 [salt.state       :300 ][INFO    ][14643] All specified packages are already installed
2019-07-03 06:19:24,153 [salt.state       :1951][INFO    ][14643] Completed state [python-netaddr] at time 06:19:24.153269 duration_in_ms=12.03
2019-07-03 06:19:24,158 [salt.state       :1780][INFO    ][14643] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:19:24.157967
2019-07-03 06:19:24,158 [salt.state       :1813][INFO    ][14643] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-07-03 06:19:24,171 [salt.state       :300 ][INFO    ][14643] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-07-03 06:19:24,172 [salt.state       :1951][INFO    ][14643] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:19:24.172102 duration_in_ms=14.134
2019-07-03 06:19:24,174 [salt.state       :1780][INFO    ][14643] Running state [salt-minion] at time 06:19:24.173969
2019-07-03 06:19:24,174 [salt.state       :1813][INFO    ][14643] Executing state service.running for [salt-minion]
2019-07-03 06:19:24,175 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-03 06:19:24,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-07-03 06:19:24,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-07-03 06:19:24,249 [salt.state       :300 ][INFO    ][14643] The service salt-minion is already running
2019-07-03 06:19:24,249 [salt.state       :1951][INFO    ][14643] Completed state [salt-minion] at time 06:19:24.249817 duration_in_ms=75.847
2019-07-03 06:19:24,251 [salt.state       :1780][INFO    ][14643] Running state [/etc/salt/grains.d] at time 06:19:24.251932
2019-07-03 06:19:24,252 [salt.state       :1813][INFO    ][14643] Executing state file.directory for [/etc/salt/grains.d]
2019-07-03 06:19:24,253 [salt.state       :300 ][INFO    ][14643] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-07-03 06:19:24,254 [salt.state       :1951][INFO    ][14643] Completed state [/etc/salt/grains.d] at time 06:19:24.253977 duration_in_ms=2.045
2019-07-03 06:19:24,254 [salt.state       :1780][INFO    ][14643] Running state [/etc/salt/grains] at time 06:19:24.254892
2019-07-03 06:19:24,255 [salt.state       :1813][INFO    ][14643] Executing state file.managed for [/etc/salt/grains]
2019-07-03 06:19:24,256 [salt.state       :300 ][INFO    ][14643] File /etc/salt/grains exists with proper permissions. No changes made.
2019-07-03 06:19:24,256 [salt.state       :1951][INFO    ][14643] Completed state [/etc/salt/grains] at time 06:19:24.256289 duration_in_ms=1.397
2019-07-03 06:19:24,256 [salt.state       :1780][INFO    ][14643] Running state [/etc/salt/grains.d/placeholder] at time 06:19:24.256918
2019-07-03 06:19:24,257 [salt.state       :1813][INFO    ][14643] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-07-03 06:19:24,257 [salt.state       :300 ][INFO    ][14643] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-07-03 06:19:24,258 [salt.state       :1951][INFO    ][14643] Completed state [/etc/salt/grains.d/placeholder] at time 06:19:24.258209 duration_in_ms=1.291
2019-07-03 06:19:24,258 [salt.state       :1780][INFO    ][14643] Running state [/etc/salt/grains.d/sphinx] at time 06:19:24.258797
2019-07-03 06:19:24,259 [salt.state       :1813][INFO    ][14643] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-07-03 06:19:24,277 [salt.state       :300 ][INFO    ][14643] File /etc/salt/grains.d/sphinx is in the correct state
2019-07-03 06:19:24,278 [salt.state       :1951][INFO    ][14643] Completed state [/etc/salt/grains.d/sphinx] at time 06:19:24.278018 duration_in_ms=19.221
2019-07-03 06:19:24,280 [salt.state       :1780][INFO    ][14643] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:19:24.280923
2019-07-03 06:19:24,281 [salt.state       :1813][INFO    ][14643] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-07-03 06:19:24,281 [salt.state       :300 ][INFO    ][14643] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-07-03 06:19:24,282 [salt.state       :1951][INFO    ][14643] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:19:24.282031 duration_in_ms=1.108
2019-07-03 06:19:24,282 [salt.state       :1780][INFO    ][14643] Running state [/etc/salt/grains.d/dns_records] at time 06:19:24.282646
2019-07-03 06:19:24,283 [salt.state       :1813][INFO    ][14643] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-07-03 06:19:24,295 [salt.state       :300 ][INFO    ][14643] File /etc/salt/grains.d/dns_records is in the correct state
2019-07-03 06:19:24,295 [salt.state       :1951][INFO    ][14643] Completed state [/etc/salt/grains.d/dns_records] at time 06:19:24.295794 duration_in_ms=13.147
2019-07-03 06:19:24,297 [salt.state       :1780][INFO    ][14643] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:19:24.296949
2019-07-03 06:19:24,297 [salt.state       :1813][INFO    ][14643] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-07-03 06:19:24,297 [salt.state       :300 ][INFO    ][14643] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-07-03 06:19:24,298 [salt.state       :1951][INFO    ][14643] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:19:24.298023 duration_in_ms=1.075
2019-07-03 06:19:24,298 [salt.state       :1780][INFO    ][14643] Running state [/etc/salt/grains.d/salt] at time 06:19:24.298620
2019-07-03 06:19:24,299 [salt.state       :1813][INFO    ][14643] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-07-03 06:19:24,307 [salt.state       :300 ][INFO    ][14643] File /etc/salt/grains.d/salt is in the correct state
2019-07-03 06:19:24,307 [salt.state       :1951][INFO    ][14643] Completed state [/etc/salt/grains.d/salt] at time 06:19:24.307831 duration_in_ms=9.21
2019-07-03 06:19:24,309 [salt.state       :1780][INFO    ][14643] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:19:24.308944
2019-07-03 06:19:24,309 [salt.state       :1813][INFO    ][14643] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-07-03 06:19:24,309 [salt.state       :300 ][INFO    ][14643] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-07-03 06:19:24,310 [salt.state       :1951][INFO    ][14643] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:19:24.310011 duration_in_ms=1.067
2019-07-03 06:19:24,312 [salt.state       :1780][INFO    ][14643] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:19:24.312450
2019-07-03 06:19:24,312 [salt.state       :1813][INFO    ][14643] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-07-03 06:19:24,313 [salt.state       :300 ][INFO    ][14643] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-07-03 06:19:24,313 [salt.state       :1951][INFO    ][14643] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:19:24.313538 duration_in_ms=1.088
2019-07-03 06:19:24,314 [salt.state       :1780][INFO    ][14643] Running state [mine.update] at time 06:19:24.314373
2019-07-03 06:19:24,314 [salt.state       :1813][INFO    ][14643] Executing state module.wait for [mine.update]
2019-07-03 06:19:24,315 [salt.state       :300 ][INFO    ][14643] No changes made for mine.update
2019-07-03 06:19:24,315 [salt.state       :1951][INFO    ][14643] Completed state [mine.update] at time 06:19:24.315346 duration_in_ms=0.973
2019-07-03 06:19:24,315 [salt.state       :1780][INFO    ][14643] Running state [ca-certificates] at time 06:19:24.315666
2019-07-03 06:19:24,316 [salt.state       :1813][INFO    ][14643] Executing state pkg.installed for [ca-certificates]
2019-07-03 06:19:24,325 [salt.state       :300 ][INFO    ][14643] All specified packages are already installed
2019-07-03 06:19:24,325 [salt.state       :1951][INFO    ][14643] Completed state [ca-certificates] at time 06:19:24.325648 duration_in_ms=9.983
2019-07-03 06:19:24,326 [salt.state       :1780][INFO    ][14643] Running state [update-ca-certificates] at time 06:19:24.326454
2019-07-03 06:19:24,326 [salt.state       :1813][INFO    ][14643] Executing state cmd.wait for [update-ca-certificates]
2019-07-03 06:19:24,327 [salt.state       :300 ][INFO    ][14643] No changes made for update-ca-certificates
2019-07-03 06:19:24,327 [salt.state       :1951][INFO    ][14643] Completed state [update-ca-certificates] at time 06:19:24.327404 duration_in_ms=0.949
2019-07-03 06:19:24,327 [salt.state       :1780][INFO    ][14643] Running state [iptables] at time 06:19:24.327708
2019-07-03 06:19:24,328 [salt.state       :1813][INFO    ][14643] Executing state pkg.installed for [iptables]
2019-07-03 06:19:24,336 [salt.state       :300 ][INFO    ][14643] All specified packages are already installed
2019-07-03 06:19:24,336 [salt.state       :1951][INFO    ][14643] Completed state [iptables] at time 06:19:24.336322 duration_in_ms=8.614
2019-07-03 06:19:24,336 [salt.state       :1780][INFO    ][14643] Running state [iptables-persistent] at time 06:19:24.336620
2019-07-03 06:19:24,336 [salt.state       :1813][INFO    ][14643] Executing state pkg.installed for [iptables-persistent]
2019-07-03 06:19:24,344 [salt.state       :300 ][INFO    ][14643] All specified packages are already installed
2019-07-03 06:19:24,344 [salt.state       :1951][INFO    ][14643] Completed state [iptables-persistent] at time 06:19:24.344927 duration_in_ms=8.307
2019-07-03 06:19:24,346 [salt.state       :1780][INFO    ][14643] Running state [iptables_modules_v4_load] at time 06:19:24.346063
2019-07-03 06:19:24,346 [salt.state       :1813][INFO    ][14643] Executing state kmod.present for [iptables_modules_v4_load]
2019-07-03 06:19:24,347 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command 'lsmod' in directory '/root'
2019-07-03 06:19:24,374 [salt.state       :300 ][INFO    ][14643] Kernel modules iptable_filter, ip_tables are already present
2019-07-03 06:19:24,374 [salt.state       :1951][INFO    ][14643] Completed state [iptables_modules_v4_load] at time 06:19:24.374332 duration_in_ms=28.269
2019-07-03 06:19:24,375 [salt.state       :1780][INFO    ][14643] Running state [/etc/iptables/rules.v4] at time 06:19:24.375020
2019-07-03 06:19:24,375 [salt.state       :1813][INFO    ][14643] Executing state file.managed for [/etc/iptables/rules.v4]
2019-07-03 06:19:24,475 [salt.state       :300 ][INFO    ][14643] File /etc/iptables/rules.v4 is in the correct state
2019-07-03 06:19:24,475 [salt.state       :1951][INFO    ][14643] Completed state [/etc/iptables/rules.v4] at time 06:19:24.475764 duration_in_ms=100.743
2019-07-03 06:19:24,476 [salt.state       :1780][INFO    ][14643] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:19:24.476886
2019-07-03 06:19:24,477 [salt.state       :1813][INFO    ][14643] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-07-03 06:19:24,477 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-07-03 06:19:24,498 [salt.state       :300 ][INFO    ][14643] onlyif execution failed
2019-07-03 06:19:24,498 [salt.state       :1951][INFO    ][14643] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:19:24.498536 duration_in_ms=21.65
2019-07-03 06:19:24,499 [salt.state       :1780][INFO    ][14643] Running state [netfilter-persistent] at time 06:19:24.499631
2019-07-03 06:19:24,500 [salt.state       :1813][INFO    ][14643] Executing state service.running for [netfilter-persistent]
2019-07-03 06:19:24,500 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-07-03 06:19:24,522 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-07-03 06:19:24,539 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-07-03 06:19:24,558 [salt.state       :300 ][INFO    ][14643] The service netfilter-persistent is already running
2019-07-03 06:19:24,559 [salt.state       :1951][INFO    ][14643] Completed state [netfilter-persistent] at time 06:19:24.559158 duration_in_ms=59.525
2019-07-03 06:19:24,560 [salt.state       :1780][INFO    ][14643] Running state [iptables_extra.remove_stale_tables] at time 06:19:24.560625
2019-07-03 06:19:24,561 [salt.state       :1813][INFO    ][14643] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-07-03 06:19:24,561 [salt.state       :300 ][INFO    ][14643] No changes made for iptables_extra.remove_stale_tables
2019-07-03 06:19:24,562 [salt.state       :1951][INFO    ][14643] Completed state [iptables_extra.remove_stale_tables] at time 06:19:24.562089 duration_in_ms=1.465
2019-07-03 06:19:24,562 [salt.state       :1780][INFO    ][14643] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:19:24.562491
2019-07-03 06:19:24,562 [salt.state       :1813][INFO    ][14643] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-07-03 06:19:24,563 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-07-03 06:19:24,581 [salt.state       :300 ][INFO    ][14643] onlyif execution failed
2019-07-03 06:19:24,582 [salt.state       :1951][INFO    ][14643] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:19:24.582011 duration_in_ms=19.532
2019-07-03 06:19:24,583 [salt.state       :1780][INFO    ][14643] Running state [/etc/iptables/rules.v6] at time 06:19:24.583516
2019-07-03 06:19:24,584 [salt.state       :1813][INFO    ][14643] Executing state file.absent for [/etc/iptables/rules.v6]
2019-07-03 06:19:24,584 [salt.state       :300 ][INFO    ][14643] File /etc/iptables/rules.v6 is not present
2019-07-03 06:19:24,585 [salt.state       :1951][INFO    ][14643] Completed state [/etc/iptables/rules.v6] at time 06:19:24.585033 duration_in_ms=1.517
2019-07-03 06:19:24,586 [salt.state       :1780][INFO    ][14643] Running state [iptables_extra.flush_all] at time 06:19:24.586066
2019-07-03 06:19:24,586 [salt.state       :1813][INFO    ][14643] Executing state module.wait for [iptables_extra.flush_all]
2019-07-03 06:19:24,587 [salt.state       :300 ][INFO    ][14643] No changes made for iptables_extra.flush_all
2019-07-03 06:19:24,587 [salt.state       :1951][INFO    ][14643] Completed state [iptables_extra.flush_all] at time 06:19:24.587285 duration_in_ms=1.219
2019-07-03 06:19:24,591 [salt.minion      :1711][INFO    ][14643] Returning information for job: 20190703061916681261
2019-07-03 06:19:25,164 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command state.apply with jid 20190703061925151637
2019-07-03 06:19:25,186 [salt.minion      :1432][INFO    ][14765] Starting a new job with PID 14765
2019-07-03 06:19:25,863 [salt.state       :915 ][INFO    ][14765] Loading fresh modules for state activity
2019-07-03 06:19:26,395 [salt.state       :1780][INFO    ][14765] Running state [maas-rack-controller] at time 06:19:26.395415
2019-07-03 06:19:26,395 [salt.state       :1813][INFO    ][14765] Executing state pkg.installed for [maas-rack-controller]
2019-07-03 06:19:26,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14765] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-03 06:19:26,464 [salt.state       :300 ][INFO    ][14765] All specified packages are already installed
2019-07-03 06:19:26,464 [salt.state       :1951][INFO    ][14765] Completed state [maas-rack-controller] at time 06:19:26.464764 duration_in_ms=69.348
2019-07-03 06:19:26,465 [salt.state       :1780][INFO    ][14765] Running state [ipmitool] at time 06:19:26.465015
2019-07-03 06:19:26,465 [salt.state       :1813][INFO    ][14765] Executing state pkg.installed for [ipmitool]
2019-07-03 06:19:26,470 [salt.state       :300 ][INFO    ][14765] All specified packages are already installed
2019-07-03 06:19:26,470 [salt.state       :1951][INFO    ][14765] Completed state [ipmitool] at time 06:19:26.470228 duration_in_ms=5.213
2019-07-03 06:19:26,472 [salt.state       :1780][INFO    ][14765] Running state [/etc/maas/rackd.conf] at time 06:19:26.472515
2019-07-03 06:19:26,472 [salt.state       :1813][INFO    ][14765] Executing state file.line for [/etc/maas/rackd.conf]
2019-07-03 06:19:26,473 [salt.state       :300 ][INFO    ][14765] No changes needed to be made
2019-07-03 06:19:26,473 [salt.state       :1951][INFO    ][14765] Completed state [/etc/maas/rackd.conf] at time 06:19:26.473661 duration_in_ms=1.146
2019-07-03 06:19:26,473 [salt.state       :1780][INFO    ][14765] Running state [/etc/maas/rackd.conf] at time 06:19:26.473833
2019-07-03 06:19:26,474 [salt.state       :1813][INFO    ][14765] Executing state file.managed for [/etc/maas/rackd.conf]
2019-07-03 06:19:26,474 [salt.loaded.int.states.file:2298][WARNING ][14765] 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-07-03 06:19:26,474 [salt.state       :300 ][INFO    ][14765] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-07-03 06:19:26,474 [salt.state       :1951][INFO    ][14765] Completed state [/etc/maas/rackd.conf] at time 06:19:26.474755 duration_in_ms=0.922
2019-07-03 06:19:26,475 [salt.state       :1780][INFO    ][14765] Running state [maas-rackd] at time 06:19:26.475461
2019-07-03 06:19:26,475 [salt.state       :1813][INFO    ][14765] Executing state service.running for [maas-rackd]
2019-07-03 06:19:26,476 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14765] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-07-03 06:19:26,506 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14765] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-07-03 06:19:26,521 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14765] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-07-03 06:19:26,536 [salt.state       :300 ][INFO    ][14765] The service maas-rackd is already running
2019-07-03 06:19:26,536 [salt.state       :1951][INFO    ][14765] Completed state [maas-rackd] at time 06:19:26.536855 duration_in_ms=61.393
2019-07-03 06:19:26,538 [salt.minion      :1711][INFO    ][14765] Returning information for job: 20190703061925151637
2019-07-03 06:19:27,034 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command state.apply with jid 20190703061927028329
2019-07-03 06:19:27,052 [salt.minion      :1432][INFO    ][14797] Starting a new job with PID 14797
2019-07-03 06:19:27,758 [salt.state       :915 ][INFO    ][14797] Loading fresh modules for state activity
2019-07-03 06:19:28,430 [salt.state       :1780][INFO    ][14797] Running state [maas-region-controller] at time 06:19:28.430327
2019-07-03 06:19:28,430 [salt.state       :1813][INFO    ][14797] Executing state pkg.installed for [maas-region-controller]
2019-07-03 06:19:28,431 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-03 06:19:28,522 [salt.state       :300 ][INFO    ][14797] All specified packages are already installed
2019-07-03 06:19:28,522 [salt.state       :1951][INFO    ][14797] Completed state [maas-region-controller] at time 06:19:28.522497 duration_in_ms=92.17
2019-07-03 06:19:28,522 [salt.state       :1780][INFO    ][14797] Running state [python-oauth] at time 06:19:28.522802
2019-07-03 06:19:28,523 [salt.state       :1813][INFO    ][14797] Executing state pkg.installed for [python-oauth]
2019-07-03 06:19:28,529 [salt.state       :300 ][INFO    ][14797] All specified packages are already installed
2019-07-03 06:19:28,529 [salt.state       :1951][INFO    ][14797] Completed state [python-oauth] at time 06:19:28.529249 duration_in_ms=6.447
2019-07-03 06:19:28,532 [salt.state       :1780][INFO    ][14797] Running state [/etc/maas/regiond.conf] at time 06:19:28.532021
2019-07-03 06:19:28,532 [salt.state       :1813][INFO    ][14797] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-03 06:19:28,575 [salt.state       :300 ][INFO    ][14797] No changes needed to be made
2019-07-03 06:19:28,576 [salt.state       :1951][INFO    ][14797] Completed state [/etc/maas/regiond.conf] at time 06:19:28.575973 duration_in_ms=43.952
2019-07-03 06:19:28,576 [salt.state       :1780][INFO    ][14797] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:19:28.576607
2019-07-03 06:19:28,577 [salt.state       :1813][INFO    ][14797] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-03 06:19:28,640 [salt.state       :300 ][INFO    ][14797] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-03 06:19:28,641 [salt.state       :1951][INFO    ][14797] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:19:28.640975 duration_in_ms=64.367
2019-07-03 06:19:28,641 [salt.state       :1780][INFO    ][14797] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:19:28.641516
2019-07-03 06:19:28,641 [salt.state       :1813][INFO    ][14797] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-03 06:19:28,660 [salt.state       :300 ][INFO    ][14797] No changes needed to be made
2019-07-03 06:19:28,660 [salt.state       :1951][INFO    ][14797] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:19:28.660249 duration_in_ms=18.733
2019-07-03 06:19:28,660 [salt.state       :1780][INFO    ][14797] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:19:28.660760
2019-07-03 06:19:28,661 [salt.state       :1813][INFO    ][14797] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-03 06:19:28,684 [salt.state       :300 ][INFO    ][14797] No changes needed to be made
2019-07-03 06:19:28,684 [salt.state       :1951][INFO    ][14797] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:19:28.684233 duration_in_ms=23.473
2019-07-03 06:19:28,684 [salt.state       :1780][INFO    ][14797] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:19:28.684746
2019-07-03 06:19:28,685 [salt.state       :1813][INFO    ][14797] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-03 06:19:28,707 [salt.state       :300 ][INFO    ][14797] No changes needed to be made
2019-07-03 06:19:28,707 [salt.state       :1951][INFO    ][14797] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:19:28.707501 duration_in_ms=22.755
2019-07-03 06:19:28,707 [salt.state       :1780][INFO    ][14797] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:19:28.707920
2019-07-03 06:19:28,708 [salt.state       :1813][INFO    ][14797] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-03 06:19:28,717 [salt.state       :300 ][INFO    ][14797] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-03 06:19:28,717 [salt.state       :1951][INFO    ][14797] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:19:28.717857 duration_in_ms=9.936
2019-07-03 06:19:28,719 [salt.state       :1780][INFO    ][14797] Running state [a2enmod headers] at time 06:19:28.718977
2019-07-03 06:19:28,719 [salt.state       :1813][INFO    ][14797] Executing state cmd.run for [a2enmod headers]
2019-07-03 06:19:28,719 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command 'a2enmod headers' in directory '/root'
2019-07-03 06:19:28,767 [salt.state       :300 ][INFO    ][14797] {'pid': 14823, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-03 06:19:28,768 [salt.state       :1951][INFO    ][14797] Completed state [a2enmod headers] at time 06:19:28.767940 duration_in_ms=48.962
2019-07-03 06:19:28,768 [salt.state       :1780][INFO    ][14797] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:19:28.768456
2019-07-03 06:19:28,768 [salt.state       :1813][INFO    ][14797] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-03 06:19:28,784 [salt.state       :300 ][INFO    ][14797] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-03 06:19:28,784 [salt.state       :1951][INFO    ][14797] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:19:28.784872 duration_in_ms=16.415
2019-07-03 06:19:28,785 [salt.state       :1780][INFO    ][14797] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:19:28.785666
2019-07-03 06:19:28,786 [salt.state       :1813][INFO    ][14797] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-03 06:19:28,862 [salt.state       :300 ][INFO    ][14797] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-03 06:19:28,863 [salt.state       :1951][INFO    ][14797] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:19:28.862944 duration_in_ms=77.277
2019-07-03 06:19:28,863 [salt.state       :1780][INFO    ][14797] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:19:28.863535
2019-07-03 06:19:28,863 [salt.state       :1813][INFO    ][14797] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-03 06:19:28,940 [salt.state       :300 ][INFO    ][14797] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-03 06:19:28,941 [salt.state       :1951][INFO    ][14797] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:19:28.941282 duration_in_ms=77.733
2019-07-03 06:19:28,942 [salt.state       :1780][INFO    ][14797] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:19:28.942084
2019-07-03 06:19:28,942 [salt.state       :1813][INFO    ][14797] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-03 06:19:29,006 [salt.state       :300 ][INFO    ][14797] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-03 06:19:29,006 [salt.state       :1951][INFO    ][14797] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:19:29.006221 duration_in_ms=64.137
2019-07-03 06:19:29,006 [salt.state       :1780][INFO    ][14797] Running state [/root/.pgpass] at time 06:19:29.006462
2019-07-03 06:19:29,006 [salt.state       :1813][INFO    ][14797] Executing state file.managed for [/root/.pgpass]
2019-07-03 06:19:29,048 [salt.state       :300 ][INFO    ][14797] File /root/.pgpass is in the correct state
2019-07-03 06:19:29,048 [salt.state       :1951][INFO    ][14797] Completed state [/root/.pgpass] at time 06:19:29.048208 duration_in_ms=41.746
2019-07-03 06:19:29,052 [salt.state       :1780][INFO    ][14797] Running state [maas-region syncdb --noinput] at time 06:19:29.052201
2019-07-03 06:19:29,052 [salt.state       :1813][INFO    ][14797] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-03 06:19:29,053 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-03 06:19:30,780 [salt.state       :300 ][INFO    ][14797] {'pid': 14838, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, auth, sites, contenttypes, piston3, 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-07-03 06:19:30,780 [salt.state       :1951][INFO    ][14797] Completed state [maas-region syncdb --noinput] at time 06:19:30.780350 duration_in_ms=1728.149
2019-07-03 06:19:30,780 [salt.state       :2022][WARNING ][14797] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-03 06:19:30,781 [salt.state       :1780][INFO    ][14797] Running state [maas-regiond] at time 06:19:30.781545
2019-07-03 06:19:30,781 [salt.state       :1813][INFO    ][14797] Executing state service.running for [maas-regiond]
2019-07-03 06:19:30,782 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-03 06:19:30,811 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-03 06:19:30,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-03 06:19:30,839 [salt.state       :300 ][INFO    ][14797] The service maas-regiond is already running
2019-07-03 06:19:30,840 [salt.state       :1951][INFO    ][14797] Completed state [maas-regiond] at time 06:19:30.840107 duration_in_ms=58.562
2019-07-03 06:19:30,841 [salt.state       :1780][INFO    ][14797] Running state [bind9] at time 06:19:30.841451
2019-07-03 06:19:30,841 [salt.state       :1813][INFO    ][14797] Executing state service.running for [bind9]
2019-07-03 06:19:30,842 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-03 06:19:30,854 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-03 06:19:30,865 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-03 06:19:30,877 [salt.state       :300 ][INFO    ][14797] The service bind9 is already running
2019-07-03 06:19:30,878 [salt.state       :1951][INFO    ][14797] Completed state [bind9] at time 06:19:30.878169 duration_in_ms=36.718
2019-07-03 06:19:30,879 [salt.state       :1780][INFO    ][14797] Running state [apache2] at time 06:19:30.879203
2019-07-03 06:19:30,879 [salt.state       :1813][INFO    ][14797] Executing state service.running for [apache2]
2019-07-03 06:19:30,879 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-03 06:19:30,891 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-03 06:19:30,905 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-03 06:19:30,922 [salt.state       :300 ][INFO    ][14797] The service apache2 is already running
2019-07-03 06:19:30,922 [salt.state       :1951][INFO    ][14797] Completed state [apache2] at time 06:19:30.922318 duration_in_ms=43.115
2019-07-03 06:19:30,923 [salt.state       :1780][INFO    ][14797] Running state [maasng.wait_for_http_code] at time 06:19:30.923247
2019-07-03 06:19:30,923 [salt.state       :1813][INFO    ][14797] Executing state module.run for [maasng.wait_for_http_code]
2019-07-03 06:19:30,923 [salt.utils.decorators:613 ][WARNING ][14797] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:19:31,022 [salt.state       :300 ][INFO    ][14797] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-03 06:19:31,023 [salt.state       :1951][INFO    ][14797] Completed state [maasng.wait_for_http_code] at time 06:19:31.023210 duration_in_ms=99.96
2019-07-03 06:19:31,025 [salt.state       :1780][INFO    ][14797] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:19:31.025188
2019-07-03 06:19:31,025 [salt.state       :1813][INFO    ][14797] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-03 06:19:31,026 [salt.state       :300 ][INFO    ][14797] /var/lib/maas/.setup_admin exists
2019-07-03 06:19:31,027 [salt.state       :1951][INFO    ][14797] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:19:31.026934 duration_in_ms=1.747
2019-07-03 06:19:31,028 [salt.state       :1780][INFO    ][14797] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:19:31.028068
2019-07-03 06:19:31,028 [salt.state       :1813][INFO    ][14797] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-03 06:19:31,029 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-03 06:19:32,376 [salt.state       :300 ][INFO    ][14797] {'pid': 14868, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-03 06:19:32,377 [salt.state       :1951][INFO    ][14797] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:19:32.377295 duration_in_ms=1349.227
2019-07-03 06:19:32,385 [salt.state       :1780][INFO    ][14797] Running state [maas_region_boot_source_resources_mirror] at time 06:19:32.385704
2019-07-03 06:19:32,386 [salt.state       :1813][INFO    ][14797] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-03 06:19:32,501 [salt.state       :300 ][INFO    ][14797] {'changes': {}}
2019-07-03 06:19:32,501 [salt.state       :1951][INFO    ][14797] Completed state [maas_region_boot_source_resources_mirror] at time 06:19:32.501874 duration_in_ms=116.169
2019-07-03 06:19:32,503 [salt.state       :1780][INFO    ][14797] Running state [maasng.boot_resources_import] at time 06:19:32.503235
2019-07-03 06:19:32,503 [salt.state       :1813][INFO    ][14797] Executing state module.run for [maasng.boot_resources_import]
2019-07-03 06:19:32,504 [salt.utils.decorators:613 ][WARNING ][14797] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:19:32,615 [salt.loaded.ext.module.maasng:1600][INFO    ][14797] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-07-03 06:19:37,677 [salt.loaded.ext.module.maasng:1600][INFO    ][14797] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-07-03 06:19:42,141 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703061942129059
2019-07-03 06:19:42,164 [salt.minion      :1432][INFO    ][14930] Starting a new job with PID 14930
2019-07-03 06:19:42,189 [salt.minion      :1711][INFO    ][14930] Returning information for job: 20190703061942129059
2019-07-03 06:19:42,900 [salt.loaded.ext.module.maasng:1600][INFO    ][14797] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-07-03 06:19:48,004 [salt.state       :300 ][INFO    ][14797] {'ret': True}
2019-07-03 06:19:48,005 [salt.state       :1951][INFO    ][14797] Completed state [maasng.boot_resources_import] at time 06:19:48.005189 duration_in_ms=15501.954
2019-07-03 06:19:48,006 [salt.state       :1780][INFO    ][14797] Running state [maas_region_boot_sources_selection_xenial] at time 06:19:48.006387
2019-07-03 06:19:48,006 [salt.state       :1813][INFO    ][14797] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-03 06:19:48,208 [salt.state       :300 ][INFO    ][14797] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-03 06:19:48,209 [salt.state       :1951][INFO    ][14797] Completed state [maas_region_boot_sources_selection_xenial] at time 06:19:48.209218 duration_in_ms=202.831
2019-07-03 06:19:48,210 [salt.state       :1780][INFO    ][14797] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:19:48.210563
2019-07-03 06:19:48,211 [salt.state       :1813][INFO    ][14797] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-03 06:19:48,211 [salt.utils.decorators:613 ][WARNING ][14797] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:19:48,212 [salt.loaded.ext.module.maasng:1771][INFO    ][14797] boot-sources sync initiated for ALL Rack's
2019-07-03 06:19:49,201 [salt.state       :300 ][INFO    ][14797] {'ret': True}
2019-07-03 06:19:49,202 [salt.state       :1951][INFO    ][14797] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:19:49.202346 duration_in_ms=991.782
2019-07-03 06:19:49,204 [salt.state       :1780][INFO    ][14797] Running state [maas.process_maas_config] at time 06:19:49.204517
2019-07-03 06:19:49,205 [salt.state       :1813][INFO    ][14797] Executing state module.run for [maas.process_maas_config]
2019-07-03 06:19:49,205 [salt.utils.decorators:613 ][WARNING ][14797] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:19:49,206 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=enable_http_proxy value=True
2019-07-03 06:19:49,257 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=upstream_dns value=8.8.8.8
2019-07-03 06:19:49,322 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=commissioning_distro_series value=xenial
2019-07-03 06:19:49,394 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=default_osystem value=ubuntu
2019-07-03 06:19:49,460 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=active_discovery_interval value=600
2019-07-03 06:19:49,513 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=dnssec_validation value=no
2019-07-03 06:19:52,411 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=maas_name value=mas01
2019-07-03 06:19:52,466 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=network_discovery value=enabled
2019-07-03 06:19:52,612 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=enable_third_party_drivers value=True
2019-07-03 06:19:52,672 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=default_storage_layout value=lvm
2019-07-03 06:19:52,727 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=ntp_external_only value=True
2019-07-03 06:19:52,789 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-03 06:19:52,840 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=default_distro_series value=xenial
2019-07-03 06:19:52,909 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-07-03 06:19:53,062 [salt.state       :300 ][INFO    ][14797] {'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-07-03 06:19:53,062 [salt.state       :1951][INFO    ][14797] Completed state [maas.process_maas_config] at time 06:19:53.062787 duration_in_ms=3858.27
2019-07-03 06:19:53,063 [salt.state       :1780][INFO    ][14797] Running state [pxe_admin] at time 06:19:53.063635
2019-07-03 06:19:53,064 [salt.state       :1813][INFO    ][14797] Executing state maasng.fabric_present for [pxe_admin]
2019-07-03 06:19:53,130 [salt.loaded.ext.module.maasng:945 ][INFO    ][14797] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', 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'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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'sg7qse', 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-07-03 06:19:53,209 [salt.loaded.ext.module.maasng:1008][WARNING ][14797] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-03 06:19:53,209 [salt.loaded.ext.module.maasng:1011][WARNING ][14797] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-03 06:19:53,287 [salt.state       :300 ][INFO    ][14797] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-03 06:19:53,288 [salt.state       :1951][INFO    ][14797] Completed state [pxe_admin] at time 06:19:53.287942 duration_in_ms=224.306
2019-07-03 06:19:53,288 [salt.state       :1780][INFO    ][14797] Running state [vlan 0] at time 06:19:53.288463
2019-07-03 06:19:53,289 [salt.state       :1813][INFO    ][14797] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-03 06:19:53,352 [salt.loaded.ext.module.maasng:945 ][INFO    ][14797] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', 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'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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'sg7qse', 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-07-03 06:19:53,453 [salt.loaded.ext.module.maasng:945 ][INFO    ][14797] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, 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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sg7qse', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-07-03 06:19:53,712 [salt.loaded.ext.module.maasng:945 ][INFO    ][14797] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'sg7qse', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-07-03 06:19:53,796 [salt.state       :300 ][INFO    ][14797] {'new': 'Vlan untagged was updated'}
2019-07-03 06:19:53,797 [salt.state       :1951][INFO    ][14797] Completed state [vlan 0] at time 06:19:53.797060 duration_in_ms=508.595
2019-07-03 06:19:53,799 [salt.state       :1780][INFO    ][14797] Running state [192.168.11.0/24] at time 06:19:53.799250
2019-07-03 06:19:53,799 [salt.state       :1813][INFO    ][14797] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-03 06:19:54,005 [salt.loaded.ext.module.maasng:945 ][INFO    ][14797] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', 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'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'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'sg7qse', 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-07-03 06:19:54,006 [salt.loaded.ext.module.maasng:1235][WARNING ][14797] Ignoring parameter vlan:0
2019-07-03 06:19:54,102 [salt.state       :300 ][INFO    ][14797] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-03 06:19:54,102 [salt.state       :1951][INFO    ][14797] Completed state [192.168.11.0/24] at time 06:19:54.102718 duration_in_ms=303.468
2019-07-03 06:19:54,103 [salt.state       :1780][INFO    ][14797] Running state [maas_create_iprange_1] at time 06:19:54.103877
2019-07-03 06:19:54,104 [salt.state       :1813][INFO    ][14797] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-03 06:19:54,164 [salt.state       :300 ][INFO    ][14797] Iprange maas_create_iprange_1 already exist.
2019-07-03 06:19:54,164 [salt.state       :1951][INFO    ][14797] Completed state [maas_create_iprange_1] at time 06:19:54.164495 duration_in_ms=60.599
2019-07-03 06:19:54,164 [salt.state       :1780][INFO    ][14797] Running state [vlan 0] at time 06:19:54.164920
2019-07-03 06:19:54,165 [salt.state       :1813][INFO    ][14797] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-03 06:19:54,236 [salt.loaded.ext.module.maasng:945 ][INFO    ][14797] [{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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sg7qse', u'mtu': 1500, 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-07-03 06:19:54,338 [salt.loaded.ext.module.maasng:945 ][INFO    ][14797] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'sg7qse', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-07-03 06:19:54,578 [salt.loaded.ext.module.maasng:945 ][INFO    ][14797] [{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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sg7qse', u'mtu': 1500, 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-07-03 06:19:54,668 [salt.state       :300 ][INFO    ][14797] {'new': 'Vlan untagged was updated'}
2019-07-03 06:19:54,668 [salt.state       :1951][INFO    ][14797] Completed state [vlan 0] at time 06:19:54.668619 duration_in_ms=503.698
2019-07-03 06:19:54,669 [salt.state       :1780][INFO    ][14797] Running state [opnfv] at time 06:19:54.669514
2019-07-03 06:19:54,670 [salt.state       :1813][INFO    ][14797] Executing state maasng.sshkey_present for [opnfv]
2019-07-03 06:19:54,718 [salt.loaded.ext.module.maasng:1903][INFO    ][14797] [{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-07-03 06:19:54,718 [salt.state       :300 ][INFO    ][14797] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-07-03 06:19:54,719 [salt.state       :1951][INFO    ][14797] Completed state [opnfv] at time 06:19:54.719069 duration_in_ms=49.555
2019-07-03 06:19:54,720 [salt.state       :1780][INFO    ][14797] Running state [maas.process_tags] at time 06:19:54.720002
2019-07-03 06:19:54,720 [salt.state       :1813][INFO    ][14797] Executing state module.run for [maas.process_tags]
2019-07-03 06:19:54,721 [salt.utils.decorators:613 ][WARNING ][14797] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:19:54,766 [salt.loaded.ext.module.maas:92  ][INFO    ][14797] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-07-03 06:19:54,833 [salt.state       :300 ][INFO    ][14797] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-07-03 06:19:54,833 [salt.state       :1951][INFO    ][14797] Completed state [maas.process_tags] at time 06:19:54.833792 duration_in_ms=113.789
2019-07-03 06:19:54,838 [salt.minion      :1711][INFO    ][14797] Returning information for job: 20190703061927028329
2019-07-03 06:19:55,414 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command state.apply with jid 20190703061955401363
2019-07-03 06:19:55,434 [salt.minion      :1432][INFO    ][15315] Starting a new job with PID 15315
2019-07-03 06:19:59,016 [salt.state       :915 ][INFO    ][15315] Loading fresh modules for state activity
2019-07-03 06:19:59,093 [salt.state       :1780][INFO    ][15315] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:19:59.093738
2019-07-03 06:19:59,094 [salt.state       :1813][INFO    ][15315] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-03 06:19:59,095 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15315] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-03 06:20:00,393 [salt.state       :300 ][INFO    ][15315] {'pid': 15338, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-03 06:20:00,393 [salt.state       :1951][INFO    ][15315] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:00.393662 duration_in_ms=1299.925
2019-07-03 06:20:00,394 [salt.state       :1780][INFO    ][15315] Running state [maas.process_machines] at time 06:20:00.394786
2019-07-03 06:20:00,395 [salt.state       :1813][INFO    ][15315] Executing state module.run for [maas.process_machines]
2019-07-03 06:20:00,395 [salt.utils.decorators:613 ][WARNING ][15315] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:20:00,613 [salt.loaded.ext.module.maas:412 ][WARNING ][15315] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:20:00,613 [salt.loaded.ext.module.maas:92  ][INFO    ][15315] 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=qbyc8w architecture=amd64/generic power_parameters_power_user=admin
2019-07-03 06:20:01,228 [salt.loaded.ext.module.maas:412 ][WARNING ][15315] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:20:01,229 [salt.loaded.ext.module.maas:92  ][INFO    ][15315] 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=fec4tc architecture=amd64/generic power_parameters_power_user=admin
2019-07-03 06:20:01,981 [salt.loaded.ext.module.maas:412 ][WARNING ][15315] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:20:01,983 [salt.loaded.ext.module.maas:92  ][INFO    ][15315] 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=d4fc33 architecture=amd64/generic power_parameters_power_user=admin
2019-07-03 06:20:02,977 [salt.loaded.ext.module.maas:412 ][WARNING ][15315] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:20:02,978 [salt.loaded.ext.module.maas:92  ][INFO    ][15315] 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=6kkymc architecture=amd64/generic power_parameters_power_user=admin
2019-07-03 06:20:03,874 [salt.loaded.ext.module.maas:412 ][WARNING ][15315] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:20:03,875 [salt.loaded.ext.module.maas:92  ][INFO    ][15315] 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=nfxrt8 architecture=amd64/generic power_parameters_power_user=admin
2019-07-03 06:20:04,955 [salt.state       :300 ][INFO    ][15315] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-07-03 06:20:04,955 [salt.state       :1951][INFO    ][15315] Completed state [maas.process_machines] at time 06:20:04.955765 duration_in_ms=4560.977
2019-07-03 06:20:04,959 [salt.minion      :1711][INFO    ][15315] Returning information for job: 20190703061955401363
2019-07-03 06:20:35,958 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command state.apply with jid 20190703062035951094
2019-07-03 06:20:35,975 [salt.minion      :1432][INFO    ][15931] Starting a new job with PID 15931
2019-07-03 06:20:39,578 [salt.state       :915 ][INFO    ][15931] Loading fresh modules for state activity
2019-07-03 06:20:39,671 [salt.state       :1780][INFO    ][15931] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:39.671024
2019-07-03 06:20:39,671 [salt.state       :1813][INFO    ][15931] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-03 06:20:39,673 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15931] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-03 06:20:41,132 [salt.state       :300 ][INFO    ][15931] {'pid': 15995, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-03 06:20:41,133 [salt.state       :1951][INFO    ][15931] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:41.133609 duration_in_ms=1462.585
2019-07-03 06:20:41,137 [salt.state       :1780][INFO    ][15931] Running state [maas.wait_for_machine_status] at time 06:20:41.136994
2019-07-03 06:20:41,137 [salt.state       :1813][INFO    ][15931] Executing state module.run for [maas.wait_for_machine_status]
2019-07-03 06:20:41,138 [salt.utils.decorators:613 ][WARNING ][15931] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:20:44,282 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Ready|Deployed for machines:['cmp001']
sleep for:30s Timeout:1500s (1496.86629915s left)
2019-07-03 06:20:51,085 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062051071532
2019-07-03 06:20:51,107 [salt.minion      :1432][INFO    ][16032] Starting a new job with PID 16032
2019-07-03 06:20:51,129 [salt.minion      :1711][INFO    ][16032] Returning information for job: 20190703062051071532
2019-07-03 06:21:17,183 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Ready|Deployed for machines:['cmp001']
sleep for:30s Timeout:1500s (1463.96537209s left)
2019-07-03 06:21:21,124 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062121111987
2019-07-03 06:21:21,146 [salt.minion      :1432][INFO    ][16162] Starting a new job with PID 16162
2019-07-03 06:21:21,170 [salt.minion      :1711][INFO    ][16162] Returning information for job: 20190703062121111987
2019-07-03 06:21:50,313 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Ready|Deployed for machines:['cmp001']
sleep for:30s Timeout:1500s (1430.83541608s left)
2019-07-03 06:21:51,222 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062151209469
2019-07-03 06:21:51,243 [salt.minion      :1432][INFO    ][16183] Starting a new job with PID 16183
2019-07-03 06:21:51,267 [salt.minion      :1711][INFO    ][16183] Returning information for job: 20190703062151209469
2019-07-03 06:22:21,264 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062221252063
2019-07-03 06:22:21,286 [salt.minion      :1432][INFO    ][16244] Starting a new job with PID 16244
2019-07-03 06:22:21,309 [salt.minion      :1711][INFO    ][16244] Returning information for job: 20190703062221252063
2019-07-03 06:22:21,612 [salt.loaded.ext.module.maas:981 ][INFO    ][15931] Machine fec4tc deleted
2019-07-03 06:22:22,674 [salt.loaded.ext.module.maas:412 ][WARNING ][15931] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:22:22,675 [salt.loaded.ext.module.maas:92  ][INFO    ][15931] 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=qbyc8w architecture=amd64/generic power_parameters_power_user=admin
2019-07-03 06:22:23,582 [salt.loaded.ext.module.maas:412 ][WARNING ][15931] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:22:23,582 [salt.loaded.ext.module.maas:92  ][INFO    ][15931] 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-07-03 06:22:25,079 [salt.loaded.ext.module.maas:412 ][WARNING ][15931] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:22:25,080 [salt.loaded.ext.module.maas:92  ][INFO    ][15931] 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=d4fc33 architecture=amd64/generic power_parameters_power_user=admin
2019-07-03 06:22:26,339 [salt.loaded.ext.module.maas:412 ][WARNING ][15931] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:22:26,340 [salt.loaded.ext.module.maas:92  ][INFO    ][15931] 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=6kkymc architecture=amd64/generic power_parameters_power_user=admin
2019-07-03 06:22:27,602 [salt.loaded.ext.module.maas:412 ][WARNING ][15931] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-03 06:22:27,603 [salt.loaded.ext.module.maas:92  ][INFO    ][15931] 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=nfxrt8 architecture=amd64/generic power_parameters_power_user=admin
2019-07-03 06:22:30,964 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Ready|Deployed for machines:['cmp001']
sleep for:30s Timeout:1500s (1390.18493104s left)
2019-07-03 06:22:51,314 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062251301305
2019-07-03 06:22:51,337 [salt.minion      :1432][INFO    ][16516] Starting a new job with PID 16516
2019-07-03 06:22:51,362 [salt.minion      :1711][INFO    ][16516] Returning information for job: 20190703062251301305
2019-07-03 06:23:04,074 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Ready|Deployed for machines:['cmp001']
sleep for:30s Timeout:1500s (1357.07446504s left)
2019-07-03 06:23:21,367 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062321354982
2019-07-03 06:23:21,390 [salt.minion      :1432][INFO    ][16590] Starting a new job with PID 16590
2019-07-03 06:23:21,414 [salt.minion      :1711][INFO    ][16590] Returning information for job: 20190703062321354982
2019-07-03 06:23:37,261 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Ready|Deployed for machines:['cmp001']
sleep for:30s Timeout:1500s (1323.88808703s left)
2019-07-03 06:23:51,423 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062351410309
2019-07-03 06:23:51,446 [salt.minion      :1432][INFO    ][16630] Starting a new job with PID 16630
2019-07-03 06:23:51,469 [salt.minion      :1711][INFO    ][16630] Returning information for job: 20190703062351410309
2019-07-03 06:24:09,885 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Ready|Deployed for machines:['cmp001']
sleep for:30s Timeout:1500s (1291.2639811s left)
2019-07-03 06:24:21,474 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062421466721
2019-07-03 06:24:21,492 [salt.minion      :1432][INFO    ][16722] Starting a new job with PID 16722
2019-07-03 06:24:21,513 [salt.minion      :1711][INFO    ][16722] Returning information for job: 20190703062421466721
2019-07-03 06:24:42,554 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Ready|Deployed for machines:['cmp001']
sleep for:30s Timeout:1500s (1258.59511209s left)
2019-07-03 06:24:51,537 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062451524186
2019-07-03 06:24:51,560 [salt.minion      :1432][INFO    ][16775] Starting a new job with PID 16775
2019-07-03 06:24:51,584 [salt.minion      :1711][INFO    ][16775] Returning information for job: 20190703062451524186
2019-07-03 06:25:15,504 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Ready|Deployed for machines:['cmp001']
sleep for:30s Timeout:1500s (1225.64485502s left)
2019-07-03 06:25:21,608 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062521595843
2019-07-03 06:25:21,631 [salt.minion      :1432][INFO    ][17037] Starting a new job with PID 17037
2019-07-03 06:25:21,655 [salt.minion      :1711][INFO    ][17037] Returning information for job: 20190703062521595843
2019-07-03 06:25:48,326 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Ready|Deployed for machines:['cmp001']
sleep for:30s Timeout:1500s (1192.82309413s left)
2019-07-03 06:25:51,677 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062551665569
2019-07-03 06:25:51,701 [salt.minion      :1432][INFO    ][17057] Starting a new job with PID 17057
2019-07-03 06:25:51,725 [salt.minion      :1711][INFO    ][17057] Returning information for job: 20190703062551665569
2019-07-03 06:26:21,489 [salt.state       :300 ][INFO    ][15931] {'ret': True}
2019-07-03 06:26:21,489 [salt.state       :1951][INFO    ][15931] Completed state [maas.wait_for_machine_status] at time 06:26:21.489645 duration_in_ms=340352.65
2019-07-03 06:26:21,493 [salt.minion      :1711][INFO    ][15931] Returning information for job: 20190703062035951094
2019-07-03 06:26:22,129 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command state.apply with jid 20190703062622116872
2019-07-03 06:26:22,152 [salt.minion      :1432][INFO    ][17181] Starting a new job with PID 17181
2019-07-03 06:26:25,846 [salt.state       :915 ][INFO    ][17181] Loading fresh modules for state activity
2019-07-03 06:26:25,898 [salt.fileclient  :1219][INFO    ][17181] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-03 06:26:25,983 [salt.state       :1780][INFO    ][17181] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:26:25.983253
2019-07-03 06:26:25,983 [salt.state       :1813][INFO    ][17181] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-03 06:26:25,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17181] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-03 06:26:27,358 [salt.state       :300 ][INFO    ][17181] {'pid': 17196, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-03 06:26:27,359 [salt.state       :1951][INFO    ][17181] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:26:27.359346 duration_in_ms=1376.092
2019-07-03 06:26:27,360 [salt.state       :1780][INFO    ][17181] Running state [maas_machines_storage_cmp002_lvm] at time 06:26:27.360734
2019-07-03 06:26:27,360 [salt.state       :1813][INFO    ][17181] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-03 06:26:28,509 [salt.loaded.ext.module.maasng:610 ][INFO    ][17181] qbyc8w
2019-07-03 06:26:28,510 [salt.loaded.ext.module.maasng:626 ][INFO    ][17181] sda
2019-07-03 06:26:29,028 [salt.loaded.ext.module.maasng:361 ][INFO    ][17181] qbyc8w
2019-07-03 06:26:29,138 [salt.loaded.ext.module.maasng:367 ][INFO    ][17181] [{u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'qbyc8w', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'dc2a24c0-2729-41f9-8595-cf408f902b75', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qbyc8w', u'filesystem': {u'uuid': u'afd66169-c921-420a-9671-9a8e66709c9e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/blockdevices/3/partition/3', u'type': u'partition', u'id': 3, u'device_id': 3}]}, {u'size': 2397988454400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'a22ab3d4-92eb-4e8b-b38e-7a00472f054a', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qbyc8w', u'partition_table_type': None, u'filesystem': {u'uuid': u'28112f12-80d5-43b6-9b05-c932c34f9744', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/blockdevices/7/', u'used_size': 2397988454400, u'id': 7, u'serial': None, u'partitions': []}]
2019-07-03 06:26:29,139 [salt.loaded.ext.module.maasng:632 ][INFO    ][17181] vgroot
2019-07-03 06:26:29,139 [salt.loaded.ext.module.maasng:635 ][INFO    ][17181] lvroot
2019-07-03 06:26:29,139 [salt.loaded.ext.module.maasng:639 ][INFO    ][17181] 107374182400
2019-07-03 06:26:29,679 [salt.loaded.ext.module.maasng:645 ][INFO    ][17181] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sg7qse', 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'id': 26, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sg7qse', 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:6a', u'id': 4, u'discovered': [], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qbyc8w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/interfaces/4/'}, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', 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'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'fqdn': u'cmp002.maas', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'qbyc8w', 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'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'5ded93dc-bb59-4f59-8e51-9e2da346bca8', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qbyc8w', u'partition_table_type': None, u'filesystem': {u'uuid': u'b7be4895-c161-444a-b079-5b36b2d88cef', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'qbyc8w', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'93591a44-6b40-4d46-b077-d84dba95c12c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qbyc8w', u'filesystem': {u'uuid': u'c4d64d7b-63b6-4b64-bed5-8fbbe8cc7d45', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'device_id': 3}]}, {u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'5ded93dc-bb59-4f59-8e51-9e2da346bca8', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qbyc8w', u'partition_table_type': None, u'filesystem': {u'uuid': u'b7be4895-c161-444a-b079-5b36b2d88cef', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'ip_addresses': [], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qbyc8w', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'qbyc8w', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'partitions': [{u'size': 2397992648704, u'uuid': u'93591a44-6b40-4d46-b077-d84dba95c12c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qbyc8w', u'filesystem': {u'uuid': u'c4d64d7b-63b6-4b64-bed5-8fbbe8cc7d45', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'device_id': 3}]}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'qbyc8w', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'partitions': [{u'size': 2397992648704, u'uuid': u'93591a44-6b40-4d46-b077-d84dba95c12c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qbyc8w', u'filesystem': {u'uuid': u'c4d64d7b-63b6-4b64-bed5-8fbbe8cc7d45', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'device_id': 3}]}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sg7qse', 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'id': 26, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sg7qse', 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:6a', u'id': 4, u'discovered': [], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qbyc8w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/interfaces/4/'}, {u'name': u'enp7s0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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:6b', u'id': 15, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qbyc8w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/interfaces/15/'}, {u'name': u'enp8s0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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:6c', u'id': 16, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qbyc8w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/interfaces/16/'}, {u'name': u'enp9s0', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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:6d', u'id': 17, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qbyc8w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qbyc8w/interfaces/17/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/qbyc8w/'}
2019-07-03 06:26:29,681 [salt.state       :300 ][INFO    ][17181] {'new': {'storage_layout': 'lvm'}}
2019-07-03 06:26:29,682 [salt.state       :1951][INFO    ][17181] Completed state [maas_machines_storage_cmp002_lvm] at time 06:26:29.681944 duration_in_ms=2321.206
2019-07-03 06:26:29,682 [salt.state       :1780][INFO    ][17181] Running state [maas_machines_storage_cmp001_lvm] at time 06:26:29.682500
2019-07-03 06:26:29,682 [salt.state       :1813][INFO    ][17181] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-03 06:26:30,753 [salt.loaded.ext.module.maasng:610 ][INFO    ][17181] 6w3xsx
2019-07-03 06:26:30,754 [salt.loaded.ext.module.maasng:626 ][INFO    ][17181] sda
2019-07-03 06:26:31,239 [salt.loaded.ext.module.maasng:361 ][INFO    ][17181] 6w3xsx
2019-07-03 06:26:31,353 [salt.loaded.ext.module.maasng:367 ][INFO    ][17181] [{u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6w3xsx', 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'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/blockdevices/9/', u'used_size': 2397998940160, u'id': 9, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'63f149cc-9681-4bba-b24b-874cd70ef493', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6w3xsx', u'filesystem': {u'uuid': u'2dfcd2bb-7593-41c0-8a3d-db1eafb21459', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/blockdevices/9/partition/5', u'type': u'partition', u'id': 5, u'device_id': 9}]}, {u'size': 2397988454400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'1bfa0441-51d2-454d-909f-4669ae1792c4', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6w3xsx', u'partition_table_type': None, u'filesystem': {u'uuid': u'4659a4b0-3356-4e77-adb2-c4c757ae5e09', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/blockdevices/10/', u'used_size': 2397988454400, u'id': 10, u'serial': None, u'partitions': []}]
2019-07-03 06:26:31,353 [salt.loaded.ext.module.maasng:632 ][INFO    ][17181] vgroot
2019-07-03 06:26:31,354 [salt.loaded.ext.module.maasng:635 ][INFO    ][17181] lvroot
2019-07-03 06:26:31,354 [salt.loaded.ext.module.maasng:639 ][INFO    ][17181] 107374182400
2019-07-03 06:26:31,876 [salt.loaded.ext.module.maasng:645 ][INFO    ][17181] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6w3xsx', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/blockdevices/9/', u'type': u'physical', u'id': 9, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'6e45300f-1493-47aa-b9d4-c94b16a5f2f2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6w3xsx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2f2d4f41-99b4-4d54-81f7-9b36ecb10aea', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/blockdevices/9/partition/7', u'type': u'partition', u'id': 7, u'device_id': 9}]}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 12, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'6w3xsx', 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'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'9eee4a68-0bb5-4954-8cb6-b198ee93ff50', u'fstype': u'ext4'}, u'uuid': u'93df277b-f067-4fb7-98a4-7494523c62c5', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6w3xsx', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/blockdevices/12/', u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6w3xsx', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/blockdevices/9/', u'type': u'physical', u'id': 9, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'6e45300f-1493-47aa-b9d4-c94b16a5f2f2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6w3xsx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2f2d4f41-99b4-4d54-81f7-9b36ecb10aea', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/blockdevices/9/partition/7', u'type': u'partition', u'id': 7, u'device_id': 9}]}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'9eee4a68-0bb5-4954-8cb6-b198ee93ff50', u'fstype': u'ext4'}, u'uuid': u'93df277b-f067-4fb7-98a4-7494523c62c5', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6w3xsx', 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/6w3xsx/blockdevices/12/', u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6w3xsx', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 13, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6w3xsx', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/blockdevices/9/', u'type': u'physical', u'id': 9, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'6e45300f-1493-47aa-b9d4-c94b16a5f2f2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6w3xsx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2f2d4f41-99b4-4d54-81f7-9b36ecb10aea', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/blockdevices/9/partition/7', u'type': u'partition', u'id': 7, u'device_id': 9}]}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'sg7qse', 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': 38, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'sg7qse', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'sg7qse', 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'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'6w3xsx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/interfaces/21/'}, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'sg7qse', 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': 38, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'sg7qse', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'sg7qse', 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'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'6w3xsx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/interfaces/21/'}, {u'name': u'enp8s0', u'links': [{u'id': 40, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'6w3xsx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/interfaces/23/'}, {u'name': u'enp9s0', u'links': [{u'id': 41, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'6w3xsx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 24, u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/interfaces/24/'}, {u'name': u'enp7s0', u'links': [{u'id': 39, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'6w3xsx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/6w3xsx/interfaces/22/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/6w3xsx/', u'distro_series': u'', u'memory_test_status': -1}
2019-07-03 06:26:31,878 [salt.state       :300 ][INFO    ][17181] {'new': {'storage_layout': 'lvm'}}
2019-07-03 06:26:31,879 [salt.state       :1951][INFO    ][17181] Completed state [maas_machines_storage_cmp001_lvm] at time 06:26:31.879277 duration_in_ms=2196.776
2019-07-03 06:26:31,882 [salt.minion      :1711][INFO    ][17181] Returning information for job: 20190703062622116872
2019-07-03 06:26:32,388 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command state.apply with jid 20190703062632375710
2019-07-03 06:26:32,407 [salt.minion      :1432][INFO    ][17215] Starting a new job with PID 17215
2019-07-03 06:26:33,141 [salt.state       :915 ][INFO    ][17215] Loading fresh modules for state activity
2019-07-03 06:26:33,194 [salt.fileclient  :1219][INFO    ][17215] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-03 06:26:33,239 [salt.state       :1780][INFO    ][17215] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:26:33.239114
2019-07-03 06:26:33,239 [salt.state       :1813][INFO    ][17215] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-03 06:26:33,241 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17215] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-03 06:26:34,673 [salt.state       :300 ][INFO    ][17215] {'pid': 17222, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-03 06:26:34,674 [salt.state       :1951][INFO    ][17215] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:26:34.674029 duration_in_ms=1434.915
2019-07-03 06:26:34,675 [salt.state       :1780][INFO    ][17215] Running state [maas.deploy_machines] at time 06:26:34.675230
2019-07-03 06:26:34,675 [salt.state       :1813][INFO    ][17215] Executing state module.run for [maas.deploy_machines]
2019-07-03 06:26:34,676 [salt.utils.decorators:613 ][WARNING ][17215] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:26:35,401 [salt.loaded.ext.module.maas:684 ][INFO    ][17215] deploymachines hwe_kernel=hwe-16.04 system_id=qbyc8w distro_series=xenial
2019-07-03 06:26:38,122 [salt.loaded.ext.module.maas:684 ][INFO    ][17215] deploymachines hwe_kernel=hwe-16.04 system_id=6w3xsx distro_series=xenial
2019-07-03 06:26:40,918 [salt.loaded.ext.module.maas:684 ][INFO    ][17215] deploymachines hwe_kernel=hwe-16.04 system_id=d4fc33 distro_series=xenial
2019-07-03 06:26:43,590 [salt.loaded.ext.module.maas:684 ][INFO    ][17215] deploymachines hwe_kernel=hwe-16.04 system_id=6kkymc distro_series=xenial
2019-07-03 06:26:46,171 [salt.loaded.ext.module.maas:684 ][INFO    ][17215] deploymachines hwe_kernel=hwe-16.04 system_id=nfxrt8 distro_series=xenial
2019-07-03 06:26:47,415 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062647403456
2019-07-03 06:26:47,438 [salt.minion      :1432][INFO    ][17479] Starting a new job with PID 17479
2019-07-03 06:26:47,461 [salt.minion      :1711][INFO    ][17479] Returning information for job: 20190703062647403456
2019-07-03 06:26:48,196 [salt.state       :300 ][INFO    ][17215] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-07-03 06:26:48,197 [salt.state       :1951][INFO    ][17215] Completed state [maas.deploy_machines] at time 06:26:48.197136 duration_in_ms=13521.902
2019-07-03 06:26:48,200 [salt.minion      :1711][INFO    ][17215] Returning information for job: 20190703062632375710
2019-07-03 06:26:48,840 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command state.apply with jid 20190703062648827970
2019-07-03 06:26:48,863 [salt.minion      :1432][INFO    ][17497] Starting a new job with PID 17497
2019-07-03 06:26:52,447 [salt.state       :915 ][INFO    ][17497] Loading fresh modules for state activity
2019-07-03 06:26:52,498 [salt.fileclient  :1219][INFO    ][17497] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-03 06:26:52,541 [salt.state       :1780][INFO    ][17497] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:26:52.541886
2019-07-03 06:26:52,542 [salt.state       :1813][INFO    ][17497] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-03 06:26:52,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17497] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-03 06:26:54,002 [salt.state       :300 ][INFO    ][17497] {'pid': 17509, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-03 06:26:54,003 [salt.state       :1951][INFO    ][17497] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:26:54.003284 duration_in_ms=1461.398
2019-07-03 06:26:54,006 [salt.state       :1780][INFO    ][17497] Running state [maas.wait_for_machine_status] at time 06:26:54.006691
2019-07-03 06:26:54,007 [salt.state       :1813][INFO    ][17497] Executing state module.run for [maas.wait_for_machine_status]
2019-07-03 06:26:54,008 [salt.utils.decorators:613 ][WARNING ][17497] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-03 06:26:57,177 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.84187412s left)
2019-07-03 06:27:03,956 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062703943999
2019-07-03 06:27:03,980 [salt.minion      :1432][INFO    ][17563] Starting a new job with PID 17563
2019-07-03 06:27:04,005 [salt.minion      :1711][INFO    ][17563] Returning information for job: 20190703062703943999
2019-07-03 06:27:30,031 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.98699212s left)
2019-07-03 06:27:34,007 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062733995473
2019-07-03 06:27:34,026 [salt.minion      :1432][INFO    ][17583] Starting a new job with PID 17583
2019-07-03 06:27:34,049 [salt.minion      :1711][INFO    ][17583] Returning information for job: 20190703062733995473
2019-07-03 06:28:02,834 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.1842742s left)
2019-07-03 06:28:04,096 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062804083814
2019-07-03 06:28:04,118 [salt.minion      :1432][INFO    ][17641] Starting a new job with PID 17641
2019-07-03 06:28:04,141 [salt.minion      :1711][INFO    ][17641] Returning information for job: 20190703062804083814
2019-07-03 06:28:34,137 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062834129342
2019-07-03 06:28:34,155 [salt.minion      :1432][INFO    ][17772] Starting a new job with PID 17772
2019-07-03 06:28:34,167 [salt.minion      :1711][INFO    ][17772] Returning information for job: 20190703062834129342
2019-07-03 06:28:35,936 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.08202314s left)
2019-07-03 06:29:04,179 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062904167174
2019-07-03 06:29:04,203 [salt.minion      :1432][INFO    ][18369] Starting a new job with PID 18369
2019-07-03 06:29:04,225 [salt.minion      :1711][INFO    ][18369] Returning information for job: 20190703062904167174
2019-07-03 06:29:09,309 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.7089591s left)
2019-07-03 06:29:34,231 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703062934218843
2019-07-03 06:29:34,255 [salt.minion      :1432][INFO    ][18410] Starting a new job with PID 18410
2019-07-03 06:29:34,279 [salt.minion      :1711][INFO    ][18410] Returning information for job: 20190703062934218843
2019-07-03 06:29:42,292 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.72685218s left)
2019-07-03 06:30:04,294 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703063004282258
2019-07-03 06:30:04,316 [salt.minion      :1432][INFO    ][18868] Starting a new job with PID 18868
2019-07-03 06:30:04,338 [salt.minion      :1711][INFO    ][18868] Returning information for job: 20190703063004282258
2019-07-03 06:30:15,869 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.14983606s left)
2019-07-03 06:30:34,353 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703063034340793
2019-07-03 06:30:34,376 [salt.minion      :1432][INFO    ][18897] Starting a new job with PID 18897
2019-07-03 06:30:34,400 [salt.minion      :1711][INFO    ][18897] Returning information for job: 20190703063034340793
2019-07-03 06:30:48,741 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.2774992s left)
2019-07-03 06:31:04,420 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703063104407831
2019-07-03 06:31:04,443 [salt.minion      :1432][INFO    ][19323] Starting a new job with PID 19323
2019-07-03 06:31:04,467 [salt.minion      :1711][INFO    ][19323] Returning information for job: 20190703063104407831
2019-07-03 06:31:22,167 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1981.85166907s left)
2019-07-03 06:31:34,492 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703063134479052
2019-07-03 06:31:34,515 [salt.minion      :1432][INFO    ][19347] Starting a new job with PID 19347
2019-07-03 06:31:34,540 [salt.minion      :1711][INFO    ][19347] Returning information for job: 20190703063134479052
2019-07-03 06:31:55,188 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1948.83022618s left)
2019-07-03 06:32:04,576 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703063204563983
2019-07-03 06:32:04,598 [salt.minion      :1432][INFO    ][19901] Starting a new job with PID 19901
2019-07-03 06:32:04,622 [salt.minion      :1711][INFO    ][19901] Returning information for job: 20190703063204563983
2019-07-03 06:32:28,848 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1915.17031407s left)
2019-07-03 06:32:34,653 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703063234640266
2019-07-03 06:32:34,677 [salt.minion      :1432][INFO    ][19931] Starting a new job with PID 19931
2019-07-03 06:32:34,700 [salt.minion      :1711][INFO    ][19931] Returning information for job: 20190703063234640266
2019-07-03 06:33:02,423 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1881.59523201s left)
2019-07-03 06:33:04,739 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703063304725878
2019-07-03 06:33:04,762 [salt.minion      :1432][INFO    ][20050] Starting a new job with PID 20050
2019-07-03 06:33:04,786 [salt.minion      :1711][INFO    ][20050] Returning information for job: 20190703063304725878
2019-07-03 06:33:34,828 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703063334815918
2019-07-03 06:33:34,851 [salt.minion      :1432][INFO    ][20079] Starting a new job with PID 20079
2019-07-03 06:33:34,874 [salt.minion      :1711][INFO    ][20079] Returning information for job: 20190703063334815918
2019-07-03 06:33:35,644 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1848.3740201s left)
2019-07-03 06:34:04,972 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703063404961140
2019-07-03 06:34:04,993 [salt.minion      :1432][INFO    ][20404] Starting a new job with PID 20404
2019-07-03 06:34:05,012 [salt.minion      :1711][INFO    ][20404] Returning information for job: 20190703063404961140
2019-07-03 06:34:09,309 [salt.loaded.ext.module.maas:1023][INFO    ][17497] Waiting status:Deployed for machines:['kvm01', 'kvm02']
sleep for:30s Timeout:2250s (1814.7096262s left)
2019-07-03 06:34:35,006 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command saltutil.find_job with jid 20190703063434993795
2019-07-03 06:34:35,029 [salt.minion      :1432][INFO    ][20526] Starting a new job with PID 20526
2019-07-03 06:34:35,051 [salt.minion      :1711][INFO    ][20526] Returning information for job: 20190703063434993795
2019-07-03 06:34:42,754 [salt.state       :300 ][INFO    ][17497] {'ret': True}
2019-07-03 06:34:42,755 [salt.state       :1951][INFO    ][17497] Completed state [maas.wait_for_machine_status] at time 06:34:42.755275 duration_in_ms=468748.581
2019-07-03 06:34:42,759 [salt.minion      :1711][INFO    ][17497] Returning information for job: 20190703062648827970
2019-07-03 07:12:05,084 [salt.utils.schedule:1377][INFO    ][13086] Running scheduled job: __mine_interval
2019-07-03 08:06:38,652 [salt.minion      :1308][INFO    ][13086] User sudo_ubuntu Executing command cp.push_dir with jid 20190703080638646332
2019-07-03 08:06:38,674 [salt.minion      :1432][INFO    ][27237] Starting a new job with PID 27237
