2019-05-06 05:02:41,868 [salt.minion      :870 ][ERROR   ][354] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-06 05:03:31,919 [salt.minion      :870 ][ERROR   ][354] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-06 05:04:21,966 [salt.minion      :870 ][ERROR   ][354] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-06 05:05:12,012 [salt.minion      :870 ][ERROR   ][354] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-06 05:06:02,061 [salt.minion      :870 ][ERROR   ][354] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-06 05:08:11,049 [salt.utils.decorators:613 ][WARNING ][2707] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 05:08:11,617 [salt.utils.decorators:613 ][WARNING ][2707] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 05:08:13,711 [salt.loaded.int.states.file:2298][WARNING ][2841] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-06 05:08:19,670 [salt.state       :2022][WARNING ][2955] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-06 05:08:22,246 [salt.utils.decorators:613 ][WARNING ][2955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 05:23:29,081 [salt.utils.decorators:613 ][WARNING ][2955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 05:52:02,836 [salt.utils.decorators:613 ][WARNING ][2955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:07:07,762 [salt.utils.decorators:613 ][WARNING ][2955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:07:11,808 [salt.loaded.ext.module.maasng:1008][WARNING ][2955] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-06 06:07:11,808 [salt.loaded.ext.module.maasng:1011][WARNING ][2955] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-06 06:07:12,539 [salt.loaded.ext.module.maasng:1235][WARNING ][2955] Ignoring parameter vlan:0
2019-05-06 06:07:13,468 [salt.utils.decorators:613 ][WARNING ][2955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:07:19,300 [salt.utils.decorators:613 ][WARNING ][13992] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:07:19,362 [salt.loaded.ext.module.maas:412 ][WARNING ][13992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:07:23,421 [salt.loaded.ext.module.maas:412 ][WARNING ][13992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:07:28,056 [salt.loaded.ext.module.maas:412 ][WARNING ][13992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:07:31,755 [salt.loaded.ext.module.maas:412 ][WARNING ][13992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:07:36,468 [salt.loaded.ext.module.maas:412 ][WARNING ][13992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:07:43,036 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14974] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-06 06:07:43,067 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14974] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-06 06:07:43,091 [salt.utils.parsers:1051][WARNING ][354] Minion received a SIGTERM. Exiting.
2019-05-06 06:07:44,135 [salt.cli.daemons :293 ][INFO    ][15019] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-05-06 06:07:44,222 [salt.cli.daemons :82  ][INFO    ][15019] Starting up the Salt Minion
2019-05-06 06:07:44,222 [salt.utils.event :1017][INFO    ][15019] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-05-06 06:07:45,131 [salt.minion      :976 ][INFO    ][15019] Creating minion process manager
2019-05-06 06:07:46,651 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15019] Executing command ['date', '+%z'] in directory '/root'
2019-05-06 06:07:46,673 [salt.utils.schedule:568 ][INFO    ][15019] Updating job settings for scheduled job: __mine_interval
2019-05-06 06:07:46,674 [salt.minion      :1108][INFO    ][15019] Added mine.update to scheduler
2019-05-06 06:07:46,679 [salt.minion      :1975][INFO    ][15019] Minion is starting as user 'root'
2019-05-06 06:07:46,690 [salt.minion      :2336][INFO    ][15019] Minion is ready to receive requests!
2019-05-06 06:08:11,737 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506060811728144
2019-05-06 06:08:11,758 [salt.minion      :1432][INFO    ][15111] Starting a new job with PID 15111
2019-05-06 06:08:15,637 [salt.state       :915 ][INFO    ][15111] Loading fresh modules for state activity
2019-05-06 06:08:15,674 [salt.fileclient  :1219][INFO    ][15111] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-06 06:08:15,712 [salt.state       :1780][INFO    ][15111] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:08:15.712396
2019-05-06 06:08:15,712 [salt.state       :1813][INFO    ][15111] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-06 06:08:15,714 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15111] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-06 06:08:17,051 [salt.state       :300 ][INFO    ][15111] {'pid': 15140, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-06 06:08:17,052 [salt.state       :1951][INFO    ][15111] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:08:17.052338 duration_in_ms=1339.94
2019-05-06 06:08:17,055 [salt.state       :1780][INFO    ][15111] Running state [maas.wait_for_machine_status] at time 06:08:17.055711
2019-05-06 06:08:17,056 [salt.state       :1813][INFO    ][15111] Executing state module.run for [maas.wait_for_machine_status]
2019-05-06 06:08:17,057 [salt.utils.decorators:613 ][WARNING ][15111] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:08:18,019 [salt.loaded.ext.module.maas:1023][INFO    ][15111] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.04808402s left)
2019-05-06 06:08:26,808 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506060826796195
2019-05-06 06:08:26,826 [salt.minion      :1432][INFO    ][15202] Starting a new job with PID 15202
2019-05-06 06:08:26,848 [salt.minion      :1711][INFO    ][15202] Returning information for job: 20190506060826796195
2019-05-06 06:08:56,869 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506060856857517
2019-05-06 06:08:56,886 [salt.minion      :1432][INFO    ][15444] Starting a new job with PID 15444
2019-05-06 06:08:56,907 [salt.minion      :1711][INFO    ][15444] Returning information for job: 20190506060856857517
2019-05-06 06:09:26,894 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506060926888132
2019-05-06 06:09:26,915 [salt.minion      :1432][INFO    ][15616] Starting a new job with PID 15616
2019-05-06 06:09:26,937 [salt.minion      :1711][INFO    ][15616] Returning information for job: 20190506060926888132
2019-05-06 06:09:56,956 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506060956943030
2019-05-06 06:09:56,979 [salt.minion      :1432][INFO    ][15670] Starting a new job with PID 15670
2019-05-06 06:09:57,002 [salt.minion      :1711][INFO    ][15670] Returning information for job: 20190506060956943030
2019-05-06 06:10:27,031 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061027018123
2019-05-06 06:10:27,052 [salt.minion      :1432][INFO    ][15730] Starting a new job with PID 15730
2019-05-06 06:10:27,074 [salt.minion      :1711][INFO    ][15730] Returning information for job: 20190506061027018123
2019-05-06 06:10:57,105 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061057093421
2019-05-06 06:10:57,126 [salt.minion      :1432][INFO    ][15767] Starting a new job with PID 15767
2019-05-06 06:10:57,150 [salt.minion      :1711][INFO    ][15767] Returning information for job: 20190506061057093421
2019-05-06 06:11:27,189 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061127174877
2019-05-06 06:11:27,212 [salt.minion      :1432][INFO    ][15811] Starting a new job with PID 15811
2019-05-06 06:11:27,231 [salt.minion      :1711][INFO    ][15811] Returning information for job: 20190506061127174877
2019-05-06 06:11:57,276 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061157266229
2019-05-06 06:11:57,296 [salt.minion      :1432][INFO    ][15850] Starting a new job with PID 15850
2019-05-06 06:11:57,317 [salt.minion      :1711][INFO    ][15850] Returning information for job: 20190506061157266229
2019-05-06 06:12:27,357 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061227345052
2019-05-06 06:12:27,378 [salt.minion      :1432][INFO    ][15902] Starting a new job with PID 15902
2019-05-06 06:12:27,398 [salt.minion      :1711][INFO    ][15902] Returning information for job: 20190506061227345052
2019-05-06 06:12:57,452 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061257440864
2019-05-06 06:12:57,471 [salt.minion      :1432][INFO    ][15927] Starting a new job with PID 15927
2019-05-06 06:12:57,489 [salt.minion      :1711][INFO    ][15927] Returning information for job: 20190506061257440864
2019-05-06 06:13:27,539 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061327526947
2019-05-06 06:13:27,558 [salt.minion      :1432][INFO    ][15971] Starting a new job with PID 15971
2019-05-06 06:13:27,572 [salt.minion      :1711][INFO    ][15971] Returning information for job: 20190506061327526947
2019-05-06 06:13:57,616 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061357603942
2019-05-06 06:13:57,635 [salt.minion      :1432][INFO    ][16004] Starting a new job with PID 16004
2019-05-06 06:13:57,659 [salt.minion      :1711][INFO    ][16004] Returning information for job: 20190506061357603942
2019-05-06 06:14:27,705 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061427692794
2019-05-06 06:14:27,728 [salt.minion      :1432][INFO    ][16035] Starting a new job with PID 16035
2019-05-06 06:14:27,751 [salt.minion      :1711][INFO    ][16035] Returning information for job: 20190506061427692794
2019-05-06 06:14:38,024 [salt.state       :302 ][ERROR   ][15111] Module function maas.wait_for_machine_status threw an exception. Exception: HTTP Error 401: OK
2019-05-06 06:14:38,025 [salt.state       :1951][INFO    ][15111] Completed state [maas.wait_for_machine_status] at time 06:14:38.025333 duration_in_ms=380969.62
2019-05-06 06:14:38,029 [salt.minion      :1711][INFO    ][15111] Returning information for job: 20190506060811728144
2019-05-06 06:14:48,823 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command pillar.get with jid 20190506061448811571
2019-05-06 06:14:48,846 [salt.minion      :1432][INFO    ][16318] Starting a new job with PID 16318
2019-05-06 06:14:48,854 [salt.minion      :1711][INFO    ][16318] Returning information for job: 20190506061448811571
2019-05-06 06:14:49,394 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command service.status with jid 20190506061449382264
2019-05-06 06:14:49,410 [salt.minion      :1432][INFO    ][16342] Starting a new job with PID 16342
2019-05-06 06:14:49,734 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][16342] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-06 06:14:49,770 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][16342] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-06 06:14:49,786 [salt.minion      :1711][INFO    ][16342] Returning information for job: 20190506061449382264
2019-05-06 06:14:50,318 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506061450304611
2019-05-06 06:14:50,337 [salt.minion      :1432][INFO    ][16353] Starting a new job with PID 16353
2019-05-06 06:14:54,984 [salt.state       :915 ][INFO    ][16353] Loading fresh modules for state activity
2019-05-06 06:14:55,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command 'salt-minion --version' in directory '/root'
2019-05-06 06:14:55,766 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command 'salt-minion --version' in directory '/root'
2019-05-06 06:14:56,554 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command 'salt-minion --version' in directory '/root'
2019-05-06 06:14:56,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command 'salt-minion --version' in directory '/root'
2019-05-06 06:14:58,200 [salt.state       :1780][INFO    ][16353] Running state [salt-minion] at time 06:14:58.200272
2019-05-06 06:14:58,200 [salt.state       :1813][INFO    ][16353] Executing state pkg.installed for [salt-minion]
2019-05-06 06:14:58,201 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-06 06:14:58,286 [salt.state       :300 ][INFO    ][16353] All specified packages are already installed
2019-05-06 06:14:58,286 [salt.state       :1951][INFO    ][16353] Completed state [salt-minion] at time 06:14:58.286739 duration_in_ms=86.467
2019-05-06 06:14:58,287 [salt.state       :1780][INFO    ][16353] Running state [salt_minion_dependency_packages] at time 06:14:58.287033
2019-05-06 06:14:58,287 [salt.state       :1813][INFO    ][16353] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-06 06:14:58,293 [salt.state       :300 ][INFO    ][16353] All specified packages are already installed
2019-05-06 06:14:58,293 [salt.state       :1951][INFO    ][16353] Completed state [salt_minion_dependency_packages] at time 06:14:58.293681 duration_in_ms=6.648
2019-05-06 06:14:58,296 [salt.state       :1780][INFO    ][16353] Running state [/etc/salt/minion.d/minion.conf] at time 06:14:58.296421
2019-05-06 06:14:58,296 [salt.state       :1813][INFO    ][16353] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-06 06:14:58,485 [salt.state       :300 ][INFO    ][16353] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-06 06:14:58,485 [salt.state       :1951][INFO    ][16353] Completed state [/etc/salt/minion.d/minion.conf] at time 06:14:58.485520 duration_in_ms=189.098
2019-05-06 06:14:58,485 [salt.state       :1780][INFO    ][16353] Running state [python-netaddr] at time 06:14:58.485711
2019-05-06 06:14:58,485 [salt.state       :1813][INFO    ][16353] Executing state pkg.installed for [python-netaddr]
2019-05-06 06:14:58,490 [salt.state       :300 ][INFO    ][16353] All specified packages are already installed
2019-05-06 06:14:58,490 [salt.state       :1951][INFO    ][16353] Completed state [python-netaddr] at time 06:14:58.490432 duration_in_ms=4.721
2019-05-06 06:14:58,492 [salt.state       :1780][INFO    ][16353] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:14:58.492503
2019-05-06 06:14:58,492 [salt.state       :1813][INFO    ][16353] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-06 06:14:58,502 [salt.state       :300 ][INFO    ][16353] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-06 06:14:58,502 [salt.state       :1951][INFO    ][16353] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:14:58.502144 duration_in_ms=9.64
2019-05-06 06:14:58,502 [salt.state       :1780][INFO    ][16353] Running state [salt-minion] at time 06:14:58.502784
2019-05-06 06:14:58,502 [salt.state       :1813][INFO    ][16353] Executing state service.running for [salt-minion]
2019-05-06 06:14:58,503 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-06 06:14:58,537 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-06 06:14:58,552 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-06 06:14:58,568 [salt.state       :300 ][INFO    ][16353] The service salt-minion is already running
2019-05-06 06:14:58,568 [salt.state       :1951][INFO    ][16353] Completed state [salt-minion] at time 06:14:58.568693 duration_in_ms=65.907
2019-05-06 06:14:58,570 [salt.state       :1780][INFO    ][16353] Running state [/etc/salt/grains.d] at time 06:14:58.569974
2019-05-06 06:14:58,570 [salt.state       :1813][INFO    ][16353] Executing state file.directory for [/etc/salt/grains.d]
2019-05-06 06:14:58,571 [salt.state       :300 ][INFO    ][16353] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-06 06:14:58,571 [salt.state       :1951][INFO    ][16353] Completed state [/etc/salt/grains.d] at time 06:14:58.571264 duration_in_ms=1.29
2019-05-06 06:14:58,571 [salt.state       :1780][INFO    ][16353] Running state [/etc/salt/grains] at time 06:14:58.571834
2019-05-06 06:14:58,572 [salt.state       :1813][INFO    ][16353] Executing state file.managed for [/etc/salt/grains]
2019-05-06 06:14:58,572 [salt.state       :300 ][INFO    ][16353] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-06 06:14:58,572 [salt.state       :1951][INFO    ][16353] Completed state [/etc/salt/grains] at time 06:14:58.572744 duration_in_ms=0.91
2019-05-06 06:14:58,573 [salt.state       :1780][INFO    ][16353] Running state [/etc/salt/grains.d/placeholder] at time 06:14:58.573131
2019-05-06 06:14:58,573 [salt.state       :1813][INFO    ][16353] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-06 06:14:58,573 [salt.state       :300 ][INFO    ][16353] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-06 06:14:58,574 [salt.state       :1951][INFO    ][16353] Completed state [/etc/salt/grains.d/placeholder] at time 06:14:58.574000 duration_in_ms=0.869
2019-05-06 06:14:58,574 [salt.state       :1780][INFO    ][16353] Running state [/etc/salt/grains.d/sphinx] at time 06:14:58.574376
2019-05-06 06:14:58,574 [salt.state       :1813][INFO    ][16353] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-06 06:14:58,587 [salt.state       :300 ][INFO    ][16353] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-06 06:14:58,587 [salt.state       :1951][INFO    ][16353] Completed state [/etc/salt/grains.d/sphinx] at time 06:14:58.587380 duration_in_ms=13.004
2019-05-06 06:14:58,589 [salt.state       :1780][INFO    ][16353] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:14:58.589276
2019-05-06 06:14:58,589 [salt.state       :1813][INFO    ][16353] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-06 06:14:58,589 [salt.state       :300 ][INFO    ][16353] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-06 06:14:58,590 [salt.state       :1951][INFO    ][16353] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:14:58.590012 duration_in_ms=0.736
2019-05-06 06:14:58,590 [salt.state       :1780][INFO    ][16353] Running state [/etc/salt/grains.d/dns_records] at time 06:14:58.590393
2019-05-06 06:14:58,590 [salt.state       :1813][INFO    ][16353] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-06 06:14:58,599 [salt.state       :300 ][INFO    ][16353] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-06 06:14:58,599 [salt.state       :1951][INFO    ][16353] Completed state [/etc/salt/grains.d/dns_records] at time 06:14:58.599247 duration_in_ms=8.854
2019-05-06 06:14:58,599 [salt.state       :1780][INFO    ][16353] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:14:58.599864
2019-05-06 06:14:58,600 [salt.state       :1813][INFO    ][16353] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-06 06:14:58,600 [salt.state       :300 ][INFO    ][16353] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-06 06:14:58,600 [salt.state       :1951][INFO    ][16353] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:14:58.600462 duration_in_ms=0.598
2019-05-06 06:14:58,600 [salt.state       :1780][INFO    ][16353] Running state [/etc/salt/grains.d/salt] at time 06:14:58.600789
2019-05-06 06:14:58,601 [salt.state       :1813][INFO    ][16353] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-06 06:14:58,610 [salt.state       :300 ][INFO    ][16353] File /etc/salt/grains.d/salt is in the correct state
2019-05-06 06:14:58,611 [salt.state       :1951][INFO    ][16353] Completed state [/etc/salt/grains.d/salt] at time 06:14:58.611139 duration_in_ms=10.349
2019-05-06 06:14:58,611 [salt.state       :1780][INFO    ][16353] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:14:58.611882
2019-05-06 06:14:58,612 [salt.state       :1813][INFO    ][16353] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-06 06:14:58,612 [salt.state       :300 ][INFO    ][16353] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-06 06:14:58,612 [salt.state       :1951][INFO    ][16353] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:14:58.612552 duration_in_ms=0.67
2019-05-06 06:14:58,613 [salt.state       :1780][INFO    ][16353] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:14:58.613883
2019-05-06 06:14:58,614 [salt.state       :1813][INFO    ][16353] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-06 06:14:58,614 [salt.state       :300 ][INFO    ][16353] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-06 06:14:58,614 [salt.state       :1951][INFO    ][16353] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:14:58.614554 duration_in_ms=0.67
2019-05-06 06:14:58,615 [salt.state       :1780][INFO    ][16353] Running state [mine.update] at time 06:14:58.615046
2019-05-06 06:14:58,615 [salt.state       :1813][INFO    ][16353] Executing state module.wait for [mine.update]
2019-05-06 06:14:58,615 [salt.state       :300 ][INFO    ][16353] No changes made for mine.update
2019-05-06 06:14:58,615 [salt.state       :1951][INFO    ][16353] Completed state [mine.update] at time 06:14:58.615667 duration_in_ms=0.621
2019-05-06 06:14:58,615 [salt.state       :1780][INFO    ][16353] Running state [ca-certificates] at time 06:14:58.615857
2019-05-06 06:14:58,616 [salt.state       :1813][INFO    ][16353] Executing state pkg.installed for [ca-certificates]
2019-05-06 06:14:58,621 [salt.state       :300 ][INFO    ][16353] All specified packages are already installed
2019-05-06 06:14:58,621 [salt.state       :1951][INFO    ][16353] Completed state [ca-certificates] at time 06:14:58.621513 duration_in_ms=5.656
2019-05-06 06:14:58,622 [salt.state       :1780][INFO    ][16353] Running state [update-ca-certificates] at time 06:14:58.622336
2019-05-06 06:14:58,622 [salt.state       :1813][INFO    ][16353] Executing state cmd.wait for [update-ca-certificates]
2019-05-06 06:14:58,623 [salt.state       :300 ][INFO    ][16353] No changes made for update-ca-certificates
2019-05-06 06:14:58,623 [salt.state       :1951][INFO    ][16353] Completed state [update-ca-certificates] at time 06:14:58.623178 duration_in_ms=0.842
2019-05-06 06:14:58,623 [salt.state       :1780][INFO    ][16353] Running state [iptables] at time 06:14:58.623357
2019-05-06 06:14:58,623 [salt.state       :1813][INFO    ][16353] Executing state pkg.installed for [iptables]
2019-05-06 06:14:58,628 [salt.state       :300 ][INFO    ][16353] All specified packages are already installed
2019-05-06 06:14:58,628 [salt.state       :1951][INFO    ][16353] Completed state [iptables] at time 06:14:58.628373 duration_in_ms=5.015
2019-05-06 06:14:58,628 [salt.state       :1780][INFO    ][16353] Running state [iptables-persistent] at time 06:14:58.628570
2019-05-06 06:14:58,628 [salt.state       :1813][INFO    ][16353] Executing state pkg.installed for [iptables-persistent]
2019-05-06 06:14:58,633 [salt.state       :300 ][INFO    ][16353] All specified packages are already installed
2019-05-06 06:14:58,633 [salt.state       :1951][INFO    ][16353] Completed state [iptables-persistent] at time 06:14:58.633730 duration_in_ms=5.16
2019-05-06 06:14:58,634 [salt.state       :1780][INFO    ][16353] Running state [iptables_modules_v4_load] at time 06:14:58.634455
2019-05-06 06:14:58,634 [salt.state       :1813][INFO    ][16353] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-06 06:14:58,635 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command 'lsmod' in directory '/root'
2019-05-06 06:14:58,656 [salt.state       :300 ][INFO    ][16353] Kernel modules iptable_filter, ip_tables are already present
2019-05-06 06:14:58,656 [salt.state       :1951][INFO    ][16353] Completed state [iptables_modules_v4_load] at time 06:14:58.656525 duration_in_ms=22.07
2019-05-06 06:14:58,657 [salt.state       :1780][INFO    ][16353] Running state [/etc/iptables/rules.v4] at time 06:14:58.656988
2019-05-06 06:14:58,657 [salt.state       :1813][INFO    ][16353] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-06 06:14:58,731 [salt.state       :300 ][INFO    ][16353] File /etc/iptables/rules.v4 is in the correct state
2019-05-06 06:14:58,731 [salt.state       :1951][INFO    ][16353] Completed state [/etc/iptables/rules.v4] at time 06:14:58.731324 duration_in_ms=74.335
2019-05-06 06:14:58,732 [salt.state       :1780][INFO    ][16353] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:14:58.732074
2019-05-06 06:14:58,732 [salt.state       :1813][INFO    ][16353] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-06 06:14:58,732 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-06 06:14:58,751 [salt.state       :300 ][INFO    ][16353] onlyif execution failed
2019-05-06 06:14:58,751 [salt.state       :1951][INFO    ][16353] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:14:58.751879 duration_in_ms=19.805
2019-05-06 06:14:58,752 [salt.state       :1780][INFO    ][16353] Running state [netfilter-persistent] at time 06:14:58.752573
2019-05-06 06:14:58,752 [salt.state       :1813][INFO    ][16353] Executing state service.running for [netfilter-persistent]
2019-05-06 06:14:58,753 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-06 06:14:58,771 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-06 06:14:58,786 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-06 06:14:58,801 [salt.state       :300 ][INFO    ][16353] The service netfilter-persistent is already running
2019-05-06 06:14:58,801 [salt.state       :1951][INFO    ][16353] Completed state [netfilter-persistent] at time 06:14:58.801585 duration_in_ms=49.012
2019-05-06 06:14:58,802 [salt.state       :1780][INFO    ][16353] Running state [iptables_extra.remove_stale_tables] at time 06:14:58.802202
2019-05-06 06:14:58,802 [salt.state       :1813][INFO    ][16353] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-06 06:14:58,802 [salt.state       :300 ][INFO    ][16353] No changes made for iptables_extra.remove_stale_tables
2019-05-06 06:14:58,802 [salt.state       :1951][INFO    ][16353] Completed state [iptables_extra.remove_stale_tables] at time 06:14:58.802940 duration_in_ms=0.739
2019-05-06 06:14:58,803 [salt.state       :1780][INFO    ][16353] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:14:58.803143
2019-05-06 06:14:58,803 [salt.state       :1813][INFO    ][16353] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-06 06:14:58,803 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16353] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-06 06:14:58,817 [salt.state       :300 ][INFO    ][16353] onlyif execution failed
2019-05-06 06:14:58,817 [salt.state       :1951][INFO    ][16353] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:14:58.817703 duration_in_ms=14.56
2019-05-06 06:14:58,818 [salt.state       :1780][INFO    ][16353] Running state [/etc/iptables/rules.v6] at time 06:14:58.818507
2019-05-06 06:14:58,818 [salt.state       :1813][INFO    ][16353] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-06 06:14:58,819 [salt.state       :300 ][INFO    ][16353] File /etc/iptables/rules.v6 is not present
2019-05-06 06:14:58,819 [salt.state       :1951][INFO    ][16353] Completed state [/etc/iptables/rules.v6] at time 06:14:58.819398 duration_in_ms=0.891
2019-05-06 06:14:58,820 [salt.state       :1780][INFO    ][16353] Running state [iptables_extra.flush_all] at time 06:14:58.819968
2019-05-06 06:14:58,820 [salt.state       :1813][INFO    ][16353] Executing state module.wait for [iptables_extra.flush_all]
2019-05-06 06:14:58,820 [salt.state       :300 ][INFO    ][16353] No changes made for iptables_extra.flush_all
2019-05-06 06:14:58,820 [salt.state       :1951][INFO    ][16353] Completed state [iptables_extra.flush_all] at time 06:14:58.820668 duration_in_ms=0.7
2019-05-06 06:14:58,823 [salt.minion      :1711][INFO    ][16353] Returning information for job: 20190506061450304611
2019-05-06 06:14:59,406 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506061459397240
2019-05-06 06:14:59,428 [salt.minion      :1432][INFO    ][16430] Starting a new job with PID 16430
2019-05-06 06:15:00,185 [salt.state       :915 ][INFO    ][16430] Loading fresh modules for state activity
2019-05-06 06:15:00,815 [salt.state       :1780][INFO    ][16430] Running state [maas-rack-controller] at time 06:15:00.815071
2019-05-06 06:15:00,815 [salt.state       :1813][INFO    ][16430] Executing state pkg.installed for [maas-rack-controller]
2019-05-06 06:15:00,815 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16430] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-06 06:15:00,898 [salt.state       :300 ][INFO    ][16430] All specified packages are already installed
2019-05-06 06:15:00,899 [salt.state       :1951][INFO    ][16430] Completed state [maas-rack-controller] at time 06:15:00.898958 duration_in_ms=83.888
2019-05-06 06:15:00,899 [salt.state       :1780][INFO    ][16430] Running state [ipmitool] at time 06:15:00.899240
2019-05-06 06:15:00,899 [salt.state       :1813][INFO    ][16430] Executing state pkg.installed for [ipmitool]
2019-05-06 06:15:00,904 [salt.state       :300 ][INFO    ][16430] All specified packages are already installed
2019-05-06 06:15:00,905 [salt.state       :1951][INFO    ][16430] Completed state [ipmitool] at time 06:15:00.904991 duration_in_ms=5.751
2019-05-06 06:15:00,907 [salt.state       :1780][INFO    ][16430] Running state [/etc/maas/rackd.conf] at time 06:15:00.907618
2019-05-06 06:15:00,907 [salt.state       :1813][INFO    ][16430] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-06 06:15:00,908 [salt.state       :300 ][INFO    ][16430] No changes needed to be made
2019-05-06 06:15:00,908 [salt.state       :1951][INFO    ][16430] Completed state [/etc/maas/rackd.conf] at time 06:15:00.908900 duration_in_ms=1.282
2019-05-06 06:15:00,909 [salt.state       :1780][INFO    ][16430] Running state [/etc/maas/rackd.conf] at time 06:15:00.909089
2019-05-06 06:15:00,909 [salt.state       :1813][INFO    ][16430] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-06 06:15:00,909 [salt.loaded.int.states.file:2298][WARNING ][16430] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-06 06:15:00,909 [salt.state       :300 ][INFO    ][16430] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-06 06:15:00,910 [salt.state       :1951][INFO    ][16430] Completed state [/etc/maas/rackd.conf] at time 06:15:00.910106 duration_in_ms=1.017
2019-05-06 06:15:00,910 [salt.state       :1780][INFO    ][16430] Running state [maas-rackd] at time 06:15:00.910900
2019-05-06 06:15:00,911 [salt.state       :1813][INFO    ][16430] Executing state service.running for [maas-rackd]
2019-05-06 06:15:00,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16430] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-06 06:15:00,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16430] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-06 06:15:00,961 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16430] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-06 06:15:00,977 [salt.state       :300 ][INFO    ][16430] The service maas-rackd is already running
2019-05-06 06:15:00,977 [salt.state       :1951][INFO    ][16430] Completed state [maas-rackd] at time 06:15:00.977322 duration_in_ms=66.421
2019-05-06 06:15:00,978 [salt.minion      :1711][INFO    ][16430] Returning information for job: 20190506061459397240
2019-05-06 06:15:01,516 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506061501503760
2019-05-06 06:15:01,535 [salt.minion      :1432][INFO    ][16463] Starting a new job with PID 16463
2019-05-06 06:15:02,235 [salt.state       :915 ][INFO    ][16463] Loading fresh modules for state activity
2019-05-06 06:15:02,910 [salt.state       :1780][INFO    ][16463] Running state [maas-region-controller] at time 06:15:02.910800
2019-05-06 06:15:02,911 [salt.state       :1813][INFO    ][16463] Executing state pkg.installed for [maas-region-controller]
2019-05-06 06:15:02,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16463] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-06 06:15:02,998 [salt.state       :300 ][INFO    ][16463] All specified packages are already installed
2019-05-06 06:15:02,998 [salt.state       :1951][INFO    ][16463] Completed state [maas-region-controller] at time 06:15:02.998342 duration_in_ms=87.541
2019-05-06 06:15:02,998 [salt.state       :1780][INFO    ][16463] Running state [python-oauth] at time 06:15:02.998653
2019-05-06 06:15:02,998 [salt.state       :1813][INFO    ][16463] Executing state pkg.installed for [python-oauth]
2019-05-06 06:15:03,004 [salt.state       :300 ][INFO    ][16463] All specified packages are already installed
2019-05-06 06:15:03,004 [salt.state       :1951][INFO    ][16463] Completed state [python-oauth] at time 06:15:03.004770 duration_in_ms=6.117
2019-05-06 06:15:03,007 [salt.state       :1780][INFO    ][16463] Running state [/etc/maas/regiond.conf] at time 06:15:03.007478
2019-05-06 06:15:03,007 [salt.state       :1813][INFO    ][16463] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-06 06:15:03,043 [salt.state       :300 ][INFO    ][16463] No changes needed to be made
2019-05-06 06:15:03,043 [salt.state       :1951][INFO    ][16463] Completed state [/etc/maas/regiond.conf] at time 06:15:03.043296 duration_in_ms=35.818
2019-05-06 06:15:03,043 [salt.state       :1780][INFO    ][16463] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:15:03.043734
2019-05-06 06:15:03,044 [salt.state       :1813][INFO    ][16463] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-06 06:15:03,114 [salt.state       :300 ][INFO    ][16463] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-06 06:15:03,114 [salt.state       :1951][INFO    ][16463] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:15:03.114702 duration_in_ms=70.968
2019-05-06 06:15:03,115 [salt.state       :1780][INFO    ][16463] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:15:03.115196
2019-05-06 06:15:03,115 [salt.state       :1813][INFO    ][16463] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-06 06:15:03,127 [salt.state       :300 ][INFO    ][16463] No changes needed to be made
2019-05-06 06:15:03,128 [salt.state       :1951][INFO    ][16463] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:15:03.128068 duration_in_ms=12.871
2019-05-06 06:15:03,128 [salt.state       :1780][INFO    ][16463] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:15:03.128545
2019-05-06 06:15:03,128 [salt.state       :1813][INFO    ][16463] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-06 06:15:03,151 [salt.state       :300 ][INFO    ][16463] No changes needed to be made
2019-05-06 06:15:03,152 [salt.state       :1951][INFO    ][16463] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:15:03.152055 duration_in_ms=23.509
2019-05-06 06:15:03,152 [salt.state       :1780][INFO    ][16463] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:15:03.152563
2019-05-06 06:15:03,152 [salt.state       :1813][INFO    ][16463] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-06 06:15:03,176 [salt.state       :300 ][INFO    ][16463] No changes needed to be made
2019-05-06 06:15:03,177 [salt.state       :1951][INFO    ][16463] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:15:03.177123 duration_in_ms=24.561
2019-05-06 06:15:03,177 [salt.state       :1780][INFO    ][16463] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:15:03.177569
2019-05-06 06:15:03,177 [salt.state       :1813][INFO    ][16463] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-06 06:15:03,189 [salt.state       :300 ][INFO    ][16463] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-06 06:15:03,189 [salt.state       :1951][INFO    ][16463] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:15:03.189337 duration_in_ms=11.767
2019-05-06 06:15:03,190 [salt.state       :1780][INFO    ][16463] Running state [a2enmod headers] at time 06:15:03.190504
2019-05-06 06:15:03,190 [salt.state       :1813][INFO    ][16463] Executing state cmd.run for [a2enmod headers]
2019-05-06 06:15:03,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16463] Executing command 'a2enmod headers' in directory '/root'
2019-05-06 06:15:03,262 [salt.state       :300 ][INFO    ][16463] {'pid': 16485, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-06 06:15:03,262 [salt.state       :1951][INFO    ][16463] Completed state [a2enmod headers] at time 06:15:03.262525 duration_in_ms=72.021
2019-05-06 06:15:03,263 [salt.state       :1780][INFO    ][16463] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:15:03.262978
2019-05-06 06:15:03,263 [salt.state       :1813][INFO    ][16463] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-06 06:15:03,278 [salt.state       :300 ][INFO    ][16463] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-06 06:15:03,278 [salt.state       :1951][INFO    ][16463] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:15:03.278638 duration_in_ms=15.66
2019-05-06 06:15:03,279 [salt.state       :1780][INFO    ][16463] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:15:03.279222
2019-05-06 06:15:03,279 [salt.state       :1813][INFO    ][16463] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-06 06:15:03,342 [salt.state       :300 ][INFO    ][16463] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-06 06:15:03,342 [salt.state       :1951][INFO    ][16463] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:15:03.342532 duration_in_ms=63.31
2019-05-06 06:15:03,343 [salt.state       :1780][INFO    ][16463] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:15:03.343042
2019-05-06 06:15:03,343 [salt.state       :1813][INFO    ][16463] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-06 06:15:03,408 [salt.state       :300 ][INFO    ][16463] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-06 06:15:03,408 [salt.state       :1951][INFO    ][16463] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:15:03.408668 duration_in_ms=65.625
2019-05-06 06:15:03,409 [salt.state       :1780][INFO    ][16463] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:15:03.409267
2019-05-06 06:15:03,409 [salt.state       :1813][INFO    ][16463] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-06 06:15:03,468 [salt.state       :300 ][INFO    ][16463] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-06 06:15:03,468 [salt.state       :1951][INFO    ][16463] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:15:03.468276 duration_in_ms=59.008
2019-05-06 06:15:03,468 [salt.state       :1780][INFO    ][16463] Running state [/root/.pgpass] at time 06:15:03.468558
2019-05-06 06:15:03,468 [salt.state       :1813][INFO    ][16463] Executing state file.managed for [/root/.pgpass]
2019-05-06 06:15:03,521 [salt.state       :300 ][INFO    ][16463] File /root/.pgpass is in the correct state
2019-05-06 06:15:03,522 [salt.state       :1951][INFO    ][16463] Completed state [/root/.pgpass] at time 06:15:03.522018 duration_in_ms=53.461
2019-05-06 06:15:03,526 [salt.state       :1780][INFO    ][16463] Running state [maas-region syncdb --noinput] at time 06:15:03.526006
2019-05-06 06:15:03,526 [salt.state       :1813][INFO    ][16463] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-06 06:15:03,526 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16463] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-06 06:15:05,718 [salt.state       :300 ][INFO    ][16463] {'pid': 16498, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, sessions, auth, maasserver, contenttypes, metadataserver, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-06 06:15:05,719 [salt.state       :1951][INFO    ][16463] Completed state [maas-region syncdb --noinput] at time 06:15:05.719065 duration_in_ms=2193.057
2019-05-06 06:15:05,719 [salt.state       :2022][WARNING ][16463] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-06 06:15:05,722 [salt.state       :1780][INFO    ][16463] Running state [maas-regiond] at time 06:15:05.722366
2019-05-06 06:15:05,722 [salt.state       :1813][INFO    ][16463] Executing state service.running for [maas-regiond]
2019-05-06 06:15:05,724 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16463] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-06 06:15:05,763 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16463] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-06 06:15:05,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16463] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-06 06:15:05,800 [salt.state       :300 ][INFO    ][16463] The service maas-regiond is already running
2019-05-06 06:15:05,801 [salt.state       :1951][INFO    ][16463] Completed state [maas-regiond] at time 06:15:05.801319 duration_in_ms=78.953
2019-05-06 06:15:05,803 [salt.state       :1780][INFO    ][16463] Running state [bind9] at time 06:15:05.803368
2019-05-06 06:15:05,803 [salt.state       :1813][INFO    ][16463] Executing state service.running for [bind9]
2019-05-06 06:15:05,804 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16463] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-06 06:15:05,826 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16463] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-06 06:15:05,844 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16463] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-06 06:15:05,861 [salt.state       :300 ][INFO    ][16463] The service bind9 is already running
2019-05-06 06:15:05,862 [salt.state       :1951][INFO    ][16463] Completed state [bind9] at time 06:15:05.861982 duration_in_ms=58.613
2019-05-06 06:15:05,864 [salt.state       :1780][INFO    ][16463] Running state [apache2] at time 06:15:05.864049
2019-05-06 06:15:05,864 [salt.state       :1813][INFO    ][16463] Executing state service.running for [apache2]
2019-05-06 06:15:05,865 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16463] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-06 06:15:05,887 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16463] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-06 06:15:05,905 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16463] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-06 06:15:05,928 [salt.state       :300 ][INFO    ][16463] The service apache2 is already running
2019-05-06 06:15:05,929 [salt.state       :1951][INFO    ][16463] Completed state [apache2] at time 06:15:05.928927 duration_in_ms=64.878
2019-05-06 06:15:05,930 [salt.state       :1780][INFO    ][16463] Running state [maasng.wait_for_http_code] at time 06:15:05.930441
2019-05-06 06:15:05,930 [salt.state       :1813][INFO    ][16463] Executing state module.run for [maasng.wait_for_http_code]
2019-05-06 06:15:05,931 [salt.utils.decorators:613 ][WARNING ][16463] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:15:06,062 [salt.state       :300 ][INFO    ][16463] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-06 06:15:06,062 [salt.state       :1951][INFO    ][16463] Completed state [maasng.wait_for_http_code] at time 06:15:06.062784 duration_in_ms=132.337
2019-05-06 06:15:06,064 [salt.state       :1780][INFO    ][16463] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:15:06.064231
2019-05-06 06:15:06,064 [salt.state       :1813][INFO    ][16463] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-06 06:15:06,065 [salt.state       :300 ][INFO    ][16463] /var/lib/maas/.setup_admin exists
2019-05-06 06:15:06,065 [salt.state       :1951][INFO    ][16463] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:15:06.065891 duration_in_ms=1.66
2019-05-06 06:15:06,067 [salt.state       :1780][INFO    ][16463] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:06.067061
2019-05-06 06:15:06,067 [salt.state       :1813][INFO    ][16463] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-06 06:15:06,068 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16463] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-06 06:15:07,628 [salt.state       :300 ][INFO    ][16463] {'pid': 16528, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-06 06:15:07,629 [salt.state       :1951][INFO    ][16463] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:07.628918 duration_in_ms=1561.857
2019-05-06 06:15:07,638 [salt.state       :1780][INFO    ][16463] Running state [maas_region_boot_source_resources_mirror] at time 06:15:07.637942
2019-05-06 06:15:07,638 [salt.state       :1813][INFO    ][16463] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-06 06:15:07,728 [salt.state       :300 ][INFO    ][16463] {'changes': {}}
2019-05-06 06:15:07,729 [salt.state       :1951][INFO    ][16463] Completed state [maas_region_boot_source_resources_mirror] at time 06:15:07.729034 duration_in_ms=91.092
2019-05-06 06:15:07,730 [salt.state       :1780][INFO    ][16463] Running state [maasng.boot_resources_import] at time 06:15:07.730050
2019-05-06 06:15:07,730 [salt.state       :1813][INFO    ][16463] Executing state module.run for [maasng.boot_resources_import]
2019-05-06 06:15:07,731 [salt.utils.decorators:613 ][WARNING ][16463] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:15:07,890 [salt.loaded.ext.module.maasng:1600][INFO    ][16463] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-06 06:15:12,951 [salt.loaded.ext.module.maasng:1600][INFO    ][16463] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-06 06:15:16,628 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061516610967
2019-05-06 06:15:16,647 [salt.minion      :1432][INFO    ][16670] Starting a new job with PID 16670
2019-05-06 06:15:16,669 [salt.minion      :1711][INFO    ][16670] Returning information for job: 20190506061516610967
2019-05-06 06:15:18,032 [salt.loaded.ext.module.maasng:1600][INFO    ][16463] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-05-06 06:15:23,105 [salt.state       :300 ][INFO    ][16463] {'ret': True}
2019-05-06 06:15:23,105 [salt.state       :1951][INFO    ][16463] Completed state [maasng.boot_resources_import] at time 06:15:23.105751 duration_in_ms=15375.702
2019-05-06 06:15:23,106 [salt.state       :1780][INFO    ][16463] Running state [maas_region_boot_sources_selection_xenial] at time 06:15:23.106220
2019-05-06 06:15:23,106 [salt.state       :1813][INFO    ][16463] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-06 06:15:23,243 [salt.state       :300 ][INFO    ][16463] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-06 06:15:23,243 [salt.state       :1951][INFO    ][16463] Completed state [maas_region_boot_sources_selection_xenial] at time 06:15:23.243764 duration_in_ms=137.543
2019-05-06 06:15:23,244 [salt.state       :1780][INFO    ][16463] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:15:23.244295
2019-05-06 06:15:23,244 [salt.state       :1813][INFO    ][16463] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-06 06:15:23,244 [salt.utils.decorators:613 ][WARNING ][16463] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:15:23,244 [salt.loaded.ext.module.maasng:1771][INFO    ][16463] boot-sources sync initiated for ALL Rack's
2019-05-06 06:15:23,825 [salt.state       :300 ][INFO    ][16463] {'ret': True}
2019-05-06 06:15:23,825 [salt.state       :1951][INFO    ][16463] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:15:23.825376 duration_in_ms=581.08
2019-05-06 06:15:23,826 [salt.state       :1780][INFO    ][16463] Running state [maas.process_maas_config] at time 06:15:23.826248
2019-05-06 06:15:23,826 [salt.state       :1813][INFO    ][16463] Executing state module.run for [maas.process_maas_config]
2019-05-06 06:15:23,826 [salt.utils.decorators:613 ][WARNING ][16463] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:15:23,827 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=enable_http_proxy value=True
2019-05-06 06:15:23,868 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=upstream_dns value=8.8.8.8
2019-05-06 06:15:23,932 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=commissioning_distro_series value=xenial
2019-05-06 06:15:23,980 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=default_osystem value=ubuntu
2019-05-06 06:15:24,022 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=active_discovery_interval value=600
2019-05-06 06:15:24,058 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=dnssec_validation value=no
2019-05-06 06:15:26,898 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=maas_name value=mas01
2019-05-06 06:15:26,960 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=network_discovery value=enabled
2019-05-06 06:15:27,124 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=enable_third_party_drivers value=True
2019-05-06 06:15:27,219 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=default_storage_layout value=lvm
2019-05-06 06:15:27,271 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=ntp_external_only value=True
2019-05-06 06:15:27,332 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-06 06:15:27,396 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=default_distro_series value=xenial
2019-05-06 06:15:27,450 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-06 06:15:27,528 [salt.state       :300 ][INFO    ][16463] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-06 06:15:27,529 [salt.state       :1951][INFO    ][16463] Completed state [maas.process_maas_config] at time 06:15:27.529259 duration_in_ms=3703.009
2019-05-06 06:15:27,529 [salt.state       :1780][INFO    ][16463] Running state [pxe_admin] at time 06:15:27.529786
2019-05-06 06:15:27,530 [salt.state       :1813][INFO    ][16463] Executing state maasng.fabric_present for [pxe_admin]
2019-05-06 06:15:27,570 [salt.loaded.ext.module.maasng:945 ][INFO    ][16463] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:15:27,618 [salt.loaded.ext.module.maasng:1008][WARNING ][16463] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-06 06:15:27,618 [salt.loaded.ext.module.maasng:1011][WARNING ][16463] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-06 06:15:27,691 [salt.state       :300 ][INFO    ][16463] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-06 06:15:27,691 [salt.state       :1951][INFO    ][16463] Completed state [pxe_admin] at time 06:15:27.691475 duration_in_ms=161.683
2019-05-06 06:15:27,691 [salt.state       :1780][INFO    ][16463] Running state [vlan 0] at time 06:15:27.691835
2019-05-06 06:15:27,692 [salt.state       :1813][INFO    ][16463] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-06 06:15:27,744 [salt.loaded.ext.module.maasng:945 ][INFO    ][16463] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:15:27,833 [salt.loaded.ext.module.maasng:945 ][INFO    ][16463] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:15:28,044 [salt.loaded.ext.module.maasng:945 ][INFO    ][16463] [{u'id': 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': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 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': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, 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'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:15:28,139 [salt.state       :300 ][INFO    ][16463] {'new': 'Vlan untagged was updated'}
2019-05-06 06:15:28,140 [salt.state       :1951][INFO    ][16463] Completed state [vlan 0] at time 06:15:28.140314 duration_in_ms=448.478
2019-05-06 06:15:28,141 [salt.state       :1780][INFO    ][16463] Running state [192.168.11.0/24] at time 06:15:28.141900
2019-05-06 06:15:28,142 [salt.state       :1813][INFO    ][16463] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-06 06:15:28,343 [salt.loaded.ext.module.maasng:945 ][INFO    ][16463] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:15:28,344 [salt.loaded.ext.module.maasng:1235][WARNING ][16463] Ignoring parameter vlan:0
2019-05-06 06:15:28,417 [salt.state       :300 ][INFO    ][16463] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-06 06:15:28,417 [salt.state       :1951][INFO    ][16463] Completed state [192.168.11.0/24] at time 06:15:28.417546 duration_in_ms=275.647
2019-05-06 06:15:28,418 [salt.state       :1780][INFO    ][16463] Running state [maas_create_iprange_1] at time 06:15:28.418259
2019-05-06 06:15:28,418 [salt.state       :1813][INFO    ][16463] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-06 06:15:28,463 [salt.state       :300 ][INFO    ][16463] Iprange maas_create_iprange_1 already exist.
2019-05-06 06:15:28,463 [salt.state       :1951][INFO    ][16463] Completed state [maas_create_iprange_1] at time 06:15:28.463520 duration_in_ms=45.26
2019-05-06 06:15:28,463 [salt.state       :1780][INFO    ][16463] Running state [vlan 0] at time 06:15:28.463870
2019-05-06 06:15:28,464 [salt.state       :1813][INFO    ][16463] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-06 06:15:28,517 [salt.loaded.ext.module.maasng:945 ][INFO    ][16463] [{u'id': 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': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 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': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, 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'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:15:28,606 [salt.loaded.ext.module.maasng:945 ][INFO    ][16463] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:15:29,001 [salt.loaded.ext.module.maasng:945 ][INFO    ][16463] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:15:29,716 [salt.state       :300 ][INFO    ][16463] {'new': 'Vlan untagged was updated'}
2019-05-06 06:15:29,717 [salt.state       :1951][INFO    ][16463] Completed state [vlan 0] at time 06:15:29.716986 duration_in_ms=1253.113
2019-05-06 06:15:29,718 [salt.state       :1780][INFO    ][16463] Running state [opnfv] at time 06:15:29.717965
2019-05-06 06:15:29,718 [salt.state       :1813][INFO    ][16463] Executing state maasng.sshkey_present for [opnfv]
2019-05-06 06:15:29,774 [salt.loaded.ext.module.maasng:1903][INFO    ][16463] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-06 06:15:29,775 [salt.state       :300 ][INFO    ][16463] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-05-06 06:15:29,775 [salt.state       :1951][INFO    ][16463] Completed state [opnfv] at time 06:15:29.775533 duration_in_ms=57.569
2019-05-06 06:15:29,776 [salt.state       :1780][INFO    ][16463] Running state [maas.process_tags] at time 06:15:29.776334
2019-05-06 06:15:29,776 [salt.state       :1813][INFO    ][16463] Executing state module.run for [maas.process_tags]
2019-05-06 06:15:29,777 [salt.utils.decorators:613 ][WARNING ][16463] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:15:29,841 [salt.loaded.ext.module.maas:92  ][INFO    ][16463] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-06 06:15:29,927 [salt.state       :300 ][INFO    ][16463] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-06 06:15:29,928 [salt.state       :1951][INFO    ][16463] Completed state [maas.process_tags] at time 06:15:29.927976 duration_in_ms=151.639
2019-05-06 06:15:29,933 [salt.minion      :1711][INFO    ][16463] Returning information for job: 20190506061501503760
2019-05-06 06:15:30,449 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506061530437131
2019-05-06 06:15:30,469 [salt.minion      :1432][INFO    ][17624] Starting a new job with PID 17624
2019-05-06 06:15:34,120 [salt.state       :915 ][INFO    ][17624] Loading fresh modules for state activity
2019-05-06 06:15:34,210 [salt.state       :1780][INFO    ][17624] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:34.210544
2019-05-06 06:15:34,210 [salt.state       :1813][INFO    ][17624] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-06 06:15:34,212 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17624] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-06 06:15:35,622 [salt.state       :300 ][INFO    ][17624] {'pid': 17668, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-06 06:15:35,623 [salt.state       :1951][INFO    ][17624] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:35.623303 duration_in_ms=1412.76
2019-05-06 06:15:35,624 [salt.state       :1780][INFO    ][17624] Running state [maas.process_machines] at time 06:15:35.624481
2019-05-06 06:15:35,624 [salt.state       :1813][INFO    ][17624] Executing state module.run for [maas.process_machines]
2019-05-06 06:15:35,625 [salt.utils.decorators:613 ][WARNING ][17624] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:15:35,976 [salt.loaded.ext.module.maas:412 ][WARNING ][17624] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:15:35,977 [salt.loaded.ext.module.maas:92  ][INFO    ][17624] 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=ebyckb architecture=amd64/generic power_parameters_power_user=admin
2019-05-06 06:15:36,915 [salt.loaded.ext.module.maas:412 ][WARNING ][17624] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:15:36,916 [salt.loaded.ext.module.maas:92  ][INFO    ][17624] 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=8g483t architecture=amd64/generic power_parameters_power_user=admin
2019-05-06 06:15:37,926 [salt.loaded.ext.module.maas:412 ][WARNING ][17624] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:15:37,927 [salt.loaded.ext.module.maas:92  ][INFO    ][17624] 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=dayss6 architecture=amd64/generic power_parameters_power_user=admin
2019-05-06 06:15:38,843 [salt.loaded.ext.module.maas:412 ][WARNING ][17624] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:15:38,844 [salt.loaded.ext.module.maas:92  ][INFO    ][17624] 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=mppbah architecture=amd64/generic power_parameters_power_user=admin
2019-05-06 06:15:40,046 [salt.loaded.ext.module.maas:412 ][WARNING ][17624] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:15:40,047 [salt.loaded.ext.module.maas:92  ][INFO    ][17624] 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=6haq6x architecture=amd64/generic power_parameters_power_user=admin
2019-05-06 06:15:40,974 [salt.state       :300 ][INFO    ][17624] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-06 06:15:40,975 [salt.state       :1951][INFO    ][17624] Completed state [maas.process_machines] at time 06:15:40.975044 duration_in_ms=5350.561
2019-05-06 06:15:40,979 [salt.minion      :1711][INFO    ][17624] Returning information for job: 20190506061530437131
2019-05-06 06:16:12,106 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506061612094726
2019-05-06 06:16:12,128 [salt.minion      :1432][INFO    ][17893] Starting a new job with PID 17893
2019-05-06 06:16:15,703 [salt.state       :915 ][INFO    ][17893] Loading fresh modules for state activity
2019-05-06 06:16:15,815 [salt.state       :1780][INFO    ][17893] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:16:15.815502
2019-05-06 06:16:15,815 [salt.state       :1813][INFO    ][17893] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-06 06:16:15,818 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17893] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-06 06:16:17,227 [salt.state       :300 ][INFO    ][17893] {'pid': 17924, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-06 06:16:17,228 [salt.state       :1951][INFO    ][17893] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:16:17.227960 duration_in_ms=1412.459
2019-05-06 06:16:17,230 [salt.state       :1780][INFO    ][17893] Running state [maas.wait_for_machine_status] at time 06:16:17.230411
2019-05-06 06:16:17,230 [salt.state       :1813][INFO    ][17893] Executing state module.run for [maas.wait_for_machine_status]
2019-05-06 06:16:17,231 [salt.utils.decorators:613 ][WARNING ][17893] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:16:20,326 [salt.loaded.ext.module.maas:1023][INFO    ][17893] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1496.91182017s left)
2019-05-06 06:16:27,208 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061627195952
2019-05-06 06:16:27,227 [salt.minion      :1432][INFO    ][18211] Starting a new job with PID 18211
2019-05-06 06:16:27,251 [salt.minion      :1711][INFO    ][18211] Returning information for job: 20190506061627195952
2019-05-06 06:16:54,000 [salt.state       :300 ][INFO    ][17893] {'ret': True}
2019-05-06 06:16:54,000 [salt.state       :1951][INFO    ][17893] Completed state [maas.wait_for_machine_status] at time 06:16:54.000499 duration_in_ms=36770.086
2019-05-06 06:16:54,004 [salt.minion      :1711][INFO    ][17893] Returning information for job: 20190506061612094726
2019-05-06 06:16:54,648 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506061654638723
2019-05-06 06:16:54,670 [salt.minion      :1432][INFO    ][18276] Starting a new job with PID 18276
2019-05-06 06:16:58,437 [salt.state       :915 ][INFO    ][18276] Loading fresh modules for state activity
2019-05-06 06:16:58,490 [salt.fileclient  :1219][INFO    ][18276] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-06 06:16:58,586 [salt.state       :1780][INFO    ][18276] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:16:58.586210
2019-05-06 06:16:58,586 [salt.state       :1813][INFO    ][18276] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-06 06:16:58,588 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18276] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-06 06:17:00,106 [salt.state       :300 ][INFO    ][18276] {'pid': 18283, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-06 06:17:00,107 [salt.state       :1951][INFO    ][18276] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:17:00.107739 duration_in_ms=1521.528
2019-05-06 06:17:00,111 [salt.state       :1780][INFO    ][18276] Running state [maas_machines_storage_cmp002_lvm] at time 06:17:00.111023
2019-05-06 06:17:00,111 [salt.state       :1813][INFO    ][18276] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-06 06:17:01,552 [salt.loaded.ext.module.maasng:610 ][INFO    ][18276] ebyckb
2019-05-06 06:17:01,553 [salt.loaded.ext.module.maasng:626 ][INFO    ][18276] sda
2019-05-06 06:17:02,269 [salt.loaded.ext.module.maasng:361 ][INFO    ][18276] ebyckb
2019-05-06 06:17:02,408 [salt.loaded.ext.module.maasng:367 ][INFO    ][18276] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/blockdevices/2/', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'fb6c9e35-84ad-4dc5-8738-da1fef3bb593', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ebyckb', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'e6a0a091-5a7a-42da-a031-1a6feb902918'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/blockdevices/2/partition/7'}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ebyckb', 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'block_size': 4096, u'type': u'physical', u'id': 2, u'uuid': None}, {u'size': 2397988454400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/blockdevices/12/', u'used_size': 2397988454400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ebyckb', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'e807936a-751c-4b56-a60d-a172c15863a0'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'uuid': u'aadb7404-372b-4fe0-9c21-e89291d5ea24'}]
2019-05-06 06:17:02,409 [salt.loaded.ext.module.maasng:632 ][INFO    ][18276] vgroot
2019-05-06 06:17:02,409 [salt.loaded.ext.module.maasng:635 ][INFO    ][18276] lvroot
2019-05-06 06:17:02,410 [salt.loaded.ext.module.maasng:639 ][INFO    ][18276] 107374182400
2019-05-06 06:17:03,109 [salt.loaded.ext.module.maasng:645 ][INFO    ][18276] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, 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'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'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'mode': u'dhcp', u'id': 40}], 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'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'system_id': u'ebyckb', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/interfaces/4/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'2e970b50-96b4-4e0b-87f6-f81783012b3e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ebyckb', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2b9eb1ce-6c7f-4be2-b196-778f55f3ecdf', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/blockdevices/2/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'uuid': None, u'id': 2, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ebyckb', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/blockdevices/2/'}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/ebyckb/', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'ebyckb', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.40'], u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'2e970b50-96b4-4e0b-87f6-f81783012b3e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ebyckb', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2b9eb1ce-6c7f-4be2-b196-778f55f3ecdf', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/blockdevices/2/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'uuid': None, u'id': 2, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ebyckb', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/blockdevices/2/'}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'4fe8b822-3bd1-49fc-b658-1069b8609e0a', u'id': 14, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'ebyckb', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5e6cc19c-9ee9-49e5-9409-4bae22d02bd2', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/blockdevices/14/'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'2e970b50-96b4-4e0b-87f6-f81783012b3e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ebyckb', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2b9eb1ce-6c7f-4be2-b196-778f55f3ecdf', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/blockdevices/2/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'uuid': None, u'id': 2, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ebyckb', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/blockdevices/2/'}], u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ebyckb', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', 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'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'mode': u'dhcp', u'id': 40}], 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'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'system_id': u'ebyckb', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/interfaces/4/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 42}], 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'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'parents': [], u'system_id': u'ebyckb', u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/interfaces/12/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 44}], 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'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'parents': [], u'system_id': u'ebyckb', u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/interfaces/13/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 46}], 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'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'parents': [], u'system_id': u'ebyckb', u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/interfaces/17/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'4fe8b822-3bd1-49fc-b658-1069b8609e0a', u'id': 14, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'ebyckb', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5e6cc19c-9ee9-49e5-9409-4bae22d02bd2', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ebyckb/blockdevices/14/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-05-06 06:17:03,111 [salt.state       :300 ][INFO    ][18276] {'new': {'storage_layout': 'lvm'}}
2019-05-06 06:17:03,112 [salt.state       :1951][INFO    ][18276] Completed state [maas_machines_storage_cmp002_lvm] at time 06:17:03.111913 duration_in_ms=3000.888
2019-05-06 06:17:03,112 [salt.state       :1780][INFO    ][18276] Running state [maas_machines_storage_cmp001_lvm] at time 06:17:03.112503
2019-05-06 06:17:03,113 [salt.state       :1813][INFO    ][18276] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-06 06:17:04,377 [salt.loaded.ext.module.maasng:610 ][INFO    ][18276] 8g483t
2019-05-06 06:17:04,377 [salt.loaded.ext.module.maasng:626 ][INFO    ][18276] sda
2019-05-06 06:17:05,106 [salt.loaded.ext.module.maasng:361 ][INFO    ][18276] 8g483t
2019-05-06 06:17:05,233 [salt.loaded.ext.module.maasng:367 ][INFO    ][18276] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'39550d27-d75c-434e-ab5f-daec160113b0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8g483t', u'device_id': 5, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'9ce06a64-2055-4fd9-8c59-be843428b5e2', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/blockdevices/5/partition/5', u'type': u'partition', u'id': 5, u'size': 2397992648704}], u'uuid': None, u'id': 5, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'8g483t', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/blockdevices/5/'}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'uuid': u'663883b0-b901-4bfb-9881-ab0a1b52dda1', u'id': 10, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'8g483t', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'f696ab3b-8cbf-4ebf-8e75-c35790c0639a', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 2397988454400, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/blockdevices/10/'}]
2019-05-06 06:17:05,234 [salt.loaded.ext.module.maasng:632 ][INFO    ][18276] vgroot
2019-05-06 06:17:05,234 [salt.loaded.ext.module.maasng:635 ][INFO    ][18276] lvroot
2019-05-06 06:17:05,234 [salt.loaded.ext.module.maasng:639 ][INFO    ][18276] 107374182400
2019-05-06 06:17:05,943 [salt.loaded.ext.module.maasng:645 ][INFO    ][18276] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', 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'boot_interface': {u'children': [], 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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': 35, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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'params': u'', u'effective_mtu': 1500, u'system_id': u'8g483t', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/blockdevices/5/', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'272447cb-3938-46ab-bb72-030e8db60382', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8g483t', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'e3fd77fd-6ad7-4bf5-be2b-46c52c3de99d'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/blockdevices/5/partition/10'}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'8g483t', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 5, u'uuid': None}, {u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/blockdevices/15/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8g483t', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'5b8ddac7-a88f-45a2-9c44-63c4df8ad224'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'uuid': u'fafe9e0f-34a4-478b-8837-c634259e8512'}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'8g483t', u'power_state': u'off', 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'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/blockdevices/15/', u'used_size': 107374182400, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'5b8ddac7-a88f-45a2-9c44-63c4df8ad224'}, u'uuid': u'fafe9e0f-34a4-478b-8837-c634259e8512', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8g483t', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'tags': [], u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'8g483t', u'id': 10}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/blockdevices/5/', u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'8g483t', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 5, u'tags': [u'rotary'], u'partitions': [{u'size': 2397992648704, u'uuid': u'272447cb-3938-46ab-bb72-030e8db60382', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8g483t', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'e3fd77fd-6ad7-4bf5-be2b-46c52c3de99d'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/blockdevices/5/partition/10'}]}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'current_commissioning_result_id': 4, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/blockdevices/5/', u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'8g483t', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 5, u'tags': [u'rotary'], u'partitions': [{u'size': 2397992648704, u'uuid': u'272447cb-3938-46ab-bb72-030e8db60382', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8g483t', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'e3fd77fd-6ad7-4bf5-be2b-46c52c3de99d'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/blockdevices/5/partition/10'}]}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'children': [], 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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': 35, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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'params': u'', u'effective_mtu': 1500, u'system_id': u'8g483t', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/interfaces/5/'}, {u'children': [], u'name': u'enp7s0', u'links': [{u'id': 36, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'effective_mtu': 1500, u'system_id': u'8g483t', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/interfaces/21/'}, {u'children': [], u'name': u'enp8s0', u'links': [{u'id': 37, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'effective_mtu': 1500, u'system_id': u'8g483t', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/interfaces/22/'}, {u'children': [], u'name': u'enp9s0', u'links': [{u'id': 38, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'effective_mtu': 1500, u'system_id': u'8g483t', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/8g483t/interfaces/23/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/8g483t/'}
2019-05-06 06:17:05,945 [salt.state       :300 ][INFO    ][18276] {'new': {'storage_layout': 'lvm'}}
2019-05-06 06:17:05,945 [salt.state       :1951][INFO    ][18276] Completed state [maas_machines_storage_cmp001_lvm] at time 06:17:05.945577 duration_in_ms=2833.074
2019-05-06 06:17:05,949 [salt.minion      :1711][INFO    ][18276] Returning information for job: 20190506061654638723
2019-05-06 06:17:06,554 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506061706540788
2019-05-06 06:17:06,575 [salt.minion      :1432][INFO    ][18306] Starting a new job with PID 18306
2019-05-06 06:17:07,209 [salt.state       :915 ][INFO    ][18306] Loading fresh modules for state activity
2019-05-06 06:17:07,236 [salt.fileclient  :1219][INFO    ][18306] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-06 06:17:07,262 [salt.state       :1780][INFO    ][18306] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:17:07.262386
2019-05-06 06:17:07,262 [salt.state       :1813][INFO    ][18306] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-06 06:17:07,263 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18306] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-06 06:17:08,682 [salt.state       :300 ][INFO    ][18306] {'pid': 18313, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-06 06:17:08,683 [salt.state       :1951][INFO    ][18306] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:17:08.683343 duration_in_ms=1420.958
2019-05-06 06:17:08,684 [salt.state       :1780][INFO    ][18306] Running state [maas.deploy_machines] at time 06:17:08.684508
2019-05-06 06:17:08,684 [salt.state       :1813][INFO    ][18306] Executing state module.run for [maas.deploy_machines]
2019-05-06 06:17:08,685 [salt.utils.decorators:613 ][WARNING ][18306] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:17:09,361 [salt.loaded.ext.module.maas:684 ][INFO    ][18306] deploymachines hwe_kernel=hwe-16.04 system_id=ebyckb distro_series=xenial
2019-05-06 06:17:12,141 [salt.loaded.ext.module.maas:684 ][INFO    ][18306] deploymachines hwe_kernel=hwe-16.04 system_id=8g483t distro_series=xenial
2019-05-06 06:17:14,926 [salt.loaded.ext.module.maas:684 ][INFO    ][18306] deploymachines hwe_kernel=hwe-16.04 system_id=dayss6 distro_series=xenial
2019-05-06 06:17:17,597 [salt.loaded.ext.module.maas:684 ][INFO    ][18306] deploymachines hwe_kernel=hwe-16.04 system_id=mppbah distro_series=xenial
2019-05-06 06:17:20,494 [salt.loaded.ext.module.maas:684 ][INFO    ][18306] deploymachines hwe_kernel=hwe-16.04 system_id=6haq6x distro_series=xenial
2019-05-06 06:17:21,604 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061721591045
2019-05-06 06:17:21,626 [salt.minion      :1432][INFO    ][18573] Starting a new job with PID 18573
2019-05-06 06:17:21,650 [salt.minion      :1711][INFO    ][18573] Returning information for job: 20190506061721591045
2019-05-06 06:17:23,119 [salt.state       :300 ][INFO    ][18306] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-06 06:17:23,119 [salt.state       :1951][INFO    ][18306] Completed state [maas.deploy_machines] at time 06:17:23.119532 duration_in_ms=14435.022
2019-05-06 06:17:23,123 [salt.minion      :1711][INFO    ][18306] Returning information for job: 20190506061706540788
2019-05-06 06:17:23,713 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506061723700039
2019-05-06 06:17:23,734 [salt.minion      :1432][INFO    ][18630] Starting a new job with PID 18630
2019-05-06 06:17:27,499 [salt.state       :915 ][INFO    ][18630] Loading fresh modules for state activity
2019-05-06 06:17:27,550 [salt.fileclient  :1219][INFO    ][18630] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-06 06:17:27,591 [salt.state       :1780][INFO    ][18630] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:17:27.590937
2019-05-06 06:17:27,591 [salt.state       :1813][INFO    ][18630] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-06 06:17:27,593 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18630] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-06 06:17:29,036 [salt.state       :300 ][INFO    ][18630] {'pid': 18644, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-06 06:17:29,037 [salt.state       :1951][INFO    ][18630] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:17:29.037384 duration_in_ms=1446.448
2019-05-06 06:17:29,038 [salt.state       :1780][INFO    ][18630] Running state [maas.wait_for_machine_status] at time 06:17:29.038923
2019-05-06 06:17:29,039 [salt.state       :1813][INFO    ][18630] Executing state module.run for [maas.wait_for_machine_status]
2019-05-06 06:17:29,039 [salt.utils.decorators:613 ][WARNING ][18630] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:17:31,684 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.35925508s left)
2019-05-06 06:17:38,781 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061738769767
2019-05-06 06:17:38,804 [salt.minion      :1432][INFO    ][18656] Starting a new job with PID 18656
2019-05-06 06:17:38,827 [salt.minion      :1711][INFO    ][18656] Returning information for job: 20190506061738769767
2019-05-06 06:18:04,989 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.05472398s left)
2019-05-06 06:18:08,823 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061808810898
2019-05-06 06:18:08,846 [salt.minion      :1432][INFO    ][18676] Starting a new job with PID 18676
2019-05-06 06:18:08,869 [salt.minion      :1711][INFO    ][18676] Returning information for job: 20190506061808810898
2019-05-06 06:18:38,474 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.56969905s left)
2019-05-06 06:18:38,875 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061838861984
2019-05-06 06:18:38,897 [salt.minion      :1432][INFO    ][18748] Starting a new job with PID 18748
2019-05-06 06:18:38,922 [salt.minion      :1711][INFO    ][18748] Returning information for job: 20190506061838861984
2019-05-06 06:19:08,909 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061908897666
2019-05-06 06:19:08,930 [salt.minion      :1432][INFO    ][18859] Starting a new job with PID 18859
2019-05-06 06:19:08,951 [salt.minion      :1711][INFO    ][18859] Returning information for job: 20190506061908897666
2019-05-06 06:19:11,987 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.05625391s left)
2019-05-06 06:19:38,967 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506061938953045
2019-05-06 06:19:38,987 [salt.minion      :1432][INFO    ][19311] Starting a new job with PID 19311
2019-05-06 06:19:39,008 [salt.minion      :1711][INFO    ][19311] Returning information for job: 20190506061938953045
2019-05-06 06:19:45,285 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.75837207s left)
2019-05-06 06:20:09,014 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062008999953
2019-05-06 06:20:09,037 [salt.minion      :1432][INFO    ][19359] Starting a new job with PID 19359
2019-05-06 06:20:09,061 [salt.minion      :1711][INFO    ][19359] Returning information for job: 20190506062008999953
2019-05-06 06:20:18,442 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.60178304s left)
2019-05-06 06:20:39,070 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062039057083
2019-05-06 06:20:39,092 [salt.minion      :1432][INFO    ][19829] Starting a new job with PID 19829
2019-05-06 06:20:39,116 [salt.minion      :1711][INFO    ][19829] Returning information for job: 20190506062039057083
2019-05-06 06:20:51,823 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.22085595s left)
2019-05-06 06:21:09,126 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062109113982
2019-05-06 06:21:09,149 [salt.minion      :1432][INFO    ][19854] Starting a new job with PID 19854
2019-05-06 06:21:09,171 [salt.minion      :1711][INFO    ][19854] Returning information for job: 20190506062109113982
2019-05-06 06:21:25,037 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2014.00713897s left)
2019-05-06 06:21:39,188 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062139175671
2019-05-06 06:21:39,212 [salt.minion      :1432][INFO    ][20021] Starting a new job with PID 20021
2019-05-06 06:21:39,236 [salt.minion      :1711][INFO    ][20021] Returning information for job: 20190506062139175671
2019-05-06 06:21:58,519 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.52447701s left)
2019-05-06 06:22:09,257 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062209243767
2019-05-06 06:22:09,279 [salt.minion      :1432][INFO    ][20047] Starting a new job with PID 20047
2019-05-06 06:22:09,301 [salt.minion      :1711][INFO    ][20047] Returning information for job: 20190506062209243767
2019-05-06 06:22:32,419 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.62462711s left)
2019-05-06 06:22:39,327 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062239313888
2019-05-06 06:22:39,350 [salt.minion      :1432][INFO    ][20584] Starting a new job with PID 20584
2019-05-06 06:22:39,375 [salt.minion      :1711][INFO    ][20584] Returning information for job: 20190506062239313888
2019-05-06 06:23:06,029 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.01434994s left)
2019-05-06 06:23:09,407 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062309394696
2019-05-06 06:23:09,430 [salt.minion      :1432][INFO    ][20628] Starting a new job with PID 20628
2019-05-06 06:23:09,453 [salt.minion      :1711][INFO    ][20628] Returning information for job: 20190506062309394696
2019-05-06 06:23:39,491 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062339477673
2019-05-06 06:23:39,496 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.54723907s left)
2019-05-06 06:23:39,511 [salt.minion      :1432][INFO    ][20994] Starting a new job with PID 20994
2019-05-06 06:23:39,534 [salt.minion      :1711][INFO    ][20994] Returning information for job: 20190506062339477673
2019-05-06 06:24:09,571 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062409560500
2019-05-06 06:24:09,593 [salt.minion      :1432][INFO    ][21010] Starting a new job with PID 21010
2019-05-06 06:24:09,617 [salt.minion      :1711][INFO    ][21010] Returning information for job: 20190506062409560500
2019-05-06 06:24:12,954 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.09003401s left)
2019-05-06 06:24:39,671 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062439659305
2019-05-06 06:24:39,692 [salt.minion      :1432][INFO    ][21182] Starting a new job with PID 21182
2019-05-06 06:24:39,715 [salt.minion      :1711][INFO    ][21182] Returning information for job: 20190506062439659305
2019-05-06 06:24:46,211 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.83262801s left)
2019-05-06 06:25:09,775 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062509763617
2019-05-06 06:25:09,799 [salt.minion      :1432][INFO    ][21377] Starting a new job with PID 21377
2019-05-06 06:25:09,822 [salt.minion      :1711][INFO    ][21377] Returning information for job: 20190506062509763617
2019-05-06 06:25:19,686 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1779.35795093s left)
2019-05-06 06:25:39,880 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062539867294
2019-05-06 06:25:39,903 [salt.minion      :1432][INFO    ][21760] Starting a new job with PID 21760
2019-05-06 06:25:39,928 [salt.minion      :1711][INFO    ][21760] Returning information for job: 20190506062539867294
2019-05-06 06:25:53,273 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1745.770684s left)
2019-05-06 06:26:09,993 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062609980150
2019-05-06 06:26:10,016 [salt.minion      :1432][INFO    ][21780] Starting a new job with PID 21780
2019-05-06 06:26:10,041 [salt.minion      :1711][INFO    ][21780] Returning information for job: 20190506062609980150
2019-05-06 06:26:26,679 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1712.36428809s left)
2019-05-06 06:26:40,118 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062640106123
2019-05-06 06:26:40,141 [salt.minion      :1432][INFO    ][21892] Starting a new job with PID 21892
2019-05-06 06:26:40,164 [salt.minion      :1711][INFO    ][21892] Returning information for job: 20190506062640106123
2019-05-06 06:27:00,238 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1678.805969s left)
2019-05-06 06:27:10,247 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062710233620
2019-05-06 06:27:10,266 [salt.minion      :1432][INFO    ][21913] Starting a new job with PID 21913
2019-05-06 06:27:10,290 [salt.minion      :1711][INFO    ][21913] Returning information for job: 20190506062710233620
2019-05-06 06:27:33,401 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1645.64303303s left)
2019-05-06 06:27:40,383 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062740371627
2019-05-06 06:27:40,407 [salt.minion      :1432][INFO    ][21969] Starting a new job with PID 21969
2019-05-06 06:27:40,431 [salt.minion      :1711][INFO    ][21969] Returning information for job: 20190506062740371627
2019-05-06 06:28:07,251 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1611.7924509s left)
2019-05-06 06:28:10,529 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062810517069
2019-05-06 06:28:10,552 [salt.minion      :1432][INFO    ][21989] Starting a new job with PID 21989
2019-05-06 06:28:10,575 [salt.minion      :1711][INFO    ][21989] Returning information for job: 20190506062810517069
2019-05-06 06:28:40,687 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062840674882
2019-05-06 06:28:40,708 [salt.minion      :1432][INFO    ][22065] Starting a new job with PID 22065
2019-05-06 06:28:40,731 [salt.minion      :1711][INFO    ][22065] Returning information for job: 20190506062840674882
2019-05-06 06:28:40,923 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1578.12189293s left)
2019-05-06 06:29:10,844 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062910830897
2019-05-06 06:29:10,866 [salt.minion      :1432][INFO    ][22095] Starting a new job with PID 22095
2019-05-06 06:29:10,890 [salt.minion      :1711][INFO    ][22095] Returning information for job: 20190506062910830897
2019-05-06 06:29:14,388 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1544.65538788s left)
2019-05-06 06:29:41,020 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506062941007140
2019-05-06 06:29:41,043 [salt.minion      :1432][INFO    ][22157] Starting a new job with PID 22157
2019-05-06 06:29:41,066 [salt.minion      :1711][INFO    ][22157] Returning information for job: 20190506062941007140
2019-05-06 06:29:47,779 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1511.264889s left)
2019-05-06 06:30:11,206 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063011193559
2019-05-06 06:30:11,229 [salt.minion      :1432][INFO    ][22183] Starting a new job with PID 22183
2019-05-06 06:30:11,256 [salt.minion      :1711][INFO    ][22183] Returning information for job: 20190506063011193559
2019-05-06 06:30:21,191 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1477.85261798s left)
2019-05-06 06:30:41,406 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063041392992
2019-05-06 06:30:41,430 [salt.minion      :1432][INFO    ][22237] Starting a new job with PID 22237
2019-05-06 06:30:41,454 [salt.minion      :1711][INFO    ][22237] Returning information for job: 20190506063041392992
2019-05-06 06:30:54,305 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1444.73903489s left)
2019-05-06 06:31:11,615 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063111602387
2019-05-06 06:31:11,638 [salt.minion      :1432][INFO    ][22258] Starting a new job with PID 22258
2019-05-06 06:31:11,663 [salt.minion      :1711][INFO    ][22258] Returning information for job: 20190506063111602387
2019-05-06 06:31:27,004 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1412.03935409s left)
2019-05-06 06:31:41,829 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063141816106
2019-05-06 06:31:41,853 [salt.minion      :1432][INFO    ][22310] Starting a new job with PID 22310
2019-05-06 06:31:41,876 [salt.minion      :1711][INFO    ][22310] Returning information for job: 20190506063141816106
2019-05-06 06:32:00,362 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1378.68148994s left)
2019-05-06 06:32:12,048 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063212035054
2019-05-06 06:32:12,071 [salt.minion      :1432][INFO    ][22331] Starting a new job with PID 22331
2019-05-06 06:32:12,096 [salt.minion      :1711][INFO    ][22331] Returning information for job: 20190506063212035054
2019-05-06 06:32:33,148 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1345.89573193s left)
2019-05-06 06:32:42,272 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063242260955
2019-05-06 06:32:42,297 [salt.minion      :1432][INFO    ][22385] Starting a new job with PID 22385
2019-05-06 06:32:42,322 [salt.minion      :1711][INFO    ][22385] Returning information for job: 20190506063242260955
2019-05-06 06:33:06,833 [salt.loaded.ext.module.maas:1023][INFO    ][18630] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1312.21027493s left)
2019-05-06 06:33:12,301 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063312288869
2019-05-06 06:33:12,326 [salt.minion      :1432][INFO    ][22408] Starting a new job with PID 22408
2019-05-06 06:33:12,351 [salt.minion      :1711][INFO    ][22408] Returning information for job: 20190506063312288869
2019-05-06 06:33:40,308 [salt.loaded.ext.module.maas:993 ][INFO    ][18630] Machine 6haq6x mark broken
2019-05-06 06:33:41,064 [salt.loaded.ext.module.maas:996 ][INFO    ][18630] Machine 6haq6x mark fixed
2019-05-06 06:33:42,326 [salt.loaded.ext.module.maas:684 ][INFO    ][18630] deploymachines hwe_kernel=hwe-16.04 system_id=6haq6x distro_series=xenial
2019-05-06 06:33:42,340 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063342331072
2019-05-06 06:33:42,359 [salt.minion      :1432][INFO    ][22611] Starting a new job with PID 22611
2019-05-06 06:33:42,384 [salt.minion      :1711][INFO    ][22611] Returning information for job: 20190506063342331072
2019-05-06 06:33:44,888 [salt.loaded.ext.module.maas:160 ][ERROR   ][18630] Failed for object kvm02 reason Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node.
2019-05-06 06:33:44,890 [salt.state       :302 ][ERROR   ][18630] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {'kvm02': "Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node."}, 'success': []}
2019-05-06 06:33:44,890 [salt.state       :1951][INFO    ][18630] Completed state [maas.wait_for_machine_status] at time 06:33:44.890474 duration_in_ms=975851.529
2019-05-06 06:33:44,896 [salt.minion      :1711][INFO    ][18630] Returning information for job: 20190506061723700039
2019-05-06 06:33:55,662 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command pillar.get with jid 20190506063355649106
2019-05-06 06:33:55,683 [salt.minion      :1432][INFO    ][22683] Starting a new job with PID 22683
2019-05-06 06:33:55,692 [salt.minion      :1711][INFO    ][22683] Returning information for job: 20190506063355649106
2019-05-06 06:33:56,242 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command service.status with jid 20190506063356230579
2019-05-06 06:33:56,261 [salt.minion      :1432][INFO    ][22688] Starting a new job with PID 22688
2019-05-06 06:33:56,589 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][22688] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-06 06:33:56,623 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][22688] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-06 06:33:56,640 [salt.minion      :1711][INFO    ][22688] Returning information for job: 20190506063356230579
2019-05-06 06:33:57,180 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506063357169344
2019-05-06 06:33:57,201 [salt.minion      :1432][INFO    ][22699] Starting a new job with PID 22699
2019-05-06 06:34:00,921 [salt.state       :915 ][INFO    ][22699] Loading fresh modules for state activity
2019-05-06 06:34:01,340 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command 'salt-minion --version' in directory '/root'
2019-05-06 06:34:01,637 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command 'salt-minion --version' in directory '/root'
2019-05-06 06:34:02,462 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command 'salt-minion --version' in directory '/root'
2019-05-06 06:34:02,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command 'salt-minion --version' in directory '/root'
2019-05-06 06:34:04,175 [salt.state       :1780][INFO    ][22699] Running state [salt-minion] at time 06:34:04.175058
2019-05-06 06:34:04,175 [salt.state       :1813][INFO    ][22699] Executing state pkg.installed for [salt-minion]
2019-05-06 06:34:04,175 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-06 06:34:04,230 [salt.state       :300 ][INFO    ][22699] All specified packages are already installed
2019-05-06 06:34:04,230 [salt.state       :1951][INFO    ][22699] Completed state [salt-minion] at time 06:34:04.230565 duration_in_ms=55.508
2019-05-06 06:34:04,230 [salt.state       :1780][INFO    ][22699] Running state [salt_minion_dependency_packages] at time 06:34:04.230808
2019-05-06 06:34:04,231 [salt.state       :1813][INFO    ][22699] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-06 06:34:04,235 [salt.state       :300 ][INFO    ][22699] All specified packages are already installed
2019-05-06 06:34:04,235 [salt.state       :1951][INFO    ][22699] Completed state [salt_minion_dependency_packages] at time 06:34:04.235696 duration_in_ms=4.889
2019-05-06 06:34:04,238 [salt.state       :1780][INFO    ][22699] Running state [/etc/salt/minion.d/minion.conf] at time 06:34:04.238093
2019-05-06 06:34:04,238 [salt.state       :1813][INFO    ][22699] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-06 06:34:04,441 [salt.state       :300 ][INFO    ][22699] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-06 06:34:04,442 [salt.state       :1951][INFO    ][22699] Completed state [/etc/salt/minion.d/minion.conf] at time 06:34:04.442199 duration_in_ms=204.105
2019-05-06 06:34:04,442 [salt.state       :1780][INFO    ][22699] Running state [python-netaddr] at time 06:34:04.442641
2019-05-06 06:34:04,443 [salt.state       :1813][INFO    ][22699] Executing state pkg.installed for [python-netaddr]
2019-05-06 06:34:04,449 [salt.state       :300 ][INFO    ][22699] All specified packages are already installed
2019-05-06 06:34:04,449 [salt.state       :1951][INFO    ][22699] Completed state [python-netaddr] at time 06:34:04.449296 duration_in_ms=6.656
2019-05-06 06:34:04,451 [salt.state       :1780][INFO    ][22699] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:34:04.451523
2019-05-06 06:34:04,451 [salt.state       :1813][INFO    ][22699] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-06 06:34:04,462 [salt.state       :300 ][INFO    ][22699] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-06 06:34:04,462 [salt.state       :1951][INFO    ][22699] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:34:04.462192 duration_in_ms=10.669
2019-05-06 06:34:04,462 [salt.state       :1780][INFO    ][22699] Running state [salt-minion] at time 06:34:04.462934
2019-05-06 06:34:04,463 [salt.state       :1813][INFO    ][22699] Executing state service.running for [salt-minion]
2019-05-06 06:34:04,463 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-06 06:34:04,493 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-06 06:34:04,506 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-06 06:34:04,519 [salt.state       :300 ][INFO    ][22699] The service salt-minion is already running
2019-05-06 06:34:04,519 [salt.state       :1951][INFO    ][22699] Completed state [salt-minion] at time 06:34:04.519694 duration_in_ms=56.76
2019-05-06 06:34:04,520 [salt.state       :1780][INFO    ][22699] Running state [/etc/salt/grains.d] at time 06:34:04.520799
2019-05-06 06:34:04,521 [salt.state       :1813][INFO    ][22699] Executing state file.directory for [/etc/salt/grains.d]
2019-05-06 06:34:04,521 [salt.state       :300 ][INFO    ][22699] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-06 06:34:04,521 [salt.state       :1951][INFO    ][22699] Completed state [/etc/salt/grains.d] at time 06:34:04.521916 duration_in_ms=1.117
2019-05-06 06:34:04,522 [salt.state       :1780][INFO    ][22699] Running state [/etc/salt/grains] at time 06:34:04.522394
2019-05-06 06:34:04,522 [salt.state       :1813][INFO    ][22699] Executing state file.managed for [/etc/salt/grains]
2019-05-06 06:34:04,523 [salt.state       :300 ][INFO    ][22699] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-06 06:34:04,523 [salt.state       :1951][INFO    ][22699] Completed state [/etc/salt/grains] at time 06:34:04.523164 duration_in_ms=0.771
2019-05-06 06:34:04,523 [salt.state       :1780][INFO    ][22699] Running state [/etc/salt/grains.d/placeholder] at time 06:34:04.523502
2019-05-06 06:34:04,523 [salt.state       :1813][INFO    ][22699] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-06 06:34:04,524 [salt.state       :300 ][INFO    ][22699] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-06 06:34:04,524 [salt.state       :1951][INFO    ][22699] Completed state [/etc/salt/grains.d/placeholder] at time 06:34:04.524234 duration_in_ms=0.732
2019-05-06 06:34:04,524 [salt.state       :1780][INFO    ][22699] Running state [/etc/salt/grains.d/sphinx] at time 06:34:04.524571
2019-05-06 06:34:04,524 [salt.state       :1813][INFO    ][22699] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-06 06:34:04,536 [salt.state       :300 ][INFO    ][22699] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-06 06:34:04,536 [salt.state       :1951][INFO    ][22699] Completed state [/etc/salt/grains.d/sphinx] at time 06:34:04.536932 duration_in_ms=12.361
2019-05-06 06:34:04,538 [salt.state       :1780][INFO    ][22699] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:34:04.538600
2019-05-06 06:34:04,538 [salt.state       :1813][INFO    ][22699] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-06 06:34:04,539 [salt.state       :300 ][INFO    ][22699] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-06 06:34:04,539 [salt.state       :1951][INFO    ][22699] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:34:04.539215 duration_in_ms=0.615
2019-05-06 06:34:04,539 [salt.state       :1780][INFO    ][22699] Running state [/etc/salt/grains.d/dns_records] at time 06:34:04.539560
2019-05-06 06:34:04,539 [salt.state       :1813][INFO    ][22699] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-06 06:34:04,548 [salt.state       :300 ][INFO    ][22699] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-06 06:34:04,548 [salt.state       :1951][INFO    ][22699] Completed state [/etc/salt/grains.d/dns_records] at time 06:34:04.548854 duration_in_ms=9.294
2019-05-06 06:34:04,549 [salt.state       :1780][INFO    ][22699] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:34:04.549499
2019-05-06 06:34:04,549 [salt.state       :1813][INFO    ][22699] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-06 06:34:04,549 [salt.state       :300 ][INFO    ][22699] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-06 06:34:04,550 [salt.state       :1951][INFO    ][22699] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:34:04.550107 duration_in_ms=0.608
2019-05-06 06:34:04,550 [salt.state       :1780][INFO    ][22699] Running state [/etc/salt/grains.d/salt] at time 06:34:04.550439
2019-05-06 06:34:04,550 [salt.state       :1813][INFO    ][22699] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-06 06:34:04,560 [salt.state       :300 ][INFO    ][22699] File /etc/salt/grains.d/salt is in the correct state
2019-05-06 06:34:04,560 [salt.state       :1951][INFO    ][22699] Completed state [/etc/salt/grains.d/salt] at time 06:34:04.560778 duration_in_ms=10.339
2019-05-06 06:34:04,561 [salt.state       :1780][INFO    ][22699] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:34:04.561410
2019-05-06 06:34:04,561 [salt.state       :1813][INFO    ][22699] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-06 06:34:04,561 [salt.state       :300 ][INFO    ][22699] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-06 06:34:04,562 [salt.state       :1951][INFO    ][22699] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:34:04.562043 duration_in_ms=0.634
2019-05-06 06:34:04,563 [salt.state       :1780][INFO    ][22699] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:34:04.563420
2019-05-06 06:34:04,563 [salt.state       :1813][INFO    ][22699] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-06 06:34:04,563 [salt.state       :300 ][INFO    ][22699] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-06 06:34:04,564 [salt.state       :1951][INFO    ][22699] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:34:04.564050 duration_in_ms=0.629
2019-05-06 06:34:04,564 [salt.state       :1780][INFO    ][22699] Running state [mine.update] at time 06:34:04.564535
2019-05-06 06:34:04,564 [salt.state       :1813][INFO    ][22699] Executing state module.wait for [mine.update]
2019-05-06 06:34:04,564 [salt.state       :300 ][INFO    ][22699] No changes made for mine.update
2019-05-06 06:34:04,565 [salt.state       :1951][INFO    ][22699] Completed state [mine.update] at time 06:34:04.565102 duration_in_ms=0.568
2019-05-06 06:34:04,565 [salt.state       :1780][INFO    ][22699] Running state [ca-certificates] at time 06:34:04.565279
2019-05-06 06:34:04,565 [salt.state       :1813][INFO    ][22699] Executing state pkg.installed for [ca-certificates]
2019-05-06 06:34:04,570 [salt.state       :300 ][INFO    ][22699] All specified packages are already installed
2019-05-06 06:34:04,570 [salt.state       :1951][INFO    ][22699] Completed state [ca-certificates] at time 06:34:04.570889 duration_in_ms=5.61
2019-05-06 06:34:04,571 [salt.state       :1780][INFO    ][22699] Running state [update-ca-certificates] at time 06:34:04.571388
2019-05-06 06:34:04,571 [salt.state       :1813][INFO    ][22699] Executing state cmd.wait for [update-ca-certificates]
2019-05-06 06:34:04,571 [salt.state       :300 ][INFO    ][22699] No changes made for update-ca-certificates
2019-05-06 06:34:04,572 [salt.state       :1951][INFO    ][22699] Completed state [update-ca-certificates] at time 06:34:04.571988 duration_in_ms=0.6
2019-05-06 06:34:04,572 [salt.state       :1780][INFO    ][22699] Running state [iptables] at time 06:34:04.572166
2019-05-06 06:34:04,572 [salt.state       :1813][INFO    ][22699] Executing state pkg.installed for [iptables]
2019-05-06 06:34:04,577 [salt.state       :300 ][INFO    ][22699] All specified packages are already installed
2019-05-06 06:34:04,577 [salt.state       :1951][INFO    ][22699] Completed state [iptables] at time 06:34:04.577367 duration_in_ms=5.201
2019-05-06 06:34:04,577 [salt.state       :1780][INFO    ][22699] Running state [iptables-persistent] at time 06:34:04.577561
2019-05-06 06:34:04,577 [salt.state       :1813][INFO    ][22699] Executing state pkg.installed for [iptables-persistent]
2019-05-06 06:34:04,582 [salt.state       :300 ][INFO    ][22699] All specified packages are already installed
2019-05-06 06:34:04,582 [salt.state       :1951][INFO    ][22699] Completed state [iptables-persistent] at time 06:34:04.582734 duration_in_ms=5.173
2019-05-06 06:34:04,583 [salt.state       :1780][INFO    ][22699] Running state [iptables_modules_v4_load] at time 06:34:04.583449
2019-05-06 06:34:04,583 [salt.state       :1813][INFO    ][22699] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-06 06:34:04,584 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command 'lsmod' in directory '/root'
2019-05-06 06:34:04,601 [salt.state       :300 ][INFO    ][22699] Kernel modules iptable_filter, ip_tables are already present
2019-05-06 06:34:04,601 [salt.state       :1951][INFO    ][22699] Completed state [iptables_modules_v4_load] at time 06:34:04.601896 duration_in_ms=18.446
2019-05-06 06:34:04,602 [salt.state       :1780][INFO    ][22699] Running state [/etc/iptables/rules.v4] at time 06:34:04.602358
2019-05-06 06:34:04,602 [salt.state       :1813][INFO    ][22699] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-06 06:34:04,674 [salt.state       :300 ][INFO    ][22699] File /etc/iptables/rules.v4 is in the correct state
2019-05-06 06:34:04,674 [salt.state       :1951][INFO    ][22699] Completed state [/etc/iptables/rules.v4] at time 06:34:04.674789 duration_in_ms=72.431
2019-05-06 06:34:04,675 [salt.state       :1780][INFO    ][22699] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:34:04.675514
2019-05-06 06:34:04,675 [salt.state       :1813][INFO    ][22699] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-06 06:34:04,676 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-06 06:34:04,693 [salt.state       :300 ][INFO    ][22699] onlyif execution failed
2019-05-06 06:34:04,693 [salt.state       :1951][INFO    ][22699] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:34:04.693388 duration_in_ms=17.874
2019-05-06 06:34:04,694 [salt.state       :1780][INFO    ][22699] Running state [netfilter-persistent] at time 06:34:04.694073
2019-05-06 06:34:04,694 [salt.state       :1813][INFO    ][22699] Executing state service.running for [netfilter-persistent]
2019-05-06 06:34:04,694 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-06 06:34:04,710 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-06 06:34:04,723 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-06 06:34:04,736 [salt.state       :300 ][INFO    ][22699] The service netfilter-persistent is already running
2019-05-06 06:34:04,736 [salt.state       :1951][INFO    ][22699] Completed state [netfilter-persistent] at time 06:34:04.736374 duration_in_ms=42.301
2019-05-06 06:34:04,737 [salt.state       :1780][INFO    ][22699] Running state [iptables_extra.remove_stale_tables] at time 06:34:04.736968
2019-05-06 06:34:04,737 [salt.state       :1813][INFO    ][22699] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-06 06:34:04,737 [salt.state       :300 ][INFO    ][22699] No changes made for iptables_extra.remove_stale_tables
2019-05-06 06:34:04,737 [salt.state       :1951][INFO    ][22699] Completed state [iptables_extra.remove_stale_tables] at time 06:34:04.737596 duration_in_ms=0.627
2019-05-06 06:34:04,737 [salt.state       :1780][INFO    ][22699] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:34:04.737768
2019-05-06 06:34:04,737 [salt.state       :1813][INFO    ][22699] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-06 06:34:04,738 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22699] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-06 06:34:04,749 [salt.state       :300 ][INFO    ][22699] onlyif execution failed
2019-05-06 06:34:04,749 [salt.state       :1951][INFO    ][22699] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:34:04.749319 duration_in_ms=11.551
2019-05-06 06:34:04,750 [salt.state       :1780][INFO    ][22699] Running state [/etc/iptables/rules.v6] at time 06:34:04.749995
2019-05-06 06:34:04,750 [salt.state       :1813][INFO    ][22699] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-06 06:34:04,750 [salt.state       :300 ][INFO    ][22699] File /etc/iptables/rules.v6 is not present
2019-05-06 06:34:04,750 [salt.state       :1951][INFO    ][22699] Completed state [/etc/iptables/rules.v6] at time 06:34:04.750700 duration_in_ms=0.705
2019-05-06 06:34:04,751 [salt.state       :1780][INFO    ][22699] Running state [iptables_extra.flush_all] at time 06:34:04.751147
2019-05-06 06:34:04,751 [salt.state       :1813][INFO    ][22699] Executing state module.wait for [iptables_extra.flush_all]
2019-05-06 06:34:04,751 [salt.state       :300 ][INFO    ][22699] No changes made for iptables_extra.flush_all
2019-05-06 06:34:04,751 [salt.state       :1951][INFO    ][22699] Completed state [iptables_extra.flush_all] at time 06:34:04.751698 duration_in_ms=0.55
2019-05-06 06:34:04,753 [salt.minion      :1711][INFO    ][22699] Returning information for job: 20190506063357169344
2019-05-06 06:34:05,397 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506063405384877
2019-05-06 06:34:05,419 [salt.minion      :1432][INFO    ][22775] Starting a new job with PID 22775
2019-05-06 06:34:06,217 [salt.state       :915 ][INFO    ][22775] Loading fresh modules for state activity
2019-05-06 06:34:06,814 [salt.state       :1780][INFO    ][22775] Running state [maas-rack-controller] at time 06:34:06.814503
2019-05-06 06:34:06,814 [salt.state       :1813][INFO    ][22775] Executing state pkg.installed for [maas-rack-controller]
2019-05-06 06:34:06,815 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22775] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-06 06:34:06,916 [salt.state       :300 ][INFO    ][22775] All specified packages are already installed
2019-05-06 06:34:06,917 [salt.state       :1951][INFO    ][22775] Completed state [maas-rack-controller] at time 06:34:06.917353 duration_in_ms=102.849
2019-05-06 06:34:06,917 [salt.state       :1780][INFO    ][22775] Running state [ipmitool] at time 06:34:06.917756
2019-05-06 06:34:06,918 [salt.state       :1813][INFO    ][22775] Executing state pkg.installed for [ipmitool]
2019-05-06 06:34:06,925 [salt.state       :300 ][INFO    ][22775] All specified packages are already installed
2019-05-06 06:34:06,925 [salt.state       :1951][INFO    ][22775] Completed state [ipmitool] at time 06:34:06.925806 duration_in_ms=8.05
2019-05-06 06:34:06,929 [salt.state       :1780][INFO    ][22775] Running state [/etc/maas/rackd.conf] at time 06:34:06.929423
2019-05-06 06:34:06,929 [salt.state       :1813][INFO    ][22775] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-06 06:34:06,930 [salt.state       :300 ][INFO    ][22775] No changes needed to be made
2019-05-06 06:34:06,931 [salt.state       :1951][INFO    ][22775] Completed state [/etc/maas/rackd.conf] at time 06:34:06.931202 duration_in_ms=1.779
2019-05-06 06:34:06,931 [salt.state       :1780][INFO    ][22775] Running state [/etc/maas/rackd.conf] at time 06:34:06.931459
2019-05-06 06:34:06,931 [salt.state       :1813][INFO    ][22775] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-06 06:34:06,932 [salt.loaded.int.states.file:2298][WARNING ][22775] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-06 06:34:06,932 [salt.state       :300 ][INFO    ][22775] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-06 06:34:06,932 [salt.state       :1951][INFO    ][22775] Completed state [/etc/maas/rackd.conf] at time 06:34:06.932886 duration_in_ms=1.427
2019-05-06 06:34:06,934 [salt.state       :1780][INFO    ][22775] Running state [maas-rackd] at time 06:34:06.933982
2019-05-06 06:34:06,934 [salt.state       :1813][INFO    ][22775] Executing state service.running for [maas-rackd]
2019-05-06 06:34:06,935 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22775] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-06 06:34:06,970 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22775] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-06 06:34:06,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22775] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-06 06:34:07,003 [salt.state       :300 ][INFO    ][22775] The service maas-rackd is already running
2019-05-06 06:34:07,004 [salt.state       :1951][INFO    ][22775] Completed state [maas-rackd] at time 06:34:07.003944 duration_in_ms=69.962
2019-05-06 06:34:07,005 [salt.minion      :1711][INFO    ][22775] Returning information for job: 20190506063405384877
2019-05-06 06:34:07,544 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506063407537438
2019-05-06 06:34:07,565 [salt.minion      :1432][INFO    ][22799] Starting a new job with PID 22799
2019-05-06 06:34:08,308 [salt.state       :915 ][INFO    ][22799] Loading fresh modules for state activity
2019-05-06 06:34:08,991 [salt.state       :1780][INFO    ][22799] Running state [maas-region-controller] at time 06:34:08.991037
2019-05-06 06:34:08,991 [salt.state       :1813][INFO    ][22799] Executing state pkg.installed for [maas-region-controller]
2019-05-06 06:34:08,991 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-06 06:34:09,088 [salt.state       :300 ][INFO    ][22799] All specified packages are already installed
2019-05-06 06:34:09,089 [salt.state       :1951][INFO    ][22799] Completed state [maas-region-controller] at time 06:34:09.089108 duration_in_ms=98.07
2019-05-06 06:34:09,089 [salt.state       :1780][INFO    ][22799] Running state [python-oauth] at time 06:34:09.089509
2019-05-06 06:34:09,089 [salt.state       :1813][INFO    ][22799] Executing state pkg.installed for [python-oauth]
2019-05-06 06:34:09,097 [salt.state       :300 ][INFO    ][22799] All specified packages are already installed
2019-05-06 06:34:09,097 [salt.state       :1951][INFO    ][22799] Completed state [python-oauth] at time 06:34:09.097588 duration_in_ms=8.079
2019-05-06 06:34:09,101 [salt.state       :1780][INFO    ][22799] Running state [/etc/maas/regiond.conf] at time 06:34:09.101376
2019-05-06 06:34:09,101 [salt.state       :1813][INFO    ][22799] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-06 06:34:09,149 [salt.state       :300 ][INFO    ][22799] No changes needed to be made
2019-05-06 06:34:09,150 [salt.state       :1951][INFO    ][22799] Completed state [/etc/maas/regiond.conf] at time 06:34:09.150008 duration_in_ms=48.631
2019-05-06 06:34:09,150 [salt.state       :1780][INFO    ][22799] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:34:09.150640
2019-05-06 06:34:09,151 [salt.state       :1813][INFO    ][22799] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-06 06:34:09,226 [salt.state       :300 ][INFO    ][22799] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-06 06:34:09,227 [salt.state       :1951][INFO    ][22799] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:34:09.227071 duration_in_ms=76.431
2019-05-06 06:34:09,227 [salt.state       :1780][INFO    ][22799] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:34:09.227670
2019-05-06 06:34:09,228 [salt.state       :1813][INFO    ][22799] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-06 06:34:09,246 [salt.state       :300 ][INFO    ][22799] No changes needed to be made
2019-05-06 06:34:09,246 [salt.state       :1951][INFO    ][22799] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:34:09.246646 duration_in_ms=18.976
2019-05-06 06:34:09,247 [salt.state       :1780][INFO    ][22799] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:34:09.247207
2019-05-06 06:34:09,247 [salt.state       :1813][INFO    ][22799] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-06 06:34:09,270 [salt.state       :300 ][INFO    ][22799] No changes needed to be made
2019-05-06 06:34:09,270 [salt.state       :1951][INFO    ][22799] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:34:09.270574 duration_in_ms=23.367
2019-05-06 06:34:09,271 [salt.state       :1780][INFO    ][22799] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:34:09.271137
2019-05-06 06:34:09,271 [salt.state       :1813][INFO    ][22799] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-06 06:34:09,299 [salt.state       :300 ][INFO    ][22799] No changes needed to be made
2019-05-06 06:34:09,299 [salt.state       :1951][INFO    ][22799] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:34:09.299353 duration_in_ms=28.216
2019-05-06 06:34:09,299 [salt.state       :1780][INFO    ][22799] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:34:09.299847
2019-05-06 06:34:09,300 [salt.state       :1813][INFO    ][22799] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-06 06:34:09,311 [salt.state       :300 ][INFO    ][22799] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-06 06:34:09,311 [salt.state       :1951][INFO    ][22799] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:34:09.311568 duration_in_ms=11.72
2019-05-06 06:34:09,312 [salt.state       :1780][INFO    ][22799] Running state [a2enmod headers] at time 06:34:09.312799
2019-05-06 06:34:09,313 [salt.state       :1813][INFO    ][22799] Executing state cmd.run for [a2enmod headers]
2019-05-06 06:34:09,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command 'a2enmod headers' in directory '/root'
2019-05-06 06:34:09,383 [salt.state       :300 ][INFO    ][22799] {'pid': 22818, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-06 06:34:09,384 [salt.state       :1951][INFO    ][22799] Completed state [a2enmod headers] at time 06:34:09.384327 duration_in_ms=71.527
2019-05-06 06:34:09,385 [salt.state       :1780][INFO    ][22799] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:34:09.384934
2019-05-06 06:34:09,385 [salt.state       :1813][INFO    ][22799] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-06 06:34:09,411 [salt.state       :300 ][INFO    ][22799] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-06 06:34:09,411 [salt.state       :1951][INFO    ][22799] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:34:09.411759 duration_in_ms=26.822
2019-05-06 06:34:09,413 [salt.state       :1780][INFO    ][22799] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:34:09.412937
2019-05-06 06:34:09,413 [salt.state       :1813][INFO    ][22799] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-06 06:34:09,490 [salt.state       :300 ][INFO    ][22799] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-06 06:34:09,490 [salt.state       :1951][INFO    ][22799] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:34:09.490718 duration_in_ms=77.781
2019-05-06 06:34:09,491 [salt.state       :1780][INFO    ][22799] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:34:09.491297
2019-05-06 06:34:09,491 [salt.state       :1813][INFO    ][22799] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-06 06:34:09,556 [salt.state       :300 ][INFO    ][22799] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-06 06:34:09,556 [salt.state       :1951][INFO    ][22799] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:34:09.556398 duration_in_ms=65.102
2019-05-06 06:34:09,556 [salt.state       :1780][INFO    ][22799] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:34:09.556904
2019-05-06 06:34:09,557 [salt.state       :1813][INFO    ][22799] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-06 06:34:09,615 [salt.state       :300 ][INFO    ][22799] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-06 06:34:09,616 [salt.state       :1951][INFO    ][22799] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:34:09.616168 duration_in_ms=59.264
2019-05-06 06:34:09,616 [salt.state       :1780][INFO    ][22799] Running state [/root/.pgpass] at time 06:34:09.616422
2019-05-06 06:34:09,616 [salt.state       :1813][INFO    ][22799] Executing state file.managed for [/root/.pgpass]
2019-05-06 06:34:09,663 [salt.state       :300 ][INFO    ][22799] File /root/.pgpass is in the correct state
2019-05-06 06:34:09,664 [salt.state       :1951][INFO    ][22799] Completed state [/root/.pgpass] at time 06:34:09.664121 duration_in_ms=47.698
2019-05-06 06:34:09,668 [salt.state       :1780][INFO    ][22799] Running state [maas-region syncdb --noinput] at time 06:34:09.668692
2019-05-06 06:34:09,669 [salt.state       :1813][INFO    ][22799] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-06 06:34:09,669 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-06 06:34:11,569 [salt.state       :300 ][INFO    ][22799] {'pid': 22831, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, sessions, metadataserver, auth, contenttypes, sites, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-06 06:34:11,570 [salt.state       :1951][INFO    ][22799] Completed state [maas-region syncdb --noinput] at time 06:34:11.570052 duration_in_ms=1901.36
2019-05-06 06:34:11,570 [salt.state       :2022][WARNING ][22799] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-06 06:34:11,571 [salt.state       :1780][INFO    ][22799] Running state [maas-regiond] at time 06:34:11.571218
2019-05-06 06:34:11,571 [salt.state       :1813][INFO    ][22799] Executing state service.running for [maas-regiond]
2019-05-06 06:34:11,572 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-06 06:34:11,607 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-06 06:34:11,624 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-06 06:34:11,639 [salt.state       :300 ][INFO    ][22799] The service maas-regiond is already running
2019-05-06 06:34:11,640 [salt.state       :1951][INFO    ][22799] Completed state [maas-regiond] at time 06:34:11.640069 duration_in_ms=68.851
2019-05-06 06:34:11,641 [salt.state       :1780][INFO    ][22799] Running state [bind9] at time 06:34:11.641256
2019-05-06 06:34:11,641 [salt.state       :1813][INFO    ][22799] Executing state service.running for [bind9]
2019-05-06 06:34:11,642 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-06 06:34:11,658 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-06 06:34:11,673 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-06 06:34:11,687 [salt.state       :300 ][INFO    ][22799] The service bind9 is already running
2019-05-06 06:34:11,688 [salt.state       :1951][INFO    ][22799] Completed state [bind9] at time 06:34:11.687954 duration_in_ms=46.697
2019-05-06 06:34:11,689 [salt.state       :1780][INFO    ][22799] Running state [apache2] at time 06:34:11.689340
2019-05-06 06:34:11,689 [salt.state       :1813][INFO    ][22799] Executing state service.running for [apache2]
2019-05-06 06:34:11,690 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-06 06:34:11,706 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-06 06:34:11,720 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-06 06:34:11,742 [salt.state       :300 ][INFO    ][22799] The service apache2 is already running
2019-05-06 06:34:11,743 [salt.state       :1951][INFO    ][22799] Completed state [apache2] at time 06:34:11.743190 duration_in_ms=53.85
2019-05-06 06:34:11,744 [salt.state       :1780][INFO    ][22799] Running state [maasng.wait_for_http_code] at time 06:34:11.744462
2019-05-06 06:34:11,744 [salt.state       :1813][INFO    ][22799] Executing state module.run for [maasng.wait_for_http_code]
2019-05-06 06:34:11,745 [salt.utils.decorators:613 ][WARNING ][22799] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:34:11,752 [salt.state       :300 ][INFO    ][22799] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-06 06:34:11,752 [salt.state       :1951][INFO    ][22799] Completed state [maasng.wait_for_http_code] at time 06:34:11.752808 duration_in_ms=8.345
2019-05-06 06:34:11,754 [salt.state       :1780][INFO    ][22799] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:34:11.754146
2019-05-06 06:34:11,754 [salt.state       :1813][INFO    ][22799] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-06 06:34:11,755 [salt.state       :300 ][INFO    ][22799] /var/lib/maas/.setup_admin exists
2019-05-06 06:34:11,755 [salt.state       :1951][INFO    ][22799] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:34:11.755701 duration_in_ms=1.555
2019-05-06 06:34:11,756 [salt.state       :1780][INFO    ][22799] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:34:11.756791
2019-05-06 06:34:11,757 [salt.state       :1813][INFO    ][22799] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-06 06:34:11,758 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-06 06:34:13,080 [salt.state       :300 ][INFO    ][22799] {'pid': 22850, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-06 06:34:13,081 [salt.state       :1951][INFO    ][22799] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:34:13.081003 duration_in_ms=1324.211
2019-05-06 06:34:13,091 [salt.state       :1780][INFO    ][22799] Running state [maas_region_boot_source_resources_mirror] at time 06:34:13.091616
2019-05-06 06:34:13,092 [salt.state       :1813][INFO    ][22799] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-06 06:34:13,165 [salt.state       :300 ][INFO    ][22799] {'changes': {}}
2019-05-06 06:34:13,166 [salt.state       :1951][INFO    ][22799] Completed state [maas_region_boot_source_resources_mirror] at time 06:34:13.166068 duration_in_ms=74.451
2019-05-06 06:34:13,167 [salt.state       :1780][INFO    ][22799] Running state [maasng.boot_resources_import] at time 06:34:13.167187
2019-05-06 06:34:13,167 [salt.state       :1813][INFO    ][22799] Executing state module.run for [maasng.boot_resources_import]
2019-05-06 06:34:13,168 [salt.utils.decorators:613 ][WARNING ][22799] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:34:13,250 [salt.loaded.ext.module.maasng:1600][INFO    ][22799] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-06 06:34:18,333 [salt.loaded.ext.module.maasng:1600][INFO    ][22799] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-06 06:34:22,580 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063422567409
2019-05-06 06:34:22,602 [salt.minion      :1432][INFO    ][22915] Starting a new job with PID 22915
2019-05-06 06:34:22,628 [salt.minion      :1711][INFO    ][22915] Returning information for job: 20190506063422567409
2019-05-06 06:34:23,398 [salt.loaded.ext.module.maasng:1600][INFO    ][22799] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-05-06 06:34:28,511 [salt.state       :300 ][INFO    ][22799] {'ret': True}
2019-05-06 06:34:28,512 [salt.state       :1951][INFO    ][22799] Completed state [maasng.boot_resources_import] at time 06:34:28.512072 duration_in_ms=15344.884
2019-05-06 06:34:28,513 [salt.state       :1780][INFO    ][22799] Running state [maas_region_boot_sources_selection_xenial] at time 06:34:28.513312
2019-05-06 06:34:28,513 [salt.state       :1813][INFO    ][22799] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-06 06:34:28,727 [salt.state       :300 ][INFO    ][22799] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-06 06:34:28,727 [salt.state       :1951][INFO    ][22799] Completed state [maas_region_boot_sources_selection_xenial] at time 06:34:28.727731 duration_in_ms=214.418
2019-05-06 06:34:28,729 [salt.state       :1780][INFO    ][22799] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:34:28.729178
2019-05-06 06:34:28,729 [salt.state       :1813][INFO    ][22799] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-06 06:34:28,730 [salt.utils.decorators:613 ][WARNING ][22799] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:34:28,730 [salt.loaded.ext.module.maasng:1771][INFO    ][22799] boot-sources sync initiated for ALL Rack's
2019-05-06 06:34:29,756 [salt.state       :300 ][INFO    ][22799] {'ret': True}
2019-05-06 06:34:29,757 [salt.state       :1951][INFO    ][22799] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:34:29.757289 duration_in_ms=1028.111
2019-05-06 06:34:29,759 [salt.state       :1780][INFO    ][22799] Running state [maas.process_maas_config] at time 06:34:29.759493
2019-05-06 06:34:29,760 [salt.state       :1813][INFO    ][22799] Executing state module.run for [maas.process_maas_config]
2019-05-06 06:34:29,760 [salt.utils.decorators:613 ][WARNING ][22799] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:34:29,761 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=enable_http_proxy value=True
2019-05-06 06:34:29,819 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=upstream_dns value=8.8.8.8
2019-05-06 06:34:29,882 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=commissioning_distro_series value=xenial
2019-05-06 06:34:32,723 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=default_osystem value=ubuntu
2019-05-06 06:34:32,813 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=active_discovery_interval value=600
2019-05-06 06:34:32,883 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=dnssec_validation value=no
2019-05-06 06:34:32,957 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=maas_name value=mas01
2019-05-06 06:34:33,015 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=network_discovery value=enabled
2019-05-06 06:34:33,129 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=enable_third_party_drivers value=True
2019-05-06 06:34:33,190 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=default_storage_layout value=lvm
2019-05-06 06:34:33,258 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=ntp_external_only value=True
2019-05-06 06:34:33,317 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-06 06:34:33,374 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=default_distro_series value=xenial
2019-05-06 06:34:33,433 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-06 06:34:33,572 [salt.state       :300 ][INFO    ][22799] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-06 06:34:33,572 [salt.state       :1951][INFO    ][22799] Completed state [maas.process_maas_config] at time 06:34:33.572782 duration_in_ms=3813.288
2019-05-06 06:34:33,573 [salt.state       :1780][INFO    ][22799] Running state [pxe_admin] at time 06:34:33.573728
2019-05-06 06:34:33,574 [salt.state       :1813][INFO    ][22799] Executing state maasng.fabric_present for [pxe_admin]
2019-05-06 06:34:33,637 [salt.loaded.ext.module.maasng:945 ][INFO    ][22799] [{u'id': 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': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 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': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, 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'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:34:33,728 [salt.loaded.ext.module.maasng:1008][WARNING ][22799] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-06 06:34:33,729 [salt.loaded.ext.module.maasng:1011][WARNING ][22799] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-06 06:34:33,800 [salt.state       :300 ][INFO    ][22799] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-06 06:34:33,801 [salt.state       :1951][INFO    ][22799] Completed state [pxe_admin] at time 06:34:33.801030 duration_in_ms=227.302
2019-05-06 06:34:33,801 [salt.state       :1780][INFO    ][22799] Running state [vlan 0] at time 06:34:33.801559
2019-05-06 06:34:33,802 [salt.state       :1813][INFO    ][22799] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-06 06:34:33,865 [salt.loaded.ext.module.maasng:945 ][INFO    ][22799] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:34:33,985 [salt.loaded.ext.module.maasng:945 ][INFO    ][22799] [{u'id': 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': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 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': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, 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'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:34:34,303 [salt.loaded.ext.module.maasng:945 ][INFO    ][22799] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:34:34,392 [salt.state       :300 ][INFO    ][22799] {'new': 'Vlan untagged was updated'}
2019-05-06 06:34:34,392 [salt.state       :1951][INFO    ][22799] Completed state [vlan 0] at time 06:34:34.392702 duration_in_ms=591.142
2019-05-06 06:34:34,394 [salt.state       :1780][INFO    ][22799] Running state [192.168.11.0/24] at time 06:34:34.393995
2019-05-06 06:34:34,394 [salt.state       :1813][INFO    ][22799] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-06 06:34:34,595 [salt.loaded.ext.module.maasng:945 ][INFO    ][22799] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:34:34,596 [salt.loaded.ext.module.maasng:1235][WARNING ][22799] Ignoring parameter vlan:0
2019-05-06 06:34:34,680 [salt.state       :300 ][INFO    ][22799] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-06 06:34:34,681 [salt.state       :1951][INFO    ][22799] Completed state [192.168.11.0/24] at time 06:34:34.681112 duration_in_ms=287.115
2019-05-06 06:34:34,682 [salt.state       :1780][INFO    ][22799] Running state [maas_create_iprange_1] at time 06:34:34.682560
2019-05-06 06:34:34,683 [salt.state       :1813][INFO    ][22799] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-06 06:34:34,746 [salt.state       :300 ][INFO    ][22799] Iprange maas_create_iprange_1 already exist.
2019-05-06 06:34:34,746 [salt.state       :1951][INFO    ][22799] Completed state [maas_create_iprange_1] at time 06:34:34.746409 duration_in_ms=63.849
2019-05-06 06:34:34,746 [salt.state       :1780][INFO    ][22799] Running state [vlan 0] at time 06:34:34.746886
2019-05-06 06:34:34,747 [salt.state       :1813][INFO    ][22799] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-06 06:34:34,800 [salt.loaded.ext.module.maasng:945 ][INFO    ][22799] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:34:34,896 [salt.loaded.ext.module.maasng:945 ][INFO    ][22799] [{u'id': 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': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 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': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, 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'relay_vlan': None, u'primary_rack': u'es8xkk', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:34:35,128 [salt.loaded.ext.module.maasng:945 ][INFO    ][22799] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'es8xkk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-06 06:34:35,213 [salt.state       :300 ][INFO    ][22799] {'new': 'Vlan untagged was updated'}
2019-05-06 06:34:35,214 [salt.state       :1951][INFO    ][22799] Completed state [vlan 0] at time 06:34:35.213925 duration_in_ms=467.037
2019-05-06 06:34:35,214 [salt.state       :1780][INFO    ][22799] Running state [opnfv] at time 06:34:35.214624
2019-05-06 06:34:35,215 [salt.state       :1813][INFO    ][22799] Executing state maasng.sshkey_present for [opnfv]
2019-05-06 06:34:35,267 [salt.loaded.ext.module.maasng:1903][INFO    ][22799] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-05-06 06:34:35,268 [salt.state       :300 ][INFO    ][22799] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-05-06 06:34:35,268 [salt.state       :1951][INFO    ][22799] Completed state [opnfv] at time 06:34:35.268490 duration_in_ms=53.853
2019-05-06 06:34:35,269 [salt.state       :1780][INFO    ][22799] Running state [maas.process_tags] at time 06:34:35.269739
2019-05-06 06:34:35,270 [salt.state       :1813][INFO    ][22799] Executing state module.run for [maas.process_tags]
2019-05-06 06:34:35,271 [salt.utils.decorators:613 ][WARNING ][22799] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:34:35,309 [salt.loaded.ext.module.maas:92  ][INFO    ][22799] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-06 06:34:35,363 [salt.state       :300 ][INFO    ][22799] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-06 06:34:35,364 [salt.state       :1951][INFO    ][22799] Completed state [maas.process_tags] at time 06:34:35.364363 duration_in_ms=94.624
2019-05-06 06:34:35,367 [salt.minion      :1711][INFO    ][22799] Returning information for job: 20190506063407537438
2019-05-06 06:34:35,942 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506063435928414
2019-05-06 06:34:35,957 [salt.minion      :1432][INFO    ][23286] Starting a new job with PID 23286
2019-05-06 06:34:39,648 [salt.state       :915 ][INFO    ][23286] Loading fresh modules for state activity
2019-05-06 06:34:39,743 [salt.state       :1780][INFO    ][23286] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:34:39.742973
2019-05-06 06:34:39,743 [salt.state       :1813][INFO    ][23286] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-06 06:34:39,745 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23286] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-06 06:34:40,961 [salt.state       :300 ][INFO    ][23286] {'pid': 23330, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-06 06:34:40,961 [salt.state       :1951][INFO    ][23286] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:34:40.961469 duration_in_ms=1218.497
2019-05-06 06:34:40,962 [salt.state       :1780][INFO    ][23286] Running state [maas.process_machines] at time 06:34:40.962539
2019-05-06 06:34:40,962 [salt.state       :1813][INFO    ][23286] Executing state module.run for [maas.process_machines]
2019-05-06 06:34:40,963 [salt.utils.decorators:613 ][WARNING ][23286] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:34:41,611 [salt.loaded.ext.module.maas:412 ][WARNING ][23286] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:34:41,612 [salt.loaded.ext.module.maas:92  ][INFO    ][23286] 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=ebyckb architecture=amd64/generic power_parameters_power_user=admin
2019-05-06 06:34:42,928 [salt.loaded.ext.module.maas:412 ][WARNING ][23286] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:34:42,929 [salt.loaded.ext.module.maas:92  ][INFO    ][23286] machine hostname=cmp001 power_type=ipmi mac_addresses=['00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=8g483t architecture=amd64/generic power_parameters_power_user=admin
2019-05-06 06:34:44,124 [salt.loaded.ext.module.maas:412 ][WARNING ][23286] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:34:44,125 [salt.loaded.ext.module.maas:92  ][INFO    ][23286] 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=dayss6 architecture=amd64/generic power_parameters_power_user=admin
2019-05-06 06:34:45,226 [salt.loaded.ext.module.maas:412 ][WARNING ][23286] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:34:45,227 [salt.loaded.ext.module.maas:92  ][INFO    ][23286] 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=mppbah architecture=amd64/generic power_parameters_power_user=admin
2019-05-06 06:34:46,250 [salt.loaded.ext.module.maas:412 ][WARNING ][23286] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-06 06:34:46,251 [salt.loaded.ext.module.maas:92  ][INFO    ][23286] 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=6haq6x architecture=amd64/generic power_parameters_power_user=admin
2019-05-06 06:34:47,544 [salt.state       :300 ][INFO    ][23286] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-06 06:34:47,545 [salt.state       :1951][INFO    ][23286] Completed state [maas.process_machines] at time 06:34:47.544980 duration_in_ms=6582.438
2019-05-06 06:34:47,548 [salt.minion      :1711][INFO    ][23286] Returning information for job: 20190506063435928414
2019-05-06 06:35:20,751 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506063520738855
2019-05-06 06:35:20,773 [salt.minion      :1432][INFO    ][23572] Starting a new job with PID 23572
2019-05-06 06:35:24,539 [salt.state       :915 ][INFO    ][23572] Loading fresh modules for state activity
2019-05-06 06:35:24,633 [salt.state       :1780][INFO    ][23572] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:35:24.633498
2019-05-06 06:35:24,633 [salt.state       :1813][INFO    ][23572] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-06 06:35:24,636 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23572] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-06 06:35:26,079 [salt.state       :300 ][INFO    ][23572] {'pid': 23609, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-06 06:35:26,080 [salt.state       :1951][INFO    ][23572] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:35:26.080229 duration_in_ms=1446.732
2019-05-06 06:35:26,081 [salt.state       :1780][INFO    ][23572] Running state [maas.wait_for_machine_status] at time 06:35:26.081454
2019-05-06 06:35:26,081 [salt.state       :1813][INFO    ][23572] Executing state module.run for [maas.wait_for_machine_status]
2019-05-06 06:35:26,082 [salt.utils.decorators:613 ][WARNING ][23572] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:35:29,199 [salt.state       :300 ][INFO    ][23572] {'ret': True}
2019-05-06 06:35:29,200 [salt.state       :1951][INFO    ][23572] Completed state [maas.wait_for_machine_status] at time 06:35:29.200213 duration_in_ms=3118.755
2019-05-06 06:35:29,204 [salt.minion      :1711][INFO    ][23572] Returning information for job: 20190506063520738855
2019-05-06 06:35:29,830 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506063529816963
2019-05-06 06:35:29,853 [salt.minion      :1432][INFO    ][23623] Starting a new job with PID 23623
2019-05-06 06:35:30,581 [salt.state       :915 ][INFO    ][23623] Loading fresh modules for state activity
2019-05-06 06:35:30,722 [salt.state       :1780][INFO    ][23623] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:35:30.722826
2019-05-06 06:35:30,723 [salt.state       :1813][INFO    ][23623] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-06 06:35:30,724 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23623] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-06 06:35:32,152 [salt.state       :300 ][INFO    ][23623] {'pid': 23631, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-06 06:35:32,153 [salt.state       :1951][INFO    ][23623] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:35:32.152993 duration_in_ms=1430.167
2019-05-06 06:35:32,154 [salt.state       :1780][INFO    ][23623] Running state [maas_machines_storage_cmp002_lvm] at time 06:35:32.154500
2019-05-06 06:35:32,154 [salt.state       :1813][INFO    ][23623] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-06 06:35:32,936 [salt.state       :300 ][INFO    ][23623] Machine cmp002 is not in Ready state.
2019-05-06 06:35:32,936 [salt.state       :1951][INFO    ][23623] Completed state [maas_machines_storage_cmp002_lvm] at time 06:35:32.936526 duration_in_ms=782.024
2019-05-06 06:35:32,937 [salt.state       :1780][INFO    ][23623] Running state [maas_machines_storage_cmp001_lvm] at time 06:35:32.937071
2019-05-06 06:35:32,937 [salt.state       :1813][INFO    ][23623] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-06 06:35:33,637 [salt.state       :300 ][INFO    ][23623] Machine cmp001 is not in Ready state.
2019-05-06 06:35:33,637 [salt.state       :1951][INFO    ][23623] Completed state [maas_machines_storage_cmp001_lvm] at time 06:35:33.637789 duration_in_ms=700.718
2019-05-06 06:35:33,642 [salt.minion      :1711][INFO    ][23623] Returning information for job: 20190506063529816963
2019-05-06 06:35:34,270 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506063534258411
2019-05-06 06:35:34,293 [salt.minion      :1432][INFO    ][23641] Starting a new job with PID 23641
2019-05-06 06:35:35,038 [salt.state       :915 ][INFO    ][23641] Loading fresh modules for state activity
2019-05-06 06:35:35,129 [salt.state       :1780][INFO    ][23641] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:35:35.129808
2019-05-06 06:35:35,130 [salt.state       :1813][INFO    ][23641] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-06 06:35:35,132 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23641] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-06 06:35:36,592 [salt.state       :300 ][INFO    ][23641] {'pid': 23648, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-06 06:35:36,592 [salt.state       :1951][INFO    ][23641] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:35:36.592595 duration_in_ms=1462.788
2019-05-06 06:35:36,593 [salt.state       :1780][INFO    ][23641] Running state [maas.deploy_machines] at time 06:35:36.593809
2019-05-06 06:35:36,594 [salt.state       :1813][INFO    ][23641] Executing state module.run for [maas.deploy_machines]
2019-05-06 06:35:36,594 [salt.utils.decorators:613 ][WARNING ][23641] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:35:37,105 [salt.loaded.ext.module.maas:684 ][INFO    ][23641] deploymachines hwe_kernel=hwe-16.04 system_id=6haq6x distro_series=xenial
2019-05-06 06:35:39,871 [salt.state       :300 ][INFO    ][23641] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-06 06:35:39,871 [salt.state       :1951][INFO    ][23641] Completed state [maas.deploy_machines] at time 06:35:39.871774 duration_in_ms=3277.964
2019-05-06 06:35:39,874 [salt.minion      :1711][INFO    ][23641] Returning information for job: 20190506063534258411
2019-05-06 06:35:40,465 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command state.apply with jid 20190506063540452229
2019-05-06 06:35:40,487 [salt.minion      :1432][INFO    ][23707] Starting a new job with PID 23707
2019-05-06 06:35:41,201 [salt.state       :915 ][INFO    ][23707] Loading fresh modules for state activity
2019-05-06 06:35:41,248 [salt.state       :1780][INFO    ][23707] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:35:41.248669
2019-05-06 06:35:41,248 [salt.state       :1813][INFO    ][23707] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-06 06:35:41,250 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23707] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-06 06:35:42,714 [salt.state       :300 ][INFO    ][23707] {'pid': 23714, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-06 06:35:42,715 [salt.state       :1951][INFO    ][23707] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:35:42.715142 duration_in_ms=1466.473
2019-05-06 06:35:42,716 [salt.state       :1780][INFO    ][23707] Running state [maas.wait_for_machine_status] at time 06:35:42.716687
2019-05-06 06:35:42,716 [salt.state       :1813][INFO    ][23707] Executing state module.run for [maas.wait_for_machine_status]
2019-05-06 06:35:42,717 [salt.utils.decorators:613 ][WARNING ][23707] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-06 06:35:45,359 [salt.loaded.ext.module.maas:1023][INFO    ][23707] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.36441302s left)
2019-05-06 06:35:55,528 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063555477063
2019-05-06 06:35:55,549 [salt.minion      :1432][INFO    ][23739] Starting a new job with PID 23739
2019-05-06 06:35:55,571 [salt.minion      :1711][INFO    ][23739] Returning information for job: 20190506063555477063
2019-05-06 06:36:18,495 [salt.loaded.ext.module.maas:1023][INFO    ][23707] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.228791s left)
2019-05-06 06:36:25,568 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063625555644
2019-05-06 06:36:25,591 [salt.minion      :1432][INFO    ][23794] Starting a new job with PID 23794
2019-05-06 06:36:25,616 [salt.minion      :1711][INFO    ][23794] Returning information for job: 20190506063625555644
2019-05-06 06:36:51,886 [salt.loaded.ext.module.maas:1023][INFO    ][23707] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2180.83710384s left)
2019-05-06 06:36:55,616 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063655603334
2019-05-06 06:36:55,639 [salt.minion      :1432][INFO    ][23815] Starting a new job with PID 23815
2019-05-06 06:36:55,665 [salt.minion      :1711][INFO    ][23815] Returning information for job: 20190506063655603334
2019-05-06 06:37:25,606 [salt.loaded.ext.module.maas:1023][INFO    ][23707] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2147.11735892s left)
2019-05-06 06:37:25,667 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063725654237
2019-05-06 06:37:25,690 [salt.minion      :1432][INFO    ][23892] Starting a new job with PID 23892
2019-05-06 06:37:25,716 [salt.minion      :1711][INFO    ][23892] Returning information for job: 20190506063725654237
2019-05-06 06:37:55,727 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063755715801
2019-05-06 06:37:55,750 [salt.minion      :1432][INFO    ][23941] Starting a new job with PID 23941
2019-05-06 06:37:55,775 [salt.minion      :1711][INFO    ][23941] Returning information for job: 20190506063755715801
2019-05-06 06:37:59,039 [salt.loaded.ext.module.maas:1023][INFO    ][23707] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2113.68445396s left)
2019-05-06 06:38:25,781 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063825769782
2019-05-06 06:38:25,804 [salt.minion      :1432][INFO    ][24164] Starting a new job with PID 24164
2019-05-06 06:38:25,830 [salt.minion      :1711][INFO    ][24164] Returning information for job: 20190506063825769782
2019-05-06 06:38:32,300 [salt.loaded.ext.module.maas:1023][INFO    ][23707] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2080.4233079s left)
2019-05-06 06:38:55,839 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063855826506
2019-05-06 06:38:55,855 [salt.minion      :1432][INFO    ][24197] Starting a new job with PID 24197
2019-05-06 06:38:55,878 [salt.minion      :1711][INFO    ][24197] Returning information for job: 20190506063855826506
2019-05-06 06:39:05,866 [salt.loaded.ext.module.maas:1023][INFO    ][23707] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2046.85735989s left)
2019-05-06 06:39:25,893 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063925880005
2019-05-06 06:39:25,916 [salt.minion      :1432][INFO    ][24275] Starting a new job with PID 24275
2019-05-06 06:39:25,942 [salt.minion      :1711][INFO    ][24275] Returning information for job: 20190506063925880005
2019-05-06 06:39:39,376 [salt.loaded.ext.module.maas:1023][INFO    ][23707] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2013.34719181s left)
2019-05-06 06:39:55,961 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506063955949412
2019-05-06 06:39:55,983 [salt.minion      :1432][INFO    ][24300] Starting a new job with PID 24300
2019-05-06 06:39:56,009 [salt.minion      :1711][INFO    ][24300] Returning information for job: 20190506063955949412
2019-05-06 06:40:12,245 [salt.loaded.ext.module.maas:1023][INFO    ][23707] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1980.478019s left)
2019-05-06 06:40:26,041 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506064026028366
2019-05-06 06:40:26,064 [salt.minion      :1432][INFO    ][24474] Starting a new job with PID 24474
2019-05-06 06:40:26,091 [salt.minion      :1711][INFO    ][24474] Returning information for job: 20190506064026028366
2019-05-06 06:40:45,758 [salt.loaded.ext.module.maas:1023][INFO    ][23707] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1946.96597385s left)
2019-05-06 06:40:56,121 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506064056108513
2019-05-06 06:40:56,144 [salt.minion      :1432][INFO    ][24497] Starting a new job with PID 24497
2019-05-06 06:40:56,171 [salt.minion      :1711][INFO    ][24497] Returning information for job: 20190506064056108513
2019-05-06 06:41:19,425 [salt.loaded.ext.module.maas:1023][INFO    ][23707] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1913.29863286s left)
2019-05-06 06:41:26,204 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506064126192567
2019-05-06 06:41:26,227 [salt.minion      :1432][INFO    ][24623] Starting a new job with PID 24623
2019-05-06 06:41:26,253 [salt.minion      :1711][INFO    ][24623] Returning information for job: 20190506064126192567
2019-05-06 06:41:53,037 [salt.loaded.ext.module.maas:1023][INFO    ][23707] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1879.68686295s left)
2019-05-06 06:41:56,296 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506064156283162
2019-05-06 06:41:56,319 [salt.minion      :1432][INFO    ][24640] Starting a new job with PID 24640
2019-05-06 06:41:56,347 [salt.minion      :1711][INFO    ][24640] Returning information for job: 20190506064156283162
2019-05-06 06:42:26,342 [salt.loaded.ext.module.maas:1023][INFO    ][23707] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1846.38146687s left)
2019-05-06 06:42:26,390 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506064226376680
2019-05-06 06:42:26,413 [salt.minion      :1432][INFO    ][24695] Starting a new job with PID 24695
2019-05-06 06:42:26,438 [salt.minion      :1711][INFO    ][24695] Returning information for job: 20190506064226376680
2019-05-06 06:42:56,487 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command saltutil.find_job with jid 20190506064256475470
2019-05-06 06:42:56,510 [salt.minion      :1432][INFO    ][24733] Starting a new job with PID 24733
2019-05-06 06:42:56,536 [salt.minion      :1711][INFO    ][24733] Returning information for job: 20190506064256475470
2019-05-06 06:42:59,605 [salt.state       :300 ][INFO    ][23707] {'ret': True}
2019-05-06 06:42:59,605 [salt.state       :1951][INFO    ][23707] Completed state [maas.wait_for_machine_status] at time 06:42:59.605676 duration_in_ms=436888.985
2019-05-06 06:42:59,609 [salt.minion      :1711][INFO    ][23707] Returning information for job: 20190506063540452229
2019-05-06 07:07:47,692 [salt.utils.schedule:1377][INFO    ][15019] Running scheduled job: __mine_interval
2019-05-06 08:04:37,403 [salt.minion      :1308][INFO    ][15019] User sudo_ubuntu Executing command cp.push_dir with jid 20190506080437390470
2019-05-06 08:04:37,424 [salt.minion      :1432][INFO    ][30617] Starting a new job with PID 30617
