2019-02-15 05:10:33,066 [salt.utils       :831 ][ERROR   ][1497] DNS lookup or connection check of 'salt' failed.
2019-02-15 05:10:33,067 [salt.minion      :152 ][ERROR   ][1497] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-02-15 05:10:34,806 [salt.utils.parsers:1051][WARNING ][1497] Minion received a SIGTERM. Exiting.
2019-02-15 05:11:54,487 [salt.loaded.int.states.file:2298][WARNING ][1845] State for file: /boot/grub/grub.cfg - 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-02-15 05:12:05,018 [salt.utils.decorators:613 ][WARNING ][1845] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:12:05,117 [salt.loaded.int.states.file:2298][WARNING ][1845] State for file: /etc/shadow - 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-02-15 05:12:05,119 [salt.loaded.int.states.file:2298][WARNING ][1845] State for file: /etc/gshadow - 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-02-15 05:12:05,120 [salt.loaded.int.states.file:2298][WARNING ][1845] State for file: /etc/group- - 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-02-15 05:12:05,121 [salt.loaded.int.states.file:2298][WARNING ][1845] State for file: /etc/group - 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-02-15 05:12:05,123 [salt.loaded.int.states.file:2298][WARNING ][1845] State for file: /etc/passwd- - 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-02-15 05:12:05,125 [salt.loaded.int.states.file:2298][WARNING ][1845] State for file: /etc/passwd - 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-02-15 05:12:05,127 [salt.loaded.int.states.file:2298][WARNING ][1845] State for file: /etc/gshadow- - 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-02-15 05:12:05,129 [salt.loaded.int.states.file:2298][WARNING ][1845] State for file: /etc/shadow- - 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-02-15 05:12:05,333 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1845] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-15 05:12:05,333 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1845] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-02-15 05:12:05,333 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1845] retcode: 32
2019-02-15 05:12:16,281 [salt.utils.parsers:1051][WARNING ][1703] Minion received a SIGTERM. Exiting.
2019-02-15 05:13:04,953 [salt.utils.decorators:613 ][WARNING ][1976] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:13:34,052 [salt.loaded.int.states.file:2298][WARNING ][4678] State for file: /boot/grub/grub.cfg - 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-02-15 05:13:37,112 [salt.loaded.int.states.file:2298][WARNING ][4678] State for file: /etc/shadow - 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-02-15 05:13:37,114 [salt.loaded.int.states.file:2298][WARNING ][4678] State for file: /etc/gshadow - 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-02-15 05:13:37,116 [salt.loaded.int.states.file:2298][WARNING ][4678] State for file: /etc/group- - 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-02-15 05:13:37,118 [salt.loaded.int.states.file:2298][WARNING ][4678] State for file: /etc/group - 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-02-15 05:13:37,119 [salt.loaded.int.states.file:2298][WARNING ][4678] State for file: /etc/passwd- - 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-02-15 05:13:37,120 [salt.loaded.int.states.file:2298][WARNING ][4678] State for file: /etc/passwd - 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-02-15 05:13:37,122 [salt.loaded.int.states.file:2298][WARNING ][4678] State for file: /etc/gshadow- - 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-02-15 05:13:37,123 [salt.loaded.int.states.file:2298][WARNING ][4678] State for file: /etc/shadow- - 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-02-15 05:13:37,273 [salt.utils.decorators:613 ][WARNING ][4678] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:13:37,666 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4678] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-15 05:13:37,667 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4678] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-02-15 05:13:37,667 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4678] retcode: 32
2019-02-15 05:13:44,847 [salt.utils.decorators:613 ][WARNING ][4678] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:13:44,875 [salt.utils.decorators:613 ][WARNING ][4678] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:13:46,919 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6619] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-15 05:13:46,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6619] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-15 05:13:46,962 [salt.utils.parsers:1051][WARNING ][1713] Minion received a SIGTERM. Exiting.
2019-02-15 05:13:47,936 [salt.cli.daemons :293 ][INFO    ][6679] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-02-15 05:13:48,060 [salt.cli.daemons :82  ][INFO    ][6679] Starting up the Salt Minion
2019-02-15 05:13:48,061 [salt.utils.event :1017][INFO    ][6679] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-02-15 05:13:48,649 [salt.minion      :976 ][INFO    ][6679] Creating minion process manager
2019-02-15 05:13:49,805 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6679] Executing command ['date', '+%z'] in directory '/root'
2019-02-15 05:13:49,825 [salt.utils.schedule:568 ][INFO    ][6679] Updating job settings for scheduled job: __mine_interval
2019-02-15 05:13:49,827 [salt.minion      :1108][INFO    ][6679] Added mine.update to scheduler
2019-02-15 05:13:49,833 [salt.minion      :1975][INFO    ][6679] Minion is starting as user 'root'
2019-02-15 05:13:49,848 [salt.minion      :2336][INFO    ][6679] Minion is ready to receive requests!
2019-02-15 05:13:55,604 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051355604685
2019-02-15 05:13:55,622 [salt.minion      :1432][INFO    ][7050] Starting a new job with PID 7050
2019-02-15 05:13:55,643 [salt.minion      :1711][INFO    ][7050] Returning information for job: 20190215051355604685
2019-02-15 05:14:05,778 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051405776931
2019-02-15 05:14:05,806 [salt.minion      :1432][INFO    ][8580] Starting a new job with PID 8580
2019-02-15 05:14:05,835 [salt.minion      :1711][INFO    ][8580] Returning information for job: 20190215051405776931
2019-02-15 05:14:15,977 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051415979425
2019-02-15 05:14:15,997 [salt.minion      :1432][INFO    ][9647] Starting a new job with PID 9647
2019-02-15 05:14:16,026 [salt.minion      :1711][INFO    ][9647] Returning information for job: 20190215051415979425
2019-02-15 05:14:26,168 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051426171240
2019-02-15 05:14:26,187 [salt.minion      :1432][INFO    ][10327] Starting a new job with PID 10327
2019-02-15 05:14:26,213 [salt.minion      :1711][INFO    ][10327] Returning information for job: 20190215051426171240
2019-02-15 05:14:36,373 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051436374436
2019-02-15 05:14:36,391 [salt.minion      :1432][INFO    ][10546] Starting a new job with PID 10546
2019-02-15 05:14:36,414 [salt.minion      :1711][INFO    ][10546] Returning information for job: 20190215051436374436
2019-02-15 05:14:41,054 [salt.loaded.int.states.file:2298][WARNING ][6624] 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-02-15 05:14:42,276 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command state.apply with jid 20190215051442269319
2019-02-15 05:14:42,301 [salt.minion      :1432][INFO    ][11529] Starting a new job with PID 11529
2019-02-15 05:14:48,094 [salt.state       :915 ][INFO    ][11529] Loading fresh modules for state activity
2019-02-15 05:14:48,167 [salt.fileclient  :1219][INFO    ][11529] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-02-15 05:14:49,184 [salt.state       :1780][INFO    ][11529] Running state [maas-region-controller] at time 05:14:49.184643
2019-02-15 05:14:49,185 [salt.state       :1813][INFO    ][11529] Executing state pkg.installed for [maas-region-controller]
2019-02-15 05:14:49,186 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-15 05:14:49,599 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-02-15 05:14:49,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-15 05:14:51,839 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-15 05:14:51,866 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-region-controller'] in directory '/root'
2019-02-15 05:14:52,408 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051452397439
2019-02-15 05:14:52,433 [salt.minion      :1432][INFO    ][11814] Starting a new job with PID 11814
2019-02-15 05:14:52,461 [salt.minion      :1711][INFO    ][11814] Returning information for job: 20190215051452397439
2019-02-15 05:15:02,609 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051502601770
2019-02-15 05:15:02,634 [salt.minion      :1432][INFO    ][12253] Starting a new job with PID 12253
2019-02-15 05:15:02,673 [salt.minion      :1711][INFO    ][12253] Returning information for job: 20190215051502601770
2019-02-15 05:15:12,810 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051512798968
2019-02-15 05:15:12,837 [salt.minion      :1432][INFO    ][13980] Starting a new job with PID 13980
2019-02-15 05:15:12,868 [salt.minion      :1711][INFO    ][13980] Returning information for job: 20190215051512798968
2019-02-15 05:15:23,015 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051523001967
2019-02-15 05:15:23,032 [salt.minion      :1432][INFO    ][15230] Starting a new job with PID 15230
2019-02-15 05:15:23,057 [salt.minion      :1711][INFO    ][15230] Returning information for job: 20190215051523001967
2019-02-15 05:15:33,221 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051533205170
2019-02-15 05:15:33,242 [salt.minion      :1432][INFO    ][15241] Starting a new job with PID 15241
2019-02-15 05:15:33,263 [salt.minion      :1711][INFO    ][15241] Returning information for job: 20190215051533205170
2019-02-15 05:15:43,410 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051543395469
2019-02-15 05:15:43,430 [salt.minion      :1432][INFO    ][15256] Starting a new job with PID 15256
2019-02-15 05:15:43,454 [salt.minion      :1711][INFO    ][15256] Returning information for job: 20190215051543395469
2019-02-15 05:15:53,611 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051553594728
2019-02-15 05:15:53,632 [salt.minion      :1432][INFO    ][15761] Starting a new job with PID 15761
2019-02-15 05:15:53,660 [salt.minion      :1711][INFO    ][15761] Returning information for job: 20190215051553594728
2019-02-15 05:16:03,810 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051603793441
2019-02-15 05:16:03,836 [salt.minion      :1432][INFO    ][16431] Starting a new job with PID 16431
2019-02-15 05:16:03,884 [salt.minion      :1711][INFO    ][16431] Returning information for job: 20190215051603793441
2019-02-15 05:16:13,820 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051613805907
2019-02-15 05:16:13,839 [salt.minion      :1432][INFO    ][16742] Starting a new job with PID 16742
2019-02-15 05:16:13,860 [salt.minion      :1711][INFO    ][16742] Returning information for job: 20190215051613805907
2019-02-15 05:16:24,014 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051623996909
2019-02-15 05:16:24,032 [salt.minion      :1432][INFO    ][17421] Starting a new job with PID 17421
2019-02-15 05:16:24,059 [salt.minion      :1711][INFO    ][17421] Returning information for job: 20190215051623996909
2019-02-15 05:16:34,201 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051634184042
2019-02-15 05:16:34,229 [salt.minion      :1432][INFO    ][17439] Starting a new job with PID 17439
2019-02-15 05:16:34,264 [salt.minion      :1711][INFO    ][17439] Returning information for job: 20190215051634184042
2019-02-15 05:16:44,407 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051644385567
2019-02-15 05:16:44,456 [salt.minion      :1432][INFO    ][17444] Starting a new job with PID 17444
2019-02-15 05:16:44,483 [salt.minion      :1711][INFO    ][17444] Returning information for job: 20190215051644385567
2019-02-15 05:16:54,626 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051654605755
2019-02-15 05:16:54,679 [salt.minion      :1432][INFO    ][17449] Starting a new job with PID 17449
2019-02-15 05:16:54,728 [salt.minion      :1711][INFO    ][17449] Returning information for job: 20190215051654605755
2019-02-15 05:17:04,670 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051704651695
2019-02-15 05:17:04,702 [salt.minion      :1432][INFO    ][17465] Starting a new job with PID 17465
2019-02-15 05:17:04,753 [salt.minion      :1711][INFO    ][17465] Returning information for job: 20190215051704651695
2019-02-15 05:17:14,685 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051714668822
2019-02-15 05:17:14,734 [salt.minion      :1432][INFO    ][17470] Starting a new job with PID 17470
2019-02-15 05:17:14,767 [salt.minion      :1711][INFO    ][17470] Returning information for job: 20190215051714668822
2019-02-15 05:17:24,708 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051724688616
2019-02-15 05:17:24,764 [salt.minion      :1432][INFO    ][17476] Starting a new job with PID 17476
2019-02-15 05:17:24,814 [salt.minion      :1711][INFO    ][17476] Returning information for job: 20190215051724688616
2019-02-15 05:17:34,755 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051734740387
2019-02-15 05:17:34,777 [salt.minion      :1432][INFO    ][17487] Starting a new job with PID 17487
2019-02-15 05:17:34,807 [salt.minion      :1711][INFO    ][17487] Returning information for job: 20190215051734740387
2019-02-15 05:17:44,957 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051744940022
2019-02-15 05:17:45,019 [salt.minion      :1432][INFO    ][17493] Starting a new job with PID 17493
2019-02-15 05:17:45,056 [salt.minion      :1711][INFO    ][17493] Returning information for job: 20190215051744940022
2019-02-15 05:17:55,004 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051754989562
2019-02-15 05:17:55,026 [salt.minion      :1432][INFO    ][18024] Starting a new job with PID 18024
2019-02-15 05:17:55,046 [salt.minion      :1711][INFO    ][18024] Returning information for job: 20190215051754989562
2019-02-15 05:18:05,213 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051805197096
2019-02-15 05:18:05,233 [salt.minion      :1432][INFO    ][18212] Starting a new job with PID 18212
2019-02-15 05:18:05,256 [salt.minion      :1711][INFO    ][18212] Returning information for job: 20190215051805197096
2019-02-15 05:18:06,452 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-15 05:18:06,504 [salt.state       :300 ][INFO    ][11529] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.5'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.8'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.8'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-02-15 05:18:06,539 [salt.state       :915 ][INFO    ][11529] Loading fresh modules for state activity
2019-02-15 05:18:06,587 [salt.state       :1951][INFO    ][11529] Completed state [maas-region-controller] at time 05:18:06.587270 duration_in_ms=197402.628
2019-02-15 05:18:06,594 [salt.state       :1780][INFO    ][11529] Running state [python-oauth] at time 05:18:06.594928
2019-02-15 05:18:06,595 [salt.state       :1813][INFO    ][11529] Executing state pkg.installed for [python-oauth]
2019-02-15 05:18:07,471 [salt.state       :300 ][INFO    ][11529] All specified packages are already installed
2019-02-15 05:18:07,471 [salt.state       :1951][INFO    ][11529] Completed state [python-oauth] at time 05:18:07.471432 duration_in_ms=876.503
2019-02-15 05:18:07,475 [salt.state       :1780][INFO    ][11529] Running state [/etc/maas/regiond.conf] at time 05:18:07.475454
2019-02-15 05:18:07,475 [salt.state       :1813][INFO    ][11529] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-15 05:18:07,484 [salt.state       :300 ][INFO    ][11529] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: nRN3mQEQDkHK
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

2019-02-15 05:18:07,485 [salt.state       :1951][INFO    ][11529] Completed state [/etc/maas/regiond.conf] at time 05:18:07.485114 duration_in_ms=9.66
2019-02-15 05:18:07,485 [salt.state       :1780][INFO    ][11529] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:18:07.485670
2019-02-15 05:18:07,485 [salt.state       :1813][INFO    ][11529] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-02-15 05:18:07,514 [salt.fileclient  :1219][INFO    ][11529] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2019-02-15 05:18:07,556 [salt.state       :300 ][INFO    ][11529] File changed:
--- 
+++ 
@@ -1,3 +1,5 @@
+
+
 # DO NOT EDIT.  This file is automatically created by MAAS.
 # Last updated at {{modified}}.
 
@@ -10,6 +12,7 @@
 acl localnet src {{cidr}}
 {{endfor}}
 acl SSL_ports port 443
+acl SSL_ports port 50000        # jenkins slave
 acl Safe_ports port 80          # http
 acl Safe_ports port 21          # ftp
 acl Safe_ports port 443         # https
@@ -23,11 +26,7 @@
 http_access allow localhost
 http_access deny all
 http_port 3128 transparent
-{{if not maas_proxy_port}}
 http_port 8000
-{{else}}
-http_port {{maas_proxy_port}}
-{{endif}}
 refresh_pattern ^ftp:           1440    20%     10080
 refresh_pattern -i (/cgi-bin/|\?) 0     0%      0
 refresh_pattern \/Release(|\.gpg)$                        0    0%    0 refresh-ims
@@ -61,7 +60,5 @@
 cache_log /var/log/maas/proxy/cache.log
 cache_store_log /var/log/maas/proxy/store.log
 {{endif}}
-{{if upstream_peer_proxy}}
-cache_peer {{upstream_proxy_address}} parent {{upstream_proxy_port}} 0 no-query default
-never_direct allow all
-{{endif}}
+
+

2019-02-15 05:18:07,562 [salt.state       :1951][INFO    ][11529] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:18:07.562883 duration_in_ms=77.212
2019-02-15 05:18:07,563 [salt.state       :1780][INFO    ][11529] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:18:07.563770
2019-02-15 05:18:07,564 [salt.state       :1813][INFO    ][11529] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-15 05:18:07,568 [salt.state       :300 ][INFO    ][11529] File changed:
--- 
+++ 
@@ -284,7 +284,7 @@
 }
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
-    NODE_STATUS.COMMISSIONING: 20,
+    NODE_STATUS.COMMISSIONING: 10,
     NODE_STATUS.DEPLOYING: 40,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,

2019-02-15 05:18:07,570 [salt.state       :1951][INFO    ][11529] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:18:07.570820 duration_in_ms=7.049
2019-02-15 05:18:07,571 [salt.state       :1780][INFO    ][11529] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:18:07.571601
2019-02-15 05:18:07,572 [salt.state       :1813][INFO    ][11529] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-15 05:18:07,577 [salt.state       :300 ][INFO    ][11529] File changed:
--- 
+++ 
@@ -285,7 +285,7 @@
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
     NODE_STATUS.COMMISSIONING: 10,
-    NODE_STATUS.DEPLOYING: 40,
+    NODE_STATUS.DEPLOYING: 15,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,
     NODE_STATUS.EXITING_RESCUE_MODE: 5,

2019-02-15 05:18:07,579 [salt.state       :1951][INFO    ][11529] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:18:07.579892 duration_in_ms=8.29
2019-02-15 05:18:07,580 [salt.state       :1780][INFO    ][11529] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:18:07.580512
2019-02-15 05:18:07,580 [salt.state       :1813][INFO    ][11529] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-15 05:18:07,611 [salt.state       :300 ][INFO    ][11529] File changed:
--- 
+++ 
@@ -3210,7 +3210,7 @@
             alloc_type=IPADDRESS_TYPE.DISCOVERED, subnet__isnull=False)
         for ip_address in discovered_addresses:
             boot_interface.link_subnet(
-                INTERFACE_LINK_TYPE.AUTO, ip_address.subnet)
+                INTERFACE_LINK_TYPE.DHCP, ip_address.subnet)
             auto_set = True
         if not auto_set:
             # Failed to set AUTO mode on the boot interface. Lets force an

2019-02-15 05:18:07,611 [salt.state       :1951][INFO    ][11529] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:18:07.611705 duration_in_ms=31.192
2019-02-15 05:18:07,612 [salt.state       :1780][INFO    ][11529] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:18:07.612255
2019-02-15 05:18:07,612 [salt.state       :1813][INFO    ][11529] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-15 05:18:07,630 [salt.fileclient  :1219][INFO    ][11529] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-02-15 05:18:07,634 [salt.state       :300 ][INFO    ][11529] File changed:
--- 
+++ 
@@ -37,3 +37,5 @@
     # Redirect (permanently) requests for /MAAS to /MAAS/.
     RewriteRule ^/MAAS$ %{REQUEST_URI}/ [R=301,L]
 </IfModule>
+Header set Access-Control-Allow-Origin "*"
+Header set X-Frame-Options "ALLOWALL"

2019-02-15 05:18:07,635 [salt.state       :1951][INFO    ][11529] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:18:07.635799 duration_in_ms=23.544
2019-02-15 05:18:07,637 [salt.state       :1780][INFO    ][11529] Running state [a2enmod headers] at time 05:18:07.637279
2019-02-15 05:18:07,637 [salt.state       :1813][INFO    ][11529] Executing state cmd.run for [a2enmod headers]
2019-02-15 05:18:07,638 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command 'a2enmod headers' in directory '/root'
2019-02-15 05:18:07,701 [salt.state       :300 ][INFO    ][11529] {'pid': 18346, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-02-15 05:18:07,702 [salt.state       :1951][INFO    ][11529] Completed state [a2enmod headers] at time 05:18:07.702237 duration_in_ms=64.957
2019-02-15 05:18:07,703 [salt.state       :1780][INFO    ][11529] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:18:07.703285
2019-02-15 05:18:07,703 [salt.state       :1813][INFO    ][11529] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-15 05:18:07,730 [salt.fileclient  :1219][INFO    ][11529] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-02-15 05:18:07,734 [salt.state       :300 ][INFO    ][11529] File changed:
New file
2019-02-15 05:18:07,734 [salt.state       :1951][INFO    ][11529] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:18:07.734768 duration_in_ms=31.482
2019-02-15 05:18:07,735 [salt.state       :1780][INFO    ][11529] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:18:07.735784
2019-02-15 05:18:07,736 [salt.state       :1813][INFO    ][11529] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-15 05:18:07,756 [salt.fileclient  :1219][INFO    ][11529] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-02-15 05:18:07,802 [salt.state       :300 ][INFO    ][11529] File changed:
New file
2019-02-15 05:18:07,803 [salt.state       :1951][INFO    ][11529] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:18:07.803299 duration_in_ms=67.515
2019-02-15 05:18:07,804 [salt.state       :1780][INFO    ][11529] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:18:07.804114
2019-02-15 05:18:07,804 [salt.state       :1813][INFO    ][11529] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-15 05:18:07,827 [salt.fileclient  :1219][INFO    ][11529] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-02-15 05:18:07,872 [salt.state       :300 ][INFO    ][11529] File changed:
New file
2019-02-15 05:18:07,872 [salt.state       :1951][INFO    ][11529] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:18:07.872738 duration_in_ms=68.624
2019-02-15 05:18:07,873 [salt.state       :1780][INFO    ][11529] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:18:07.873490
2019-02-15 05:18:07,874 [salt.state       :1813][INFO    ][11529] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-15 05:18:07,896 [salt.fileclient  :1219][INFO    ][11529] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-02-15 05:18:07,952 [salt.state       :300 ][INFO    ][11529] File changed:
New file
2019-02-15 05:18:07,953 [salt.state       :1951][INFO    ][11529] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:18:07.952993 duration_in_ms=79.503
2019-02-15 05:18:07,953 [salt.state       :1780][INFO    ][11529] Running state [/root/.pgpass] at time 05:18:07.953493
2019-02-15 05:18:07,954 [salt.state       :1813][INFO    ][11529] Executing state file.managed for [/root/.pgpass]
2019-02-15 05:18:07,975 [salt.fileclient  :1219][INFO    ][11529] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-02-15 05:18:08,007 [salt.state       :300 ][INFO    ][11529] File changed:
New file
2019-02-15 05:18:08,008 [salt.state       :1951][INFO    ][11529] Completed state [/root/.pgpass] at time 05:18:08.008274 duration_in_ms=54.781
2019-02-15 05:18:08,013 [salt.state       :1780][INFO    ][11529] Running state [maas-region syncdb --noinput] at time 05:18:08.013724
2019-02-15 05:18:08,014 [salt.state       :1813][INFO    ][11529] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-15 05:18:08,015 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-15 05:18:10,811 [salt.state       :300 ][INFO    ][11529] {'pid': 18370, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: auth, sites, contenttypes, sessions, metadataserver, maasserver, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-15 05:18:10,812 [salt.state       :1951][INFO    ][11529] Completed state [maas-region syncdb --noinput] at time 05:18:10.812282 duration_in_ms=2798.557
2019-02-15 05:18:10,812 [salt.state       :2022][WARNING ][11529] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-15 05:18:10,815 [salt.state       :1780][INFO    ][11529] Running state [maas-regiond] at time 05:18:10.815459
2019-02-15 05:18:10,816 [salt.state       :1813][INFO    ][11529] Executing state service.running for [maas-regiond]
2019-02-15 05:18:10,817 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-15 05:18:10,841 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-15 05:18:10,861 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-15 05:18:10,879 [salt.state       :300 ][INFO    ][11529] The service maas-regiond is already running
2019-02-15 05:18:10,879 [salt.state       :1951][INFO    ][11529] Completed state [maas-regiond] at time 05:18:10.879768 duration_in_ms=64.309
2019-02-15 05:18:10,880 [salt.state       :1780][INFO    ][11529] Running state [maas-regiond] at time 05:18:10.880496
2019-02-15 05:18:10,881 [salt.state       :1813][INFO    ][11529] Executing state service.mod_watch for [maas-regiond]
2019-02-15 05:18:10,883 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-15 05:18:10,901 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-02-15 05:18:10,976 [salt.state       :300 ][INFO    ][11529] {'maas-regiond': True}
2019-02-15 05:18:10,977 [salt.state       :1951][INFO    ][11529] Completed state [maas-regiond] at time 05:18:10.977297 duration_in_ms=96.801
2019-02-15 05:18:10,979 [salt.state       :1780][INFO    ][11529] Running state [bind9] at time 05:18:10.979308
2019-02-15 05:18:10,979 [salt.state       :1813][INFO    ][11529] Executing state service.running for [bind9]
2019-02-15 05:18:10,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-15 05:18:11,004 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-15 05:18:11,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-15 05:18:11,049 [salt.state       :300 ][INFO    ][11529] The service bind9 is already running
2019-02-15 05:18:11,049 [salt.state       :1951][INFO    ][11529] Completed state [bind9] at time 05:18:11.049496 duration_in_ms=70.188
2019-02-15 05:18:11,049 [salt.state       :1780][INFO    ][11529] Running state [bind9] at time 05:18:11.049814
2019-02-15 05:18:11,050 [salt.state       :1813][INFO    ][11529] Executing state service.mod_watch for [bind9]
2019-02-15 05:18:11,051 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-15 05:18:11,070 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-02-15 05:18:11,149 [salt.state       :300 ][INFO    ][11529] {'bind9': True}
2019-02-15 05:18:11,149 [salt.state       :1951][INFO    ][11529] Completed state [bind9] at time 05:18:11.149814 duration_in_ms=99.999
2019-02-15 05:18:11,152 [salt.state       :1780][INFO    ][11529] Running state [apache2] at time 05:18:11.151991
2019-02-15 05:18:11,152 [salt.state       :1813][INFO    ][11529] Executing state service.running for [apache2]
2019-02-15 05:18:11,153 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-15 05:18:11,171 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-15 05:18:11,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-15 05:18:11,236 [salt.state       :300 ][INFO    ][11529] The service apache2 is already running
2019-02-15 05:18:11,236 [salt.state       :1951][INFO    ][11529] Completed state [apache2] at time 05:18:11.236894 duration_in_ms=84.903
2019-02-15 05:18:11,237 [salt.state       :1780][INFO    ][11529] Running state [apache2] at time 05:18:11.237208
2019-02-15 05:18:11,237 [salt.state       :1813][INFO    ][11529] Executing state service.mod_watch for [apache2]
2019-02-15 05:18:11,238 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-15 05:18:11,257 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-02-15 05:18:13,514 [salt.state       :300 ][INFO    ][11529] {'apache2': True}
2019-02-15 05:18:13,514 [salt.state       :1951][INFO    ][11529] Completed state [apache2] at time 05:18:13.514564 duration_in_ms=2277.355
2019-02-15 05:18:13,516 [salt.state       :1780][INFO    ][11529] Running state [maasng.wait_for_http_code] at time 05:18:13.516569
2019-02-15 05:18:13,516 [salt.state       :1813][INFO    ][11529] Executing state module.run for [maasng.wait_for_http_code]
2019-02-15 05:18:13,517 [salt.utils.decorators:613 ][WARNING ][11529] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:18:14,847 [salt.state       :300 ][INFO    ][11529] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-15 05:18:14,847 [salt.state       :1951][INFO    ][11529] Completed state [maasng.wait_for_http_code] at time 05:18:14.847827 duration_in_ms=1331.258
2019-02-15 05:18:14,848 [salt.state       :1780][INFO    ][11529] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:18:14.848756
2019-02-15 05:18:14,849 [salt.state       :1813][INFO    ][11529] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-15 05:18:14,849 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-02-15 05:18:15,421 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051815404834
2019-02-15 05:18:15,441 [salt.minion      :1432][INFO    ][18712] Starting a new job with PID 18712
2019-02-15 05:18:15,468 [salt.minion      :1711][INFO    ][18712] Returning information for job: 20190215051815404834
2019-02-15 05:18:16,775 [salt.state       :300 ][INFO    ][11529] {'pid': 18697, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-15 05:18:16,775 [salt.state       :1951][INFO    ][11529] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:18:16.775855 duration_in_ms=1927.098
2019-02-15 05:18:16,777 [salt.state       :1780][INFO    ][11529] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:16.777587
2019-02-15 05:18:16,778 [salt.state       :1813][INFO    ][11529] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-15 05:18:16,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11529] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-15 05:18:18,528 [salt.state       :300 ][INFO    ][11529] {'pid': 18740, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-15 05:18:18,529 [salt.state       :1951][INFO    ][11529] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:18.529029 duration_in_ms=1751.443
2019-02-15 05:18:18,539 [salt.state       :1780][INFO    ][11529] Running state [maas_region_boot_source_resources_mirror] at time 05:18:18.539347
2019-02-15 05:18:18,539 [salt.state       :1813][INFO    ][11529] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-15 05:18:20,536 [salt.loaded.ext.module.maasng:1508][INFO    ][11529] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-02-15 05:18:20,640 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-15 05:18:25,636 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051825620634
2019-02-15 05:18:25,658 [salt.minion      :1432][INFO    ][18826] Starting a new job with PID 18826
2019-02-15 05:18:25,681 [salt.minion      :1711][INFO    ][18826] Returning information for job: 20190215051825620634
2019-02-15 05:18:25,700 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-15 05:18:30,749 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-15 05:18:35,788 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-15 05:18:35,833 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051835816578
2019-02-15 05:18:35,853 [salt.minion      :1432][INFO    ][18899] Starting a new job with PID 18899
2019-02-15 05:18:35,877 [salt.minion      :1711][INFO    ][18899] Returning information for job: 20190215051835816578
2019-02-15 05:18:40,845 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-02-15 05:18:46,027 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051846008310
2019-02-15 05:18:46,049 [salt.minion      :1432][INFO    ][18969] Starting a new job with PID 18969
2019-02-15 05:18:46,078 [salt.minion      :1711][INFO    ][18969] Returning information for job: 20190215051846008310
2019-02-15 05:18:46,081 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-02-15 05:18:51,136 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:869.0/900s
2019-02-15 05:18:56,078 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051856049287
2019-02-15 05:18:56,109 [salt.minion      :1432][INFO    ][19024] Starting a new job with PID 19024
2019-02-15 05:18:56,137 [salt.minion      :1711][INFO    ][19024] Returning information for job: 20190215051856049287
2019-02-15 05:18:56,181 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2019-02-15 05:19:01,238 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-02-15 05:19:06,126 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051906104641
2019-02-15 05:19:06,148 [salt.minion      :1432][INFO    ][19080] Starting a new job with PID 19080
2019-02-15 05:19:06,168 [salt.minion      :1711][INFO    ][19080] Returning information for job: 20190215051906104641
2019-02-15 05:19:06,285 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-02-15 05:19:11,333 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-02-15 05:19:16,169 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051916144373
2019-02-15 05:19:16,194 [salt.minion      :1432][INFO    ][19148] Starting a new job with PID 19148
2019-02-15 05:19:16,215 [salt.minion      :1711][INFO    ][19148] Returning information for job: 20190215051916144373
2019-02-15 05:19:16,390 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-02-15 05:19:21,443 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-02-15 05:19:26,224 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051926197665
2019-02-15 05:19:26,249 [salt.minion      :1432][INFO    ][19188] Starting a new job with PID 19188
2019-02-15 05:19:26,272 [salt.minion      :1711][INFO    ][19188] Returning information for job: 20190215051926197665
2019-02-15 05:19:26,500 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-02-15 05:19:31,562 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-02-15 05:19:36,270 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051936250430
2019-02-15 05:19:36,296 [salt.minion      :1432][INFO    ][19227] Starting a new job with PID 19227
2019-02-15 05:19:36,323 [salt.minion      :1711][INFO    ][19227] Returning information for job: 20190215051936250430
2019-02-15 05:19:36,613 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-02-15 05:19:41,775 [salt.state       :300 ][INFO    ][11529] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2019-02-15 05:19:41,776 [salt.state       :1951][INFO    ][11529] Completed state [maas_region_boot_source_resources_mirror] at time 05:19:41.776679 duration_in_ms=83237.329
2019-02-15 05:19:41,778 [salt.state       :1780][INFO    ][11529] Running state [maasng.boot_resources_import] at time 05:19:41.778031
2019-02-15 05:19:41,778 [salt.state       :1813][INFO    ][11529] Executing state module.run for [maasng.boot_resources_import]
2019-02-15 05:19:41,779 [salt.utils.decorators:613 ][WARNING ][11529] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:19:43,600 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-15 05:19:46,301 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051946279334
2019-02-15 05:19:46,328 [salt.minion      :1432][INFO    ][19433] Starting a new job with PID 19433
2019-02-15 05:19:46,357 [salt.minion      :1711][INFO    ][19433] Returning information for job: 20190215051946279334
2019-02-15 05:19:48,641 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-15 05:19:53,745 [salt.state       :300 ][INFO    ][11529] {'ret': True}
2019-02-15 05:19:53,745 [salt.state       :1951][INFO    ][11529] Completed state [maasng.boot_resources_import] at time 05:19:53.745755 duration_in_ms=11967.724
2019-02-15 05:19:53,746 [salt.state       :1780][INFO    ][11529] Running state [maas_region_boot_sources_selection_xenial] at time 05:19:53.746813
2019-02-15 05:19:53,747 [salt.state       :1813][INFO    ][11529] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-15 05:19:56,362 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215051956337351
2019-02-15 05:19:56,386 [salt.minion      :1432][INFO    ][19500] Starting a new job with PID 19500
2019-02-15 05:19:56,410 [salt.minion      :1711][INFO    ][19500] Returning information for job: 20190215051956337351
2019-02-15 05:19:59,085 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-15 05:20:04,153 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-15 05:20:06,406 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052006386701
2019-02-15 05:20:06,426 [salt.minion      :1432][INFO    ][19540] Starting a new job with PID 19540
2019-02-15 05:20:06,453 [salt.minion      :1711][INFO    ][19540] Returning information for job: 20190215052006386701
2019-02-15 05:20:09,214 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-15 05:20:14,262 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-15 05:20:16,467 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052016442221
2019-02-15 05:20:16,494 [salt.minion      :1432][INFO    ][19584] Starting a new job with PID 19584
2019-02-15 05:20:16,521 [salt.minion      :1711][INFO    ][19584] Returning information for job: 20190215052016442221
2019-02-15 05:20:19,319 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-02-15 05:20:24,385 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-02-15 05:20:26,543 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052026523717
2019-02-15 05:20:26,563 [salt.minion      :1432][INFO    ][19593] Starting a new job with PID 19593
2019-02-15 05:20:26,590 [salt.minion      :1711][INFO    ][19593] Returning information for job: 20190215052026523717
2019-02-15 05:20:29,446 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-02-15 05:20:34,494 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2019-02-15 05:20:36,605 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052036585911
2019-02-15 05:20:36,627 [salt.minion      :1432][INFO    ][19609] Starting a new job with PID 19609
2019-02-15 05:20:36,653 [salt.minion      :1711][INFO    ][19609] Returning information for job: 20190215052036585911
2019-02-15 05:20:39,606 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-02-15 05:20:44,646 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-02-15 05:20:46,663 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052046645372
2019-02-15 05:20:46,688 [salt.minion      :1432][INFO    ][19636] Starting a new job with PID 19636
2019-02-15 05:20:46,713 [salt.minion      :1711][INFO    ][19636] Returning information for job: 20190215052046645372
2019-02-15 05:20:49,695 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-02-15 05:20:54,756 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-02-15 05:20:56,734 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052056714655
2019-02-15 05:20:56,758 [salt.minion      :1432][INFO    ][19645] Starting a new job with PID 19645
2019-02-15 05:20:56,781 [salt.minion      :1711][INFO    ][19645] Returning information for job: 20190215052056714655
2019-02-15 05:20:59,810 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-02-15 05:21:04,866 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-02-15 05:21:06,820 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052106801964
2019-02-15 05:21:06,839 [salt.minion      :1432][INFO    ][19667] Starting a new job with PID 19667
2019-02-15 05:21:06,863 [salt.minion      :1711][INFO    ][19667] Returning information for job: 20190215052106801964
2019-02-15 05:21:09,911 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-02-15 05:21:14,953 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-02-15 05:21:16,893 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052116875893
2019-02-15 05:21:16,918 [salt.minion      :1432][INFO    ][19689] Starting a new job with PID 19689
2019-02-15 05:21:16,939 [salt.minion      :1711][INFO    ][19689] Returning information for job: 20190215052116875893
2019-02-15 05:21:20,009 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-02-15 05:21:25,070 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-02-15 05:21:26,994 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052126969041
2019-02-15 05:21:27,015 [salt.minion      :1432][INFO    ][19699] Starting a new job with PID 19699
2019-02-15 05:21:27,037 [salt.minion      :1711][INFO    ][19699] Returning information for job: 20190215052126969041
2019-02-15 05:21:30,133 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-02-15 05:21:35,190 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-02-15 05:21:37,076 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052137053054
2019-02-15 05:21:37,098 [salt.minion      :1432][INFO    ][19710] Starting a new job with PID 19710
2019-02-15 05:21:37,120 [salt.minion      :1711][INFO    ][19710] Returning information for job: 20190215052137053054
2019-02-15 05:21:40,247 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2019-02-15 05:21:45,297 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2019-02-15 05:21:47,190 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052147165376
2019-02-15 05:21:47,213 [salt.minion      :1432][INFO    ][19738] Starting a new job with PID 19738
2019-02-15 05:21:47,236 [salt.minion      :1711][INFO    ][19738] Returning information for job: 20190215052147165376
2019-02-15 05:21:50,341 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2019-02-15 05:21:55,385 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:784.0/900s
2019-02-15 05:21:57,267 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052157245834
2019-02-15 05:21:57,294 [salt.minion      :1432][INFO    ][19747] Starting a new job with PID 19747
2019-02-15 05:21:57,317 [salt.minion      :1711][INFO    ][19747] Returning information for job: 20190215052157245834
2019-02-15 05:22:00,446 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:779.0/900s
2019-02-15 05:22:05,497 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:774.0/900s
2019-02-15 05:22:07,310 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052207288480
2019-02-15 05:22:07,336 [salt.minion      :1432][INFO    ][19769] Starting a new job with PID 19769
2019-02-15 05:22:07,358 [salt.minion      :1711][INFO    ][19769] Returning information for job: 20190215052207288480
2019-02-15 05:22:10,541 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:769.0/900s
2019-02-15 05:22:15,601 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2019-02-15 05:22:17,431 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052217411883
2019-02-15 05:22:17,455 [salt.minion      :1432][INFO    ][19796] Starting a new job with PID 19796
2019-02-15 05:22:17,477 [salt.minion      :1711][INFO    ][19796] Returning information for job: 20190215052217411883
2019-02-15 05:22:20,664 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2019-02-15 05:22:25,715 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2019-02-15 05:22:27,548 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052227528143
2019-02-15 05:22:27,572 [salt.minion      :1432][INFO    ][19806] Starting a new job with PID 19806
2019-02-15 05:22:27,594 [salt.minion      :1711][INFO    ][19806] Returning information for job: 20190215052227528143
2019-02-15 05:22:30,776 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2019-02-15 05:22:35,838 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2019-02-15 05:22:37,678 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052237657775
2019-02-15 05:22:37,701 [salt.minion      :1432][INFO    ][19821] Starting a new job with PID 19821
2019-02-15 05:22:37,726 [salt.minion      :1711][INFO    ][19821] Returning information for job: 20190215052237657775
2019-02-15 05:22:40,941 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2019-02-15 05:22:45,996 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2019-02-15 05:22:47,825 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052247804162
2019-02-15 05:22:47,851 [salt.minion      :1432][INFO    ][19854] Starting a new job with PID 19854
2019-02-15 05:22:47,872 [salt.minion      :1711][INFO    ][19854] Returning information for job: 20190215052247804162
2019-02-15 05:22:51,052 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2019-02-15 05:22:56,100 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2019-02-15 05:22:57,928 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052257912389
2019-02-15 05:22:57,944 [salt.minion      :1432][INFO    ][19863] Starting a new job with PID 19863
2019-02-15 05:22:57,969 [salt.minion      :1711][INFO    ][19863] Returning information for job: 20190215052257912389
2019-02-15 05:23:01,159 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2019-02-15 05:23:06,216 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2019-02-15 05:23:08,045 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052308024001
2019-02-15 05:23:08,071 [salt.minion      :1432][INFO    ][19934] Starting a new job with PID 19934
2019-02-15 05:23:08,102 [salt.minion      :1711][INFO    ][19934] Returning information for job: 20190215052308024001
2019-02-15 05:23:11,279 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2019-02-15 05:23:16,339 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:703.0/900s
2019-02-15 05:23:18,218 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052318193353
2019-02-15 05:23:18,239 [salt.minion      :1432][INFO    ][19957] Starting a new job with PID 19957
2019-02-15 05:23:18,259 [salt.minion      :1711][INFO    ][19957] Returning information for job: 20190215052318193353
2019-02-15 05:23:21,384 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:698.0/900s
2019-02-15 05:23:26,436 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:693.0/900s
2019-02-15 05:23:28,361 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052328343526
2019-02-15 05:23:28,390 [salt.minion      :1432][INFO    ][19968] Starting a new job with PID 19968
2019-02-15 05:23:28,412 [salt.minion      :1711][INFO    ][19968] Returning information for job: 20190215052328343526
2019-02-15 05:23:31,492 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:688.0/900s
2019-02-15 05:23:36,541 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:683.0/900s
2019-02-15 05:23:38,539 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052338517701
2019-02-15 05:23:38,563 [salt.minion      :1432][INFO    ][19979] Starting a new job with PID 19979
2019-02-15 05:23:38,583 [salt.minion      :1711][INFO    ][19979] Returning information for job: 20190215052338517701
2019-02-15 05:23:41,595 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2019-02-15 05:23:46,668 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2019-02-15 05:23:48,713 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052348698470
2019-02-15 05:23:48,745 [salt.minion      :1432][INFO    ][20006] Starting a new job with PID 20006
2019-02-15 05:23:48,795 [salt.minion      :1711][INFO    ][20006] Returning information for job: 20190215052348698470
2019-02-15 05:23:51,725 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2019-02-15 05:23:56,779 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2019-02-15 05:23:58,732 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052358713678
2019-02-15 05:23:58,756 [salt.minion      :1432][INFO    ][20017] Starting a new job with PID 20017
2019-02-15 05:23:58,777 [salt.minion      :1711][INFO    ][20017] Returning information for job: 20190215052358713678
2019-02-15 05:24:01,820 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2019-02-15 05:24:06,866 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2019-02-15 05:24:08,859 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052408840493
2019-02-15 05:24:08,876 [salt.minion      :1432][INFO    ][20037] Starting a new job with PID 20037
2019-02-15 05:24:08,902 [salt.minion      :1711][INFO    ][20037] Returning information for job: 20190215052408840493
2019-02-15 05:24:11,916 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2019-02-15 05:24:16,974 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2019-02-15 05:24:19,051 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052419029331
2019-02-15 05:24:19,073 [salt.minion      :1432][INFO    ][20063] Starting a new job with PID 20063
2019-02-15 05:24:19,096 [salt.minion      :1711][INFO    ][20063] Returning information for job: 20190215052419029331
2019-02-15 05:24:22,027 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2019-02-15 05:24:27,080 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2019-02-15 05:24:29,256 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052429235236
2019-02-15 05:24:29,284 [salt.minion      :1432][INFO    ][20071] Starting a new job with PID 20071
2019-02-15 05:24:29,310 [salt.minion      :1711][INFO    ][20071] Returning information for job: 20190215052429235236
2019-02-15 05:24:32,159 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:627.0/900s
2019-02-15 05:24:37,213 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:622.0/900s
2019-02-15 05:24:39,475 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052439453371
2019-02-15 05:24:39,498 [salt.minion      :1432][INFO    ][20087] Starting a new job with PID 20087
2019-02-15 05:24:39,521 [salt.minion      :1711][INFO    ][20087] Returning information for job: 20190215052439453371
2019-02-15 05:24:42,258 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:617.0/900s
2019-02-15 05:24:47,323 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:612.0/900s
2019-02-15 05:24:49,695 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052449673728
2019-02-15 05:24:49,726 [salt.minion      :1432][INFO    ][20116] Starting a new job with PID 20116
2019-02-15 05:24:49,770 [salt.minion      :1711][INFO    ][20116] Returning information for job: 20190215052449673728
2019-02-15 05:24:52,387 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:607.0/900s
2019-02-15 05:24:57,443 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:602.0/900s
2019-02-15 05:24:59,737 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052459717662
2019-02-15 05:24:59,757 [salt.minion      :1432][INFO    ][20130] Starting a new job with PID 20130
2019-02-15 05:24:59,782 [salt.minion      :1711][INFO    ][20130] Returning information for job: 20190215052459717662
2019-02-15 05:25:02,504 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:597.0/900s
2019-02-15 05:25:07,550 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2019-02-15 05:25:09,916 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052509898691
2019-02-15 05:25:09,937 [salt.minion      :1432][INFO    ][20153] Starting a new job with PID 20153
2019-02-15 05:25:09,971 [salt.minion      :1711][INFO    ][20153] Returning information for job: 20190215052509898691
2019-02-15 05:25:12,599 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2019-02-15 05:25:17,651 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2019-02-15 05:25:20,093 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052520077609
2019-02-15 05:25:20,113 [salt.minion      :1432][INFO    ][20177] Starting a new job with PID 20177
2019-02-15 05:25:20,134 [salt.minion      :1711][INFO    ][20177] Returning information for job: 20190215052520077609
2019-02-15 05:25:22,692 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2019-02-15 05:25:27,735 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2019-02-15 05:25:30,265 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052530250629
2019-02-15 05:25:30,284 [salt.minion      :1432][INFO    ][20186] Starting a new job with PID 20186
2019-02-15 05:25:30,308 [salt.minion      :1711][INFO    ][20186] Returning information for job: 20190215052530250629
2019-02-15 05:25:32,781 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2019-02-15 05:25:37,844 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2019-02-15 05:25:40,448 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052540433437
2019-02-15 05:25:40,473 [salt.minion      :1432][INFO    ][20198] Starting a new job with PID 20198
2019-02-15 05:25:40,499 [salt.minion      :1711][INFO    ][20198] Returning information for job: 20190215052540433437
2019-02-15 05:25:42,885 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2019-02-15 05:25:47,930 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2019-02-15 05:25:50,656 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052550639501
2019-02-15 05:25:50,677 [salt.minion      :1432][INFO    ][20227] Starting a new job with PID 20227
2019-02-15 05:25:50,699 [salt.minion      :1711][INFO    ][20227] Returning information for job: 20190215052550639501
2019-02-15 05:25:52,972 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:546.0/900s
2019-02-15 05:25:58,013 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:541.0/900s
2019-02-15 05:26:00,876 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052600860080
2019-02-15 05:26:00,897 [salt.minion      :1432][INFO    ][20236] Starting a new job with PID 20236
2019-02-15 05:26:00,919 [salt.minion      :1711][INFO    ][20236] Returning information for job: 20190215052600860080
2019-02-15 05:26:03,059 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:536.0/900s
2019-02-15 05:26:08,113 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:531.0/900s
2019-02-15 05:26:10,891 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052610875573
2019-02-15 05:26:10,916 [salt.minion      :1432][INFO    ][20257] Starting a new job with PID 20257
2019-02-15 05:26:10,937 [salt.minion      :1711][INFO    ][20257] Returning information for job: 20190215052610875573
2019-02-15 05:26:13,152 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:526.0/900s
2019-02-15 05:26:18,195 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:521.0/900s
2019-02-15 05:26:21,072 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052621056352
2019-02-15 05:26:21,090 [salt.minion      :1432][INFO    ][20282] Starting a new job with PID 20282
2019-02-15 05:26:21,113 [salt.minion      :1711][INFO    ][20282] Returning information for job: 20190215052621056352
2019-02-15 05:26:23,240 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:516.0/900s
2019-02-15 05:26:28,286 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:511.0/900s
2019-02-15 05:26:31,242 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052631226594
2019-02-15 05:26:31,263 [salt.minion      :1432][INFO    ][20290] Starting a new job with PID 20290
2019-02-15 05:26:31,288 [salt.minion      :1711][INFO    ][20290] Returning information for job: 20190215052631226594
2019-02-15 05:26:33,334 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:506.0/900s
2019-02-15 05:26:38,381 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:501.0/900s
2019-02-15 05:26:41,309 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052641292356
2019-02-15 05:26:41,334 [salt.minion      :1432][INFO    ][20303] Starting a new job with PID 20303
2019-02-15 05:26:41,355 [salt.minion      :1711][INFO    ][20303] Returning information for job: 20190215052641292356
2019-02-15 05:26:43,428 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:496.0/900s
2019-02-15 05:26:48,484 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:491.0/900s
2019-02-15 05:26:51,377 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052651355298
2019-02-15 05:26:51,404 [salt.minion      :1432][INFO    ][20332] Starting a new job with PID 20332
2019-02-15 05:26:51,428 [salt.minion      :1711][INFO    ][20332] Returning information for job: 20190215052651355298
2019-02-15 05:26:53,553 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2019-02-15 05:26:58,603 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2019-02-15 05:27:01,497 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052701473519
2019-02-15 05:27:01,527 [salt.minion      :1432][INFO    ][20340] Starting a new job with PID 20340
2019-02-15 05:27:01,551 [salt.minion      :1711][INFO    ][20340] Returning information for job: 20190215052701473519
2019-02-15 05:27:03,655 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2019-02-15 05:27:08,705 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:470.0/900s
2019-02-15 05:27:11,597 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052711575294
2019-02-15 05:27:11,620 [salt.minion      :1432][INFO    ][20361] Starting a new job with PID 20361
2019-02-15 05:27:11,641 [salt.minion      :1711][INFO    ][20361] Returning information for job: 20190215052711575294
2019-02-15 05:27:13,747 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:465.0/900s
2019-02-15 05:27:18,787 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:460.0/900s
2019-02-15 05:27:21,634 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052721619575
2019-02-15 05:27:21,652 [salt.minion      :1432][INFO    ][20387] Starting a new job with PID 20387
2019-02-15 05:27:21,677 [salt.minion      :1711][INFO    ][20387] Returning information for job: 20190215052721619575
2019-02-15 05:27:23,838 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:455.0/900s
2019-02-15 05:27:28,897 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:450.0/900s
2019-02-15 05:27:31,731 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052731708109
2019-02-15 05:27:31,763 [salt.minion      :1432][INFO    ][20409] Starting a new job with PID 20409
2019-02-15 05:27:31,788 [salt.minion      :1711][INFO    ][20409] Returning information for job: 20190215052731708109
2019-02-15 05:27:33,956 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:445.0/900s
2019-02-15 05:27:39,010 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:440.0/900s
2019-02-15 05:27:41,856 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052741839689
2019-02-15 05:27:41,890 [salt.minion      :1432][INFO    ][20421] Starting a new job with PID 20421
2019-02-15 05:27:41,911 [salt.minion      :1711][INFO    ][20421] Returning information for job: 20190215052741839689
2019-02-15 05:27:44,061 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:435.0/900s
2019-02-15 05:27:49,115 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:430.0/900s
2019-02-15 05:27:51,992 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052751968590
2019-02-15 05:27:52,016 [salt.minion      :1432][INFO    ][20449] Starting a new job with PID 20449
2019-02-15 05:27:52,034 [salt.minion      :1711][INFO    ][20449] Returning information for job: 20190215052751968590
2019-02-15 05:27:54,161 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:425.0/900s
2019-02-15 05:27:59,225 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:420.0/900s
2019-02-15 05:28:02,129 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052802113116
2019-02-15 05:28:02,155 [salt.minion      :1432][INFO    ][20457] Starting a new job with PID 20457
2019-02-15 05:28:02,178 [salt.minion      :1711][INFO    ][20457] Returning information for job: 20190215052802113116
2019-02-15 05:28:04,280 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:415.0/900s
2019-02-15 05:28:09,320 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:410.0/900s
2019-02-15 05:28:12,258 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052812242444
2019-02-15 05:28:12,281 [salt.minion      :1432][INFO    ][20483] Starting a new job with PID 20483
2019-02-15 05:28:12,303 [salt.minion      :1711][INFO    ][20483] Returning information for job: 20190215052812242444
2019-02-15 05:28:14,378 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:405.0/900s
2019-02-15 05:28:19,441 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:400.0/900s
2019-02-15 05:28:22,409 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052822391159
2019-02-15 05:28:22,432 [salt.minion      :1432][INFO    ][20515] Starting a new job with PID 20515
2019-02-15 05:28:22,454 [salt.minion      :1711][INFO    ][20515] Returning information for job: 20190215052822391159
2019-02-15 05:28:24,483 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:395.0/900s
2019-02-15 05:28:29,540 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:390.0/900s
2019-02-15 05:28:32,482 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052832464387
2019-02-15 05:28:32,511 [salt.minion      :1432][INFO    ][20528] Starting a new job with PID 20528
2019-02-15 05:28:32,533 [salt.minion      :1711][INFO    ][20528] Returning information for job: 20190215052832464387
2019-02-15 05:28:34,602 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:384.0/900s
2019-02-15 05:28:39,660 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:379.0/900s
2019-02-15 05:28:42,650 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052842625716
2019-02-15 05:28:42,674 [salt.minion      :1432][INFO    ][20547] Starting a new job with PID 20547
2019-02-15 05:28:42,696 [salt.minion      :1711][INFO    ][20547] Returning information for job: 20190215052842625716
2019-02-15 05:28:44,714 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:374.0/900s
2019-02-15 05:28:49,767 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:369.0/900s
2019-02-15 05:28:52,825 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052852803257
2019-02-15 05:28:52,853 [salt.minion      :1432][INFO    ][20573] Starting a new job with PID 20573
2019-02-15 05:28:52,882 [salt.minion      :1711][INFO    ][20573] Returning information for job: 20190215052852803257
2019-02-15 05:28:54,818 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:364.0/900s
2019-02-15 05:28:59,872 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:359.0/900s
2019-02-15 05:29:03,017 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052902996774
2019-02-15 05:29:03,041 [salt.minion      :1432][INFO    ][20582] Starting a new job with PID 20582
2019-02-15 05:29:03,063 [salt.minion      :1711][INFO    ][20582] Returning information for job: 20190215052902996774
2019-02-15 05:29:04,918 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:354.0/900s
2019-02-15 05:29:09,982 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:349.0/900s
2019-02-15 05:29:13,167 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052913148215
2019-02-15 05:29:13,191 [salt.minion      :1432][INFO    ][20603] Starting a new job with PID 20603
2019-02-15 05:29:13,213 [salt.minion      :1711][INFO    ][20603] Returning information for job: 20190215052913148215
2019-02-15 05:29:15,024 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:344.0/900s
2019-02-15 05:29:20,073 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:339.0/900s
2019-02-15 05:29:23,339 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052923313406
2019-02-15 05:29:23,367 [salt.minion      :1432][INFO    ][20628] Starting a new job with PID 20628
2019-02-15 05:29:23,389 [salt.minion      :1711][INFO    ][20628] Returning information for job: 20190215052923313406
2019-02-15 05:29:25,142 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:334.0/900s
2019-02-15 05:29:30,183 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:329.0/900s
2019-02-15 05:29:33,446 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052933427888
2019-02-15 05:29:33,464 [salt.minion      :1432][INFO    ][20635] Starting a new job with PID 20635
2019-02-15 05:29:33,488 [salt.minion      :1711][INFO    ][20635] Returning information for job: 20190215052933427888
2019-02-15 05:29:35,241 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:324.0/900s
2019-02-15 05:29:40,301 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:319.0/900s
2019-02-15 05:29:43,626 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052943606784
2019-02-15 05:29:43,650 [salt.minion      :1432][INFO    ][20664] Starting a new job with PID 20664
2019-02-15 05:29:43,674 [salt.minion      :1711][INFO    ][20664] Returning information for job: 20190215052943606784
2019-02-15 05:29:45,368 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:314.0/900s
2019-02-15 05:29:50,416 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:309.0/900s
2019-02-15 05:29:53,847 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215052953826029
2019-02-15 05:29:53,872 [salt.minion      :1432][INFO    ][20678] Starting a new job with PID 20678
2019-02-15 05:29:53,897 [salt.minion      :1711][INFO    ][20678] Returning information for job: 20190215052953826029
2019-02-15 05:29:55,470 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:304.0/900s
2019-02-15 05:30:00,533 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:299.0/900s
2019-02-15 05:30:04,038 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053004017831
2019-02-15 05:30:04,067 [salt.minion      :1432][INFO    ][20686] Starting a new job with PID 20686
2019-02-15 05:30:04,095 [salt.minion      :1711][INFO    ][20686] Returning information for job: 20190215053004017831
2019-02-15 05:30:05,590 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:293.0/900s
2019-02-15 05:30:10,640 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:288.0/900s
2019-02-15 05:30:14,062 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053014044713
2019-02-15 05:30:14,086 [salt.minion      :1432][INFO    ][20718] Starting a new job with PID 20718
2019-02-15 05:30:14,109 [salt.minion      :1711][INFO    ][20718] Returning information for job: 20190215053014044713
2019-02-15 05:30:15,695 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:283.0/900s
2019-02-15 05:30:20,757 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:278.0/900s
2019-02-15 05:30:24,290 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053024273641
2019-02-15 05:30:24,313 [salt.minion      :1432][INFO    ][20731] Starting a new job with PID 20731
2019-02-15 05:30:24,336 [salt.minion      :1711][INFO    ][20731] Returning information for job: 20190215053024273641
2019-02-15 05:30:25,816 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:273.0/900s
2019-02-15 05:30:30,866 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:268.0/900s
2019-02-15 05:30:34,333 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053034316311
2019-02-15 05:30:34,350 [salt.minion      :1432][INFO    ][20739] Starting a new job with PID 20739
2019-02-15 05:30:34,372 [salt.minion      :1711][INFO    ][20739] Returning information for job: 20190215053034316311
2019-02-15 05:30:35,906 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:263.0/900s
2019-02-15 05:30:40,950 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:258.0/900s
2019-02-15 05:30:44,473 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053044449920
2019-02-15 05:30:44,500 [salt.minion      :1432][INFO    ][20766] Starting a new job with PID 20766
2019-02-15 05:30:44,523 [salt.minion      :1711][INFO    ][20766] Returning information for job: 20190215053044449920
2019-02-15 05:30:46,004 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:253.0/900s
2019-02-15 05:30:51,079 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:248.0/900s
2019-02-15 05:30:54,520 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053054496352
2019-02-15 05:30:54,550 [salt.minion      :1432][INFO    ][20778] Starting a new job with PID 20778
2019-02-15 05:30:54,575 [salt.minion      :1711][INFO    ][20778] Returning information for job: 20190215053054496352
2019-02-15 05:30:56,138 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:243.0/900s
2019-02-15 05:31:01,195 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:238.0/900s
2019-02-15 05:31:04,587 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053104565403
2019-02-15 05:31:04,613 [salt.minion      :1432][INFO    ][20787] Starting a new job with PID 20787
2019-02-15 05:31:04,634 [salt.minion      :1711][INFO    ][20787] Returning information for job: 20190215053104565403
2019-02-15 05:31:06,252 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:233.0/900s
2019-02-15 05:31:11,302 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:228.0/900s
2019-02-15 05:31:14,618 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053114599408
2019-02-15 05:31:14,642 [salt.minion      :1432][INFO    ][20819] Starting a new job with PID 20819
2019-02-15 05:31:14,665 [salt.minion      :1711][INFO    ][20819] Returning information for job: 20190215053114599408
2019-02-15 05:31:16,354 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:223.0/900s
2019-02-15 05:31:21,400 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:218.0/900s
2019-02-15 05:31:24,668 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053124646606
2019-02-15 05:31:24,692 [salt.minion      :1432][INFO    ][20832] Starting a new job with PID 20832
2019-02-15 05:31:24,712 [salt.minion      :1711][INFO    ][20832] Returning information for job: 20190215053124646606
2019-02-15 05:31:26,457 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:213.0/900s
2019-02-15 05:31:31,507 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:208.0/900s
2019-02-15 05:31:34,769 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053134752349
2019-02-15 05:31:34,790 [salt.minion      :1432][INFO    ][20839] Starting a new job with PID 20839
2019-02-15 05:31:34,814 [salt.minion      :1711][INFO    ][20839] Returning information for job: 20190215053134752349
2019-02-15 05:31:36,557 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:202.0/900s
2019-02-15 05:31:41,596 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:197.0/900s
2019-02-15 05:31:44,929 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053144910127
2019-02-15 05:31:44,947 [salt.minion      :1432][INFO    ][20870] Starting a new job with PID 20870
2019-02-15 05:31:44,970 [salt.minion      :1711][INFO    ][20870] Returning information for job: 20190215053144910127
2019-02-15 05:31:46,657 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:192.0/900s
2019-02-15 05:31:51,702 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:187.0/900s
2019-02-15 05:31:54,993 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053154970952
2019-02-15 05:31:55,022 [salt.minion      :1432][INFO    ][20881] Starting a new job with PID 20881
2019-02-15 05:31:55,043 [salt.minion      :1711][INFO    ][20881] Returning information for job: 20190215053154970952
2019-02-15 05:31:56,754 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:182.0/900s
2019-02-15 05:32:01,809 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:177.0/900s
2019-02-15 05:32:05,101 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053205077212
2019-02-15 05:32:05,127 [salt.minion      :1432][INFO    ][20889] Starting a new job with PID 20889
2019-02-15 05:32:05,148 [salt.minion      :1711][INFO    ][20889] Returning information for job: 20190215053205077212
2019-02-15 05:32:06,862 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:172.0/900s
2019-02-15 05:32:11,923 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:167.0/900s
2019-02-15 05:32:15,176 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053215160039
2019-02-15 05:32:15,198 [salt.minion      :1432][INFO    ][20926] Starting a new job with PID 20926
2019-02-15 05:32:15,220 [salt.minion      :1711][INFO    ][20926] Returning information for job: 20190215053215160039
2019-02-15 05:32:16,984 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:162.0/900s
2019-02-15 05:32:22,033 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:157.0/900s
2019-02-15 05:32:25,289 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053225271246
2019-02-15 05:32:25,311 [salt.minion      :1432][INFO    ][20935] Starting a new job with PID 20935
2019-02-15 05:32:25,332 [salt.minion      :1711][INFO    ][20935] Returning information for job: 20190215053225271246
2019-02-15 05:32:27,084 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:152.0/900s
2019-02-15 05:32:32,129 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:147.0/900s
2019-02-15 05:32:35,463 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053235443800
2019-02-15 05:32:35,490 [salt.minion      :1432][INFO    ][20942] Starting a new job with PID 20942
2019-02-15 05:32:35,511 [salt.minion      :1711][INFO    ][20942] Returning information for job: 20190215053235443800
2019-02-15 05:32:37,191 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:142.0/900s
2019-02-15 05:32:42,247 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:137.0/900s
2019-02-15 05:32:45,589 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053245572060
2019-02-15 05:32:45,605 [salt.minion      :1432][INFO    ][20973] Starting a new job with PID 20973
2019-02-15 05:32:45,626 [salt.minion      :1711][INFO    ][20973] Returning information for job: 20190215053245572060
2019-02-15 05:32:47,287 [salt.loaded.ext.module.maasng:1600][INFO    ][11529] Waiting boot-resources import done
sleep for:5s Left:132.0/900s
2019-02-15 05:32:52,373 [salt.state       :300 ][INFO    ][11529] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-02-15 05:32:52,374 [salt.state       :1951][INFO    ][11529] Completed state [maas_region_boot_sources_selection_xenial] at time 05:32:52.373918 duration_in_ms=778627.104
2019-02-15 05:32:52,376 [salt.state       :1780][INFO    ][11529] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:32:52.376258
2019-02-15 05:32:52,377 [salt.state       :1813][INFO    ][11529] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-15 05:32:52,378 [salt.utils.decorators:613 ][WARNING ][11529] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:32:52,379 [salt.loaded.ext.module.maasng:1771][INFO    ][11529] boot-sources sync initiated for ALL Rack's
2019-02-15 05:32:52,830 [salt.loaded.ext.module.maasng:1878][INFO    ][11529] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-02-15 05:32:55,731 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053255715149
2019-02-15 05:32:55,754 [salt.minion      :1432][INFO    ][21001] Starting a new job with PID 21001
2019-02-15 05:32:55,778 [salt.minion      :1711][INFO    ][21001] Returning information for job: 20190215053255715149
2019-02-15 05:32:58,087 [salt.loaded.ext.module.maasng:1878][INFO    ][11529] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2019-02-15 05:33:03,328 [salt.loaded.ext.module.maasng:1878][INFO    ][11529] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-02-15 05:33:05,914 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053305898734
2019-02-15 05:33:05,980 [salt.minion      :1432][INFO    ][21019] Starting a new job with PID 21019
2019-02-15 05:33:06,068 [salt.minion      :1711][INFO    ][21019] Returning information for job: 20190215053305898734
2019-02-15 05:33:08,640 [salt.loaded.ext.module.maasng:1878][INFO    ][11529] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-02-15 05:33:14,610 [salt.state       :300 ][INFO    ][11529] {'ret': True}
2019-02-15 05:33:14,611 [salt.state       :1951][INFO    ][11529] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:33:14.611430 duration_in_ms=22235.171
2019-02-15 05:33:14,612 [salt.state       :1780][INFO    ][11529] Running state [maas.process_maas_config] at time 05:33:14.612908
2019-02-15 05:33:14,613 [salt.state       :1813][INFO    ][11529] Executing state module.run for [maas.process_maas_config]
2019-02-15 05:33:14,613 [salt.utils.decorators:613 ][WARNING ][11529] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:33:14,614 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=enable_http_proxy value=True
2019-02-15 05:33:14,676 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=upstream_dns value=8.8.8.8
2019-02-15 05:33:14,733 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=commissioning_distro_series value=xenial
2019-02-15 05:33:16,045 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053316023820
2019-02-15 05:33:16,071 [salt.minion      :1432][INFO    ][21191] Starting a new job with PID 21191
2019-02-15 05:33:16,122 [salt.minion      :1711][INFO    ][21191] Returning information for job: 20190215053316023820
2019-02-15 05:33:17,076 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=default_osystem value=ubuntu
2019-02-15 05:33:17,158 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=active_discovery_interval value=600
2019-02-15 05:33:17,209 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=dnssec_validation value=no
2019-02-15 05:33:17,275 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=maas_name value=mas01
2019-02-15 05:33:17,324 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=network_discovery value=enabled
2019-02-15 05:33:17,444 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=enable_third_party_drivers value=True
2019-02-15 05:33:17,487 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=default_storage_layout value=lvm
2019-02-15 05:33:17,535 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=ntp_external_only value=True
2019-02-15 05:33:17,592 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-15 05:33:17,635 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=default_distro_series value=xenial
2019-02-15 05:33:17,693 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-02-15 05:33:17,827 [salt.state       :300 ][INFO    ][11529] {'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-02-15 05:33:17,828 [salt.state       :1951][INFO    ][11529] Completed state [maas.process_maas_config] at time 05:33:17.828116 duration_in_ms=3215.208
2019-02-15 05:33:17,828 [salt.state       :1780][INFO    ][11529] Running state [pxe_admin] at time 05:33:17.828897
2019-02-15 05:33:17,829 [salt.state       :1813][INFO    ][11529] Executing state maasng.fabric_present for [pxe_admin]
2019-02-15 05:33:17,883 [salt.loaded.ext.module.maasng:945 ][INFO    ][11529] [{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': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-4', u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-15 05:33:17,937 [salt.loaded.ext.module.maasng:1008][WARNING ][11529] Detected cidr:192.168.11.0/24 in fabric:fabric-4
2019-02-15 05:33:17,937 [salt.loaded.ext.module.maasng:1011][WARNING ][11529] Guessing, that fabric with current name:fabric-4
 should be renamed to:pxe_admin
2019-02-15 05:33:17,993 [salt.state       :300 ][INFO    ][11529] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-15 05:33:17,994 [salt.state       :1951][INFO    ][11529] Completed state [pxe_admin] at time 05:33:17.994140 duration_in_ms=165.243
2019-02-15 05:33:17,994 [salt.state       :1780][INFO    ][11529] Running state [vlan 0] at time 05:33:17.994460
2019-02-15 05:33:17,994 [salt.state       :1813][INFO    ][11529] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-15 05:33:18,055 [salt.loaded.ext.module.maasng:945 ][INFO    ][11529] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': None, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-15 05:33:18,141 [salt.loaded.ext.module.maasng:945 ][INFO    ][11529] [{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': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-15 05:33:18,429 [salt.loaded.ext.module.maasng:945 ][INFO    ][11529] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-15 05:33:18,521 [salt.state       :300 ][INFO    ][11529] {'new': 'Vlan untagged was updated'}
2019-02-15 05:33:18,521 [salt.state       :1951][INFO    ][11529] Completed state [vlan 0] at time 05:33:18.521508 duration_in_ms=527.046
2019-02-15 05:33:18,522 [salt.state       :1780][INFO    ][11529] Running state [192.168.11.0/24] at time 05:33:18.522842
2019-02-15 05:33:18,523 [salt.state       :1813][INFO    ][11529] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-15 05:33:18,684 [salt.loaded.ext.module.maasng:945 ][INFO    ][11529] [{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': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wmdxxe', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-15 05:33:18,685 [salt.loaded.ext.module.maasng:1235][WARNING ][11529] Ignoring parameter vlan:0
2019-02-15 05:33:18,760 [salt.state       :300 ][INFO    ][11529] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-15 05:33:18,760 [salt.state       :1951][INFO    ][11529] Completed state [192.168.11.0/24] at time 05:33:18.760556 duration_in_ms=237.714
2019-02-15 05:33:18,761 [salt.state       :1780][INFO    ][11529] Running state [maas_create_iprange_1] at time 05:33:18.761427
2019-02-15 05:33:18,761 [salt.state       :1813][INFO    ][11529] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-15 05:33:18,875 [salt.state       :300 ][INFO    ][11529] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-02-15 05:33:18,875 [salt.state       :1951][INFO    ][11529] Completed state [maas_create_iprange_1] at time 05:33:18.875921 duration_in_ms=114.493
2019-02-15 05:33:18,876 [salt.state       :1780][INFO    ][11529] Running state [vlan 0] at time 05:33:18.876213
2019-02-15 05:33:18,876 [salt.state       :1813][INFO    ][11529] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-15 05:33:18,932 [salt.loaded.ext.module.maasng:945 ][INFO    ][11529] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'wmdxxe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-15 05:33:19,041 [salt.loaded.ext.module.maasng:945 ][INFO    ][11529] [{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': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wmdxxe', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-15 05:33:19,457 [salt.loaded.ext.module.maasng:945 ][INFO    ][11529] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'wmdxxe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-15 05:33:19,661 [salt.state       :300 ][INFO    ][11529] {'new': 'Vlan untagged was updated'}
2019-02-15 05:33:19,661 [salt.state       :1951][INFO    ][11529] Completed state [vlan 0] at time 05:33:19.661519 duration_in_ms=785.306
2019-02-15 05:33:19,662 [salt.state       :1780][INFO    ][11529] Running state [opnfv] at time 05:33:19.662205
2019-02-15 05:33:19,662 [salt.state       :1813][INFO    ][11529] Executing state maasng.sshkey_present for [opnfv]
2019-02-15 05:33:19,727 [salt.loaded.ext.module.maasng:1903][INFO    ][11529] []
2019-02-15 05:33:19,853 [salt.state       :300 ][INFO    ][11529] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2019-02-15 05:33:19,853 [salt.state       :1951][INFO    ][11529] Completed state [opnfv] at time 05:33:19.853756 duration_in_ms=191.551
2019-02-15 05:33:19,854 [salt.state       :1780][INFO    ][11529] Running state [maas.process_tags] at time 05:33:19.854777
2019-02-15 05:33:19,855 [salt.state       :1813][INFO    ][11529] Executing state module.run for [maas.process_tags]
2019-02-15 05:33:19,855 [salt.utils.decorators:613 ][WARNING ][11529] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:33:19,903 [salt.loaded.ext.module.maas:92  ][INFO    ][11529] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-02-15 05:33:19,956 [salt.state       :300 ][INFO    ][11529] {'ret': {'updated': [], 'errors': {}, 'success': ['aarch64_hugepages_1g']}}
2019-02-15 05:33:19,956 [salt.state       :1951][INFO    ][11529] Completed state [maas.process_tags] at time 05:33:19.956885 duration_in_ms=102.107
2019-02-15 05:33:19,961 [salt.minion      :1711][INFO    ][11529] Returning information for job: 20190215051442269319
2019-02-15 05:33:20,908 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command state.apply with jid 20190215053320892531
2019-02-15 05:33:20,931 [salt.minion      :1432][INFO    ][21308] Starting a new job with PID 21308
2019-02-15 05:33:26,591 [salt.state       :915 ][INFO    ][21308] Loading fresh modules for state activity
2019-02-15 05:33:26,653 [salt.fileclient  :1219][INFO    ][21308] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-02-15 05:33:26,708 [salt.state       :1780][INFO    ][21308] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:33:26.708145
2019-02-15 05:33:26,708 [salt.state       :1813][INFO    ][21308] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-15 05:33:26,711 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21308] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-15 05:33:28,783 [salt.state       :300 ][INFO    ][21308] {'pid': 21320, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-15 05:33:28,785 [salt.state       :1951][INFO    ][21308] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:33:28.784997 duration_in_ms=2076.855
2019-02-15 05:33:28,787 [salt.state       :1780][INFO    ][21308] Running state [maas.process_machines] at time 05:33:28.787296
2019-02-15 05:33:28,787 [salt.state       :1813][INFO    ][21308] Executing state module.run for [maas.process_machines]
2019-02-15 05:33:28,788 [salt.utils.decorators:613 ][WARNING ][21308] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:33:28,861 [salt.loaded.ext.module.maas:412 ][WARNING ][21308] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-15 05:33:28,862 [salt.loaded.ext.module.maas:92  ][INFO    ][21308] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-15 05:33:30,343 [salt.loaded.ext.module.maas:412 ][WARNING ][21308] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-15 05:33:30,344 [salt.loaded.ext.module.maas:92  ][INFO    ][21308] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-15 05:33:31,008 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053330989711
2019-02-15 05:33:31,029 [salt.minion      :1432][INFO    ][21421] Starting a new job with PID 21421
2019-02-15 05:33:31,056 [salt.minion      :1711][INFO    ][21421] Returning information for job: 20190215053330989711
2019-02-15 05:33:31,688 [salt.loaded.ext.module.maas:412 ][WARNING ][21308] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-15 05:33:31,688 [salt.loaded.ext.module.maas:92  ][INFO    ][21308] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-15 05:33:33,063 [salt.loaded.ext.module.maas:412 ][WARNING ][21308] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-15 05:33:33,063 [salt.loaded.ext.module.maas:92  ][INFO    ][21308] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-15 05:33:34,327 [salt.loaded.ext.module.maas:412 ][WARNING ][21308] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-15 05:33:34,328 [salt.loaded.ext.module.maas:92  ][INFO    ][21308] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-15 05:33:35,619 [salt.state       :300 ][INFO    ][21308] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-15 05:33:35,619 [salt.state       :1951][INFO    ][21308] Completed state [maas.process_machines] at time 05:33:35.619696 duration_in_ms=6832.399
2019-02-15 05:33:35,623 [salt.minion      :1711][INFO    ][21308] Returning information for job: 20190215053320892531
2019-02-15 05:34:06,668 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command state.apply with jid 20190215053406642897
2019-02-15 05:34:06,697 [salt.minion      :1432][INFO    ][21647] Starting a new job with PID 21647
2019-02-15 05:34:12,340 [salt.state       :915 ][INFO    ][21647] Loading fresh modules for state activity
2019-02-15 05:34:12,396 [salt.fileclient  :1219][INFO    ][21647] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-15 05:34:12,451 [salt.state       :1780][INFO    ][21647] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:12.451024
2019-02-15 05:34:12,451 [salt.state       :1813][INFO    ][21647] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-15 05:34:12,454 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21647] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-15 05:34:14,323 [salt.state       :300 ][INFO    ][21647] {'pid': 21654, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-15 05:34:14,324 [salt.state       :1951][INFO    ][21647] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:14.324624 duration_in_ms=1873.603
2019-02-15 05:34:14,326 [salt.state       :1780][INFO    ][21647] Running state [maas.wait_for_machine_status] at time 05:34:14.326768
2019-02-15 05:34:14,327 [salt.state       :1813][INFO    ][21647] Executing state module.run for [maas.wait_for_machine_status]
2019-02-15 05:34:14,328 [salt.utils.decorators:613 ][WARNING ][21647] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:34:15,263 [salt.loaded.ext.module.maas:1023][INFO    ][21647] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.07393193s left)
2019-02-15 05:34:16,767 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053416745285
2019-02-15 05:34:16,795 [salt.minion      :1432][INFO    ][21679] Starting a new job with PID 21679
2019-02-15 05:34:16,819 [salt.minion      :1711][INFO    ][21679] Returning information for job: 20190215053416745285
2019-02-15 05:34:26,986 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053426963944
2019-02-15 05:34:27,014 [salt.minion      :1432][INFO    ][21685] Starting a new job with PID 21685
2019-02-15 05:34:27,037 [salt.minion      :1711][INFO    ][21685] Returning information for job: 20190215053426963944
2019-02-15 05:34:37,193 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053437176169
2019-02-15 05:34:37,221 [salt.minion      :1432][INFO    ][21694] Starting a new job with PID 21694
2019-02-15 05:34:37,244 [salt.minion      :1711][INFO    ][21694] Returning information for job: 20190215053437176169
2019-02-15 05:34:46,196 [salt.loaded.ext.module.maas:1023][INFO    ][21647] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.14146185s left)
2019-02-15 05:34:47,401 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053447381362
2019-02-15 05:34:47,429 [salt.minion      :1432][INFO    ][21727] Starting a new job with PID 21727
2019-02-15 05:34:47,449 [salt.minion      :1711][INFO    ][21727] Returning information for job: 20190215053447381362
2019-02-15 05:34:57,605 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053457590120
2019-02-15 05:34:57,624 [salt.minion      :1432][INFO    ][21733] Starting a new job with PID 21733
2019-02-15 05:34:57,648 [salt.minion      :1711][INFO    ][21733] Returning information for job: 20190215053457590120
2019-02-15 05:35:07,807 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053507783419
2019-02-15 05:35:07,837 [salt.minion      :1432][INFO    ][21755] Starting a new job with PID 21755
2019-02-15 05:35:07,868 [salt.minion      :1711][INFO    ][21755] Returning information for job: 20190215053507783419
2019-02-15 05:35:17,096 [salt.loaded.ext.module.maas:1023][INFO    ][21647] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.24124789s left)
2019-02-15 05:35:17,847 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053517824593
2019-02-15 05:35:17,879 [salt.minion      :1432][INFO    ][21781] Starting a new job with PID 21781
2019-02-15 05:35:17,903 [salt.minion      :1711][INFO    ][21781] Returning information for job: 20190215053517824593
2019-02-15 05:35:28,066 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053528047011
2019-02-15 05:35:28,097 [salt.minion      :1432][INFO    ][21788] Starting a new job with PID 21788
2019-02-15 05:35:28,118 [salt.minion      :1711][INFO    ][21788] Returning information for job: 20190215053528047011
2019-02-15 05:35:38,285 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053538263984
2019-02-15 05:35:38,310 [salt.minion      :1432][INFO    ][21797] Starting a new job with PID 21797
2019-02-15 05:35:38,330 [salt.minion      :1711][INFO    ][21797] Returning information for job: 20190215053538263984
2019-02-15 05:35:47,988 [salt.loaded.ext.module.maas:1023][INFO    ][21647] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.34891891s left)
2019-02-15 05:35:48,492 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053548473920
2019-02-15 05:35:48,518 [salt.minion      :1432][INFO    ][21827] Starting a new job with PID 21827
2019-02-15 05:35:48,541 [salt.minion      :1711][INFO    ][21827] Returning information for job: 20190215053548473920
2019-02-15 05:35:58,714 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053558691720
2019-02-15 05:35:58,738 [salt.minion      :1432][INFO    ][21835] Starting a new job with PID 21835
2019-02-15 05:35:58,763 [salt.minion      :1711][INFO    ][21835] Returning information for job: 20190215053558691720
2019-02-15 05:36:08,908 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053608892504
2019-02-15 05:36:08,928 [salt.minion      :1432][INFO    ][21882] Starting a new job with PID 21882
2019-02-15 05:36:08,953 [salt.minion      :1711][INFO    ][21882] Returning information for job: 20190215053608892504
2019-02-15 05:36:19,113 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053619095597
2019-02-15 05:36:19,137 [salt.minion      :1432][INFO    ][21908] Starting a new job with PID 21908
2019-02-15 05:36:19,167 [salt.minion      :1711][INFO    ][21908] Returning information for job: 20190215053619095597
2019-02-15 05:36:19,250 [salt.loaded.ext.module.maas:1023][INFO    ][21647] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.08740091s left)
2019-02-15 05:36:29,329 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053629311398
2019-02-15 05:36:29,359 [salt.minion      :1432][INFO    ][21954] Starting a new job with PID 21954
2019-02-15 05:36:29,389 [salt.minion      :1711][INFO    ][21954] Returning information for job: 20190215053629311398
2019-02-15 05:36:39,537 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053639519852
2019-02-15 05:36:39,568 [salt.minion      :1432][INFO    ][21982] Starting a new job with PID 21982
2019-02-15 05:36:39,595 [salt.minion      :1711][INFO    ][21982] Returning information for job: 20190215053639519852
2019-02-15 05:36:49,563 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053649544267
2019-02-15 05:36:49,589 [salt.minion      :1432][INFO    ][22007] Starting a new job with PID 22007
2019-02-15 05:36:49,611 [salt.minion      :1711][INFO    ][22007] Returning information for job: 20190215053649544267
2019-02-15 05:36:50,522 [salt.loaded.ext.module.maas:1023][INFO    ][21647] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.81517005s left)
2019-02-15 05:36:59,788 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053659764735
2019-02-15 05:36:59,811 [salt.minion      :1432][INFO    ][22028] Starting a new job with PID 22028
2019-02-15 05:36:59,841 [salt.minion      :1711][INFO    ][22028] Returning information for job: 20190215053659764735
2019-02-15 05:37:09,798 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053709782502
2019-02-15 05:37:09,822 [salt.minion      :1432][INFO    ][22083] Starting a new job with PID 22083
2019-02-15 05:37:09,846 [salt.minion      :1711][INFO    ][22083] Returning information for job: 20190215053709782502
2019-02-15 05:37:19,994 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053719972750
2019-02-15 05:37:20,021 [salt.minion      :1432][INFO    ][22247] Starting a new job with PID 22247
2019-02-15 05:37:20,045 [salt.minion      :1711][INFO    ][22247] Returning information for job: 20190215053719972750
2019-02-15 05:37:21,787 [salt.loaded.ext.module.maas:1023][INFO    ][21647] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.55036306s left)
2019-02-15 05:37:30,201 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053730178568
2019-02-15 05:37:30,229 [salt.minion      :1432][INFO    ][22269] Starting a new job with PID 22269
2019-02-15 05:37:30,256 [salt.minion      :1711][INFO    ][22269] Returning information for job: 20190215053730178568
2019-02-15 05:37:40,245 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053740219448
2019-02-15 05:37:40,274 [salt.minion      :1432][INFO    ][22282] Starting a new job with PID 22282
2019-02-15 05:37:40,306 [salt.minion      :1711][INFO    ][22282] Returning information for job: 20190215053740219448
2019-02-15 05:37:50,258 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053750241192
2019-02-15 05:37:50,286 [salt.minion      :1432][INFO    ][22434] Starting a new job with PID 22434
2019-02-15 05:37:50,317 [salt.minion      :1711][INFO    ][22434] Returning information for job: 20190215053750241192
2019-02-15 05:37:53,133 [salt.loaded.ext.module.maas:1023][INFO    ][21647] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.20423603s left)
2019-02-15 05:38:00,465 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053800448719
2019-02-15 05:38:00,488 [salt.minion      :1432][INFO    ][22479] Starting a new job with PID 22479
2019-02-15 05:38:00,546 [salt.minion      :1711][INFO    ][22479] Returning information for job: 20190215053800448719
2019-02-15 05:38:10,496 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053810477047
2019-02-15 05:38:10,522 [salt.minion      :1432][INFO    ][22509] Starting a new job with PID 22509
2019-02-15 05:38:10,545 [salt.minion      :1711][INFO    ][22509] Returning information for job: 20190215053810477047
2019-02-15 05:38:20,521 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053820495167
2019-02-15 05:38:20,548 [salt.minion      :1432][INFO    ][22649] Starting a new job with PID 22649
2019-02-15 05:38:20,579 [salt.minion      :1711][INFO    ][22649] Returning information for job: 20190215053820495167
2019-02-15 05:38:25,168 [salt.loaded.ext.module.maas:1023][INFO    ][21647] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.16927195s left)
2019-02-15 05:38:30,566 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053830541350
2019-02-15 05:38:30,592 [salt.minion      :1432][INFO    ][22723] Starting a new job with PID 22723
2019-02-15 05:38:30,620 [salt.minion      :1711][INFO    ][22723] Returning information for job: 20190215053830541350
2019-02-15 05:38:40,617 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053840594839
2019-02-15 05:38:40,646 [salt.minion      :1432][INFO    ][22740] Starting a new job with PID 22740
2019-02-15 05:38:40,675 [salt.minion      :1711][INFO    ][22740] Returning information for job: 20190215053840594839
2019-02-15 05:38:50,654 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053850634524
2019-02-15 05:38:50,674 [salt.minion      :1432][INFO    ][22835] Starting a new job with PID 22835
2019-02-15 05:38:50,704 [salt.minion      :1711][INFO    ][22835] Returning information for job: 20190215053850634524
2019-02-15 05:38:57,516 [salt.loaded.ext.module.maas:1023][INFO    ][21647] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.82121897s left)
2019-02-15 05:39:00,693 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053900672756
2019-02-15 05:39:00,721 [salt.minion      :1432][INFO    ][22952] Starting a new job with PID 22952
2019-02-15 05:39:00,752 [salt.minion      :1711][INFO    ][22952] Returning information for job: 20190215053900672756
2019-02-15 05:39:10,757 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053910732791
2019-02-15 05:39:10,791 [salt.minion      :1432][INFO    ][22994] Starting a new job with PID 22994
2019-02-15 05:39:10,817 [salt.minion      :1711][INFO    ][22994] Returning information for job: 20190215053910732791
2019-02-15 05:39:20,795 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053920778447
2019-02-15 05:39:20,815 [salt.minion      :1432][INFO    ][23302] Starting a new job with PID 23302
2019-02-15 05:39:20,841 [salt.minion      :1711][INFO    ][23302] Returning information for job: 20190215053920778447
2019-02-15 05:39:29,829 [salt.loaded.ext.module.maas:1023][INFO    ][21647] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.50997996s left)
2019-02-15 05:39:30,837 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053930811839
2019-02-15 05:39:30,871 [salt.minion      :1432][INFO    ][23325] Starting a new job with PID 23325
2019-02-15 05:39:30,892 [salt.minion      :1711][INFO    ][23325] Returning information for job: 20190215053930811839
2019-02-15 05:39:40,914 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053940889592
2019-02-15 05:39:40,941 [salt.minion      :1432][INFO    ][23341] Starting a new job with PID 23341
2019-02-15 05:39:40,966 [salt.minion      :1711][INFO    ][23341] Returning information for job: 20190215053940889592
2019-02-15 05:39:50,968 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215053950948829
2019-02-15 05:39:50,991 [salt.minion      :1432][INFO    ][23398] Starting a new job with PID 23398
2019-02-15 05:39:51,017 [salt.minion      :1711][INFO    ][23398] Returning information for job: 20190215053950948829
2019-02-15 05:40:01,031 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054001013168
2019-02-15 05:40:01,057 [salt.minion      :1432][INFO    ][23481] Starting a new job with PID 23481
2019-02-15 05:40:01,084 [salt.minion      :1711][INFO    ][23481] Returning information for job: 20190215054001013168
2019-02-15 05:40:02,583 [salt.loaded.ext.module.maas:1023][INFO    ][21647] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:1500s (1151.75419903s left)
2019-02-15 05:40:11,106 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054011086618
2019-02-15 05:40:11,130 [salt.minion      :1432][INFO    ][23572] Starting a new job with PID 23572
2019-02-15 05:40:11,150 [salt.minion      :1711][INFO    ][23572] Returning information for job: 20190215054011086618
2019-02-15 05:40:21,191 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054021166398
2019-02-15 05:40:21,217 [salt.minion      :1432][INFO    ][23608] Starting a new job with PID 23608
2019-02-15 05:40:21,239 [salt.minion      :1711][INFO    ][23608] Returning information for job: 20190215054021166398
2019-02-15 05:40:31,242 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054031221499
2019-02-15 05:40:31,271 [salt.minion      :1432][INFO    ][23684] Starting a new job with PID 23684
2019-02-15 05:40:31,293 [salt.minion      :1711][INFO    ][23684] Returning information for job: 20190215054031221499
2019-02-15 05:40:35,887 [salt.state       :300 ][INFO    ][21647] {'ret': True}
2019-02-15 05:40:35,888 [salt.state       :1951][INFO    ][21647] Completed state [maas.wait_for_machine_status] at time 05:40:35.887997 duration_in_ms=381561.228
2019-02-15 05:40:35,895 [salt.minion      :1711][INFO    ][21647] Returning information for job: 20190215053406642897
2019-02-15 05:40:36,462 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command state.apply with jid 20190215054036441187
2019-02-15 05:40:36,488 [salt.minion      :1432][INFO    ][23706] Starting a new job with PID 23706
2019-02-15 05:40:42,142 [salt.state       :915 ][INFO    ][23706] Loading fresh modules for state activity
2019-02-15 05:40:42,202 [salt.fileclient  :1219][INFO    ][23706] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-15 05:40:42,308 [salt.state       :1780][INFO    ][23706] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:42.308557
2019-02-15 05:40:42,308 [salt.state       :1813][INFO    ][23706] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-15 05:40:42,311 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23706] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-15 05:40:44,599 [salt.state       :300 ][INFO    ][23706] {'pid': 23714, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-15 05:40:44,601 [salt.state       :1951][INFO    ][23706] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:44.601386 duration_in_ms=2292.829
2019-02-15 05:40:44,607 [salt.state       :1780][INFO    ][23706] Running state [maas_machines_storage_cmp002_lvm] at time 05:40:44.607271
2019-02-15 05:40:44,608 [salt.state       :1813][INFO    ][23706] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-15 05:40:45,947 [salt.loaded.ext.module.maasng:610 ][INFO    ][23706] wascba
2019-02-15 05:40:45,949 [salt.loaded.ext.module.maasng:626 ][INFO    ][23706] sda
2019-02-15 05:40:46,555 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054046535293
2019-02-15 05:40:46,579 [salt.minion      :1432][INFO    ][23745] Starting a new job with PID 23745
2019-02-15 05:40:46,588 [salt.loaded.ext.module.maasng:361 ][INFO    ][23706] wascba
2019-02-15 05:40:46,613 [salt.minion      :1711][INFO    ][23745] Returning information for job: 20190215054046535293
2019-02-15 05:40:46,695 [salt.loaded.ext.module.maasng:367 ][INFO    ][23706] [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'ebf253e7-d095-4d14-8df7-d7440589d369', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wascba', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'88ffb622-a4c3-4601-ae8c-4cd5d7904acd', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/blockdevices/2/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wascba', u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'partition_table_type': None, u'available_size': 0, u'uuid': u'dac4f3fe-4861-47dc-97f0-29e53f7bcac6', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wascba', u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/blockdevices/4/', u'filesystem': {u'mount_options': None, u'uuid': u'2774f461-444f-448e-96ce-c9c127509aa4', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'size': 800097042432}]
2019-02-15 05:40:46,699 [salt.loaded.ext.module.maasng:632 ][INFO    ][23706] vgroot
2019-02-15 05:40:46,700 [salt.loaded.ext.module.maasng:635 ][INFO    ][23706] lvroot
2019-02-15 05:40:46,701 [salt.loaded.ext.module.maasng:639 ][INFO    ][23706] 107374182400
2019-02-15 05:40:47,362 [salt.loaded.ext.module.maasng:645 ][INFO    ][23706] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, 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'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'd13152e6-1f70-42ed-a65d-d8c6b208906c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wascba', u'filesystem': {u'uuid': u'5d002821-4e46-4529-939b-03922ac98e51', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'wascba', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'396b92eb-9fc8-41cc-95d7-1213248364f9', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'wascba', u'partition_table_type': None, u'filesystem': {u'uuid': u'1fcd52ac-cee9-4bcb-9280-af2a215c84de', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/blockdevices/12/', u'used_size': 107374182400, u'id': 12, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'wascba', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'd13152e6-1f70-42ed-a65d-d8c6b208906c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wascba', u'filesystem': {u'uuid': u'5d002821-4e46-4529-939b-03922ac98e51', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'wascba', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'wascba', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'd13152e6-1f70-42ed-a65d-d8c6b208906c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wascba', u'filesystem': {u'uuid': u'5d002821-4e46-4529-939b-03922ac98e51', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'wascba', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wmdxxe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wmdxxe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 9, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wmdxxe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'wascba', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/interfaces/9/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 16, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'wascba', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/interfaces/16/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 20, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'wascba', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/interfaces/20/'}, {u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 17, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'wascba', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/interfaces/17/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wmdxxe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wmdxxe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 18, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wmdxxe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'wascba', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/interfaces/18/'}, {u'name': u'ens2f0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 19, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'wascba', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/interfaces/19/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'396b92eb-9fc8-41cc-95d7-1213248364f9', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'wascba', u'partition_table_type': None, u'filesystem': {u'uuid': u'1fcd52ac-cee9-4bcb-9280-af2a215c84de', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/blockdevices/12/', u'used_size': 107374182400, u'id': 12, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wmdxxe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wmdxxe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 9, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wmdxxe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'wascba', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wascba/interfaces/9/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/wascba/'}
2019-02-15 05:40:47,370 [salt.state       :300 ][INFO    ][23706] {'new': {'storage_layout': 'lvm'}}
2019-02-15 05:40:47,370 [salt.state       :1951][INFO    ][23706] Completed state [maas_machines_storage_cmp002_lvm] at time 05:40:47.370580 duration_in_ms=2763.306
2019-02-15 05:40:47,371 [salt.state       :1780][INFO    ][23706] Running state [maas_machines_storage_cmp001_lvm] at time 05:40:47.371170
2019-02-15 05:40:47,372 [salt.state       :1813][INFO    ][23706] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-15 05:40:48,605 [salt.loaded.ext.module.maasng:610 ][INFO    ][23706] sc7tak
2019-02-15 05:40:48,606 [salt.loaded.ext.module.maasng:626 ][INFO    ][23706] sda
2019-02-15 05:40:49,198 [salt.loaded.ext.module.maasng:361 ][INFO    ][23706] sc7tak
2019-02-15 05:40:49,303 [salt.loaded.ext.module.maasng:367 ][INFO    ][23706] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'cd26e189-834a-4b9a-a345-39594e828231', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sc7tak', u'filesystem': {u'uuid': u'0e9c7f04-8220-41e7-8af9-68906847783f', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/blockdevices/3/partition/2', u'type': u'partition', u'id': 2, u'device_id': 3}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'sc7tak', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/blockdevices/3/', u'used_size': 800106479616, u'id': 3, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'ba945fdc-fd60-4c1a-8edb-aaf7b909a573', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'sc7tak', u'partition_table_type': None, u'filesystem': {u'uuid': u'ebda92da-c665-49c8-ae75-61df42133f6c', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/blockdevices/5/', u'used_size': 800097042432, u'id': 5, u'size': 800097042432}]
2019-02-15 05:40:49,306 [salt.loaded.ext.module.maasng:632 ][INFO    ][23706] vgroot
2019-02-15 05:40:49,306 [salt.loaded.ext.module.maasng:635 ][INFO    ][23706] lvroot
2019-02-15 05:40:49,307 [salt.loaded.ext.module.maasng:639 ][INFO    ][23706] 107374182400
2019-02-15 05:40:49,990 [salt.loaded.ext.module.maasng:645 ][INFO    ][23706] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wmdxxe', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39', u'id': 33, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wmdxxe', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wmdxxe', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'sc7tak', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/interfaces/10/'}, 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': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'sc7tak', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'cfaf4ea9-82af-4cc0-aaec-3ea5047e0ae9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sc7tak', u'device_id': 3, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'9b439e57-9d9a-4295-88d4-8863f2071618', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/blockdevices/3/'}, {u'size': 107374182400, u'block_size': 4096, u'uuid': u'600fcb49-4e98-4273-9ebc-46c3f81702b3', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'sc7tak', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'ce7ec2a4-9d17-42a3-897a-f5cc19804cf2', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/blockdevices/13/'}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/sc7tak/', u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'sc7tak', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'sc7tak', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'sc7tak', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'cfaf4ea9-82af-4cc0-aaec-3ea5047e0ae9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sc7tak', u'device_id': 3, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'9b439e57-9d9a-4295-88d4-8863f2071618', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/blockdevices/3/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wmdxxe', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39', u'id': 33, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wmdxxe', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wmdxxe', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'sc7tak', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/interfaces/10/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'system_id': u'sc7tak', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/interfaces/22/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'system_id': u'sc7tak', u'type': u'physical', u'id': 24, u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/interfaces/24/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wmdxxe', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'id': 34, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wmdxxe', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wmdxxe', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.44'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'system_id': u'sc7tak', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/interfaces/21/'}, {u'name': u'ens2f1', u'links': [{u'id': 35, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'system_id': u'sc7tak', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/interfaces/23/'}, {u'name': u'ens2f0', u'links': [{u'id': 36, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'system_id': u'sc7tak', u'type': u'physical', u'id': 25, u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/interfaces/25/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'sc7tak', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'cfaf4ea9-82af-4cc0-aaec-3ea5047e0ae9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sc7tak', u'device_id': 3, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'9b439e57-9d9a-4295-88d4-8863f2071618', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/blockdevices/3/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'uuid': u'600fcb49-4e98-4273-9ebc-46c3f81702b3', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'sc7tak', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'ce7ec2a4-9d17-42a3-897a-f5cc19804cf2', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sc7tak/blockdevices/13/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-02-15 05:40:49,993 [salt.state       :300 ][INFO    ][23706] {'new': {'storage_layout': 'lvm'}}
2019-02-15 05:40:49,993 [salt.state       :1951][INFO    ][23706] Completed state [maas_machines_storage_cmp001_lvm] at time 05:40:49.993555 duration_in_ms=2622.385
2019-02-15 05:40:49,997 [salt.minion      :1711][INFO    ][23706] Returning information for job: 20190215054036441187
2019-02-15 05:40:50,549 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command state.apply with jid 20190215054050522729
2019-02-15 05:40:50,579 [salt.minion      :1432][INFO    ][23768] Starting a new job with PID 23768
2019-02-15 05:40:51,322 [salt.state       :915 ][INFO    ][23768] Loading fresh modules for state activity
2019-02-15 05:40:51,379 [salt.fileclient  :1219][INFO    ][23768] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-15 05:40:51,431 [salt.state       :1780][INFO    ][23768] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:51.431787
2019-02-15 05:40:51,432 [salt.state       :1813][INFO    ][23768] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-15 05:40:51,435 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23768] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-15 05:40:53,366 [salt.state       :300 ][INFO    ][23768] {'pid': 23776, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-15 05:40:53,367 [salt.state       :1951][INFO    ][23768] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:53.367842 duration_in_ms=1936.055
2019-02-15 05:40:53,371 [salt.state       :1780][INFO    ][23768] Running state [maas.deploy_machines] at time 05:40:53.371769
2019-02-15 05:40:53,372 [salt.state       :1813][INFO    ][23768] Executing state module.run for [maas.deploy_machines]
2019-02-15 05:40:53,373 [salt.utils.decorators:613 ][WARNING ][23768] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:40:54,026 [salt.loaded.ext.module.maas:684 ][INFO    ][23768] deploymachines hwe_kernel=hwe-16.04 system_id=wascba distro_series=xenial
2019-02-15 05:40:56,638 [salt.loaded.ext.module.maas:684 ][INFO    ][23768] deploymachines hwe_kernel=hwe-16.04 system_id=sc7tak distro_series=xenial
2019-02-15 05:40:59,165 [salt.loaded.ext.module.maas:684 ][INFO    ][23768] deploymachines hwe_kernel=hwe-16.04 system_id=g3f8an distro_series=xenial
2019-02-15 05:41:00,603 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054100583889
2019-02-15 05:41:00,627 [salt.minion      :1432][INFO    ][23925] Starting a new job with PID 23925
2019-02-15 05:41:00,649 [salt.minion      :1711][INFO    ][23925] Returning information for job: 20190215054100583889
2019-02-15 05:41:01,755 [salt.loaded.ext.module.maas:684 ][INFO    ][23768] deploymachines hwe_kernel=hwe-16.04 system_id=mhtwsq distro_series=xenial
2019-02-15 05:41:04,304 [salt.loaded.ext.module.maas:684 ][INFO    ][23768] deploymachines hwe_kernel=hwe-16.04 system_id=7b6eqd distro_series=xenial
2019-02-15 05:41:06,833 [salt.state       :300 ][INFO    ][23768] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-15 05:41:06,833 [salt.state       :1951][INFO    ][23768] Completed state [maas.deploy_machines] at time 05:41:06.833352 duration_in_ms=13461.583
2019-02-15 05:41:06,836 [salt.minion      :1711][INFO    ][23768] Returning information for job: 20190215054050522729
2019-02-15 05:41:07,376 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command state.apply with jid 20190215054107354760
2019-02-15 05:41:07,402 [salt.minion      :1432][INFO    ][24059] Starting a new job with PID 24059
2019-02-15 05:41:12,976 [salt.state       :915 ][INFO    ][24059] Loading fresh modules for state activity
2019-02-15 05:41:13,026 [salt.fileclient  :1219][INFO    ][24059] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-15 05:41:13,081 [salt.state       :1780][INFO    ][24059] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:41:13.081759
2019-02-15 05:41:13,082 [salt.state       :1813][INFO    ][24059] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-15 05:41:13,085 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24059] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-15 05:41:15,066 [salt.state       :300 ][INFO    ][24059] {'pid': 24079, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-15 05:41:15,067 [salt.state       :1951][INFO    ][24059] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:41:15.067385 duration_in_ms=1985.626
2019-02-15 05:41:15,070 [salt.state       :1780][INFO    ][24059] Running state [maas.wait_for_machine_status] at time 05:41:15.070291
2019-02-15 05:41:15,070 [salt.state       :1813][INFO    ][24059] Executing state module.run for [maas.wait_for_machine_status]
2019-02-15 05:41:15,071 [salt.utils.decorators:613 ][WARNING ][24059] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-15 05:41:17,499 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054117478669
2019-02-15 05:41:17,526 [salt.minion      :1432][INFO    ][24110] Starting a new job with PID 24110
2019-02-15 05:41:17,546 [salt.minion      :1711][INFO    ][24110] Returning information for job: 20190215054117478669
2019-02-15 05:41:18,393 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.68573618s left)
2019-02-15 05:41:27,707 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054127684566
2019-02-15 05:41:27,733 [salt.minion      :1432][INFO    ][24117] Starting a new job with PID 24117
2019-02-15 05:41:27,762 [salt.minion      :1711][INFO    ][24117] Returning information for job: 20190215054127684566
2019-02-15 05:41:37,895 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054137873834
2019-02-15 05:41:37,922 [salt.minion      :1432][INFO    ][24126] Starting a new job with PID 24126
2019-02-15 05:41:37,950 [salt.minion      :1711][INFO    ][24126] Returning information for job: 20190215054137873834
2019-02-15 05:41:48,084 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054148062346
2019-02-15 05:41:48,115 [salt.minion      :1432][INFO    ][24158] Starting a new job with PID 24158
2019-02-15 05:41:48,147 [salt.minion      :1711][INFO    ][24158] Returning information for job: 20190215054148062346
2019-02-15 05:41:51,810 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.26958704s left)
2019-02-15 05:41:58,303 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054158284144
2019-02-15 05:41:58,331 [salt.minion      :1432][INFO    ][24171] Starting a new job with PID 24171
2019-02-15 05:41:58,354 [salt.minion      :1711][INFO    ][24171] Returning information for job: 20190215054158284144
2019-02-15 05:42:08,524 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054208501430
2019-02-15 05:42:08,555 [salt.minion      :1432][INFO    ][24189] Starting a new job with PID 24189
2019-02-15 05:42:08,576 [salt.minion      :1711][INFO    ][24189] Returning information for job: 20190215054208501430
2019-02-15 05:42:18,561 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054218529108
2019-02-15 05:42:18,591 [salt.minion      :1432][INFO    ][24209] Starting a new job with PID 24209
2019-02-15 05:42:18,622 [salt.minion      :1711][INFO    ][24209] Returning information for job: 20190215054218529108
2019-02-15 05:42:25,234 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.84574318s left)
2019-02-15 05:42:28,575 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054228548861
2019-02-15 05:42:28,608 [salt.minion      :1432][INFO    ][24221] Starting a new job with PID 24221
2019-02-15 05:42:28,635 [salt.minion      :1711][INFO    ][24221] Returning information for job: 20190215054228548861
2019-02-15 05:42:38,787 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054238768799
2019-02-15 05:42:38,806 [salt.minion      :1432][INFO    ][24230] Starting a new job with PID 24230
2019-02-15 05:42:38,828 [salt.minion      :1711][INFO    ][24230] Returning information for job: 20190215054238768799
2019-02-15 05:42:48,994 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054248971924
2019-02-15 05:42:49,014 [salt.minion      :1432][INFO    ][24255] Starting a new job with PID 24255
2019-02-15 05:42:49,038 [salt.minion      :1711][INFO    ][24255] Returning information for job: 20190215054248971924
2019-02-15 05:42:58,520 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.55859613s left)
2019-02-15 05:42:59,207 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054259182480
2019-02-15 05:42:59,235 [salt.minion      :1432][INFO    ][24267] Starting a new job with PID 24267
2019-02-15 05:42:59,263 [salt.minion      :1711][INFO    ][24267] Returning information for job: 20190215054259182480
2019-02-15 05:43:09,233 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054309206064
2019-02-15 05:43:09,261 [salt.minion      :1432][INFO    ][24285] Starting a new job with PID 24285
2019-02-15 05:43:09,282 [salt.minion      :1711][INFO    ][24285] Returning information for job: 20190215054309206064
2019-02-15 05:43:19,418 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054319398788
2019-02-15 05:43:19,449 [salt.minion      :1432][INFO    ][24307] Starting a new job with PID 24307
2019-02-15 05:43:19,470 [salt.minion      :1711][INFO    ][24307] Returning information for job: 20190215054319398788
2019-02-15 05:43:29,609 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054329587942
2019-02-15 05:43:29,628 [salt.minion      :1432][INFO    ][24315] Starting a new job with PID 24315
2019-02-15 05:43:29,654 [salt.minion      :1711][INFO    ][24315] Returning information for job: 20190215054329587942
2019-02-15 05:43:31,983 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.09588099s left)
2019-02-15 05:43:39,814 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054339796269
2019-02-15 05:43:39,837 [salt.minion      :1432][INFO    ][24347] Starting a new job with PID 24347
2019-02-15 05:43:39,859 [salt.minion      :1711][INFO    ][24347] Returning information for job: 20190215054339796269
2019-02-15 05:43:50,014 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054349989441
2019-02-15 05:43:50,048 [salt.minion      :1432][INFO    ][24373] Starting a new job with PID 24373
2019-02-15 05:43:50,073 [salt.minion      :1711][INFO    ][24373] Returning information for job: 20190215054349989441
2019-02-15 05:44:00,035 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054400014585
2019-02-15 05:44:00,059 [salt.minion      :1432][INFO    ][24401] Starting a new job with PID 24401
2019-02-15 05:44:00,088 [salt.minion      :1711][INFO    ][24401] Returning information for job: 20190215054400014585
2019-02-15 05:44:04,892 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.18664217s left)
2019-02-15 05:44:10,245 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054410224321
2019-02-15 05:44:10,272 [salt.minion      :1432][INFO    ][24450] Starting a new job with PID 24450
2019-02-15 05:44:10,297 [salt.minion      :1711][INFO    ][24450] Returning information for job: 20190215054410224321
2019-02-15 05:44:20,279 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054420253946
2019-02-15 05:44:20,304 [salt.minion      :1432][INFO    ][24518] Starting a new job with PID 24518
2019-02-15 05:44:20,329 [salt.minion      :1711][INFO    ][24518] Returning information for job: 20190215054420253946
2019-02-15 05:44:30,489 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054430462007
2019-02-15 05:44:30,519 [salt.minion      :1432][INFO    ][24526] Starting a new job with PID 24526
2019-02-15 05:44:30,543 [salt.minion      :1711][INFO    ][24526] Returning information for job: 20190215054430462007
2019-02-15 05:44:38,148 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.93156719s left)
2019-02-15 05:44:40,697 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054440668529
2019-02-15 05:44:40,722 [salt.minion      :1432][INFO    ][24570] Starting a new job with PID 24570
2019-02-15 05:44:40,755 [salt.minion      :1711][INFO    ][24570] Returning information for job: 20190215054440668529
2019-02-15 05:44:50,905 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054450884845
2019-02-15 05:44:50,940 [salt.minion      :1432][INFO    ][24642] Starting a new job with PID 24642
2019-02-15 05:44:50,967 [salt.minion      :1711][INFO    ][24642] Returning information for job: 20190215054450884845
2019-02-15 05:45:01,114 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054501093404
2019-02-15 05:45:01,138 [salt.minion      :1432][INFO    ][24652] Starting a new job with PID 24652
2019-02-15 05:45:01,163 [salt.minion      :1711][INFO    ][24652] Returning information for job: 20190215054501093404
2019-02-15 05:45:11,136 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054511116870
2019-02-15 05:45:11,159 [salt.minion      :1432][INFO    ][24678] Starting a new job with PID 24678
2019-02-15 05:45:11,189 [salt.minion      :1711][INFO    ][24678] Returning information for job: 20190215054511116870
2019-02-15 05:45:11,552 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.52687502s left)
2019-02-15 05:45:21,149 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054521126494
2019-02-15 05:45:21,172 [salt.minion      :1432][INFO    ][24897] Starting a new job with PID 24897
2019-02-15 05:45:21,226 [salt.minion      :1711][INFO    ][24897] Returning information for job: 20190215054521126494
2019-02-15 05:45:31,170 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054531149624
2019-02-15 05:45:31,195 [salt.minion      :1432][INFO    ][24903] Starting a new job with PID 24903
2019-02-15 05:45:31,235 [salt.minion      :1711][INFO    ][24903] Returning information for job: 20190215054531149624
2019-02-15 05:45:41,195 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054541169500
2019-02-15 05:45:41,224 [salt.minion      :1432][INFO    ][24913] Starting a new job with PID 24913
2019-02-15 05:45:41,253 [salt.minion      :1711][INFO    ][24913] Returning information for job: 20190215054541169500
2019-02-15 05:45:44,919 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.1605432s left)
2019-02-15 05:45:51,234 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054551212923
2019-02-15 05:45:51,251 [salt.minion      :1432][INFO    ][25001] Starting a new job with PID 25001
2019-02-15 05:45:51,274 [salt.minion      :1711][INFO    ][25001] Returning information for job: 20190215054551212923
2019-02-15 05:46:01,445 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054601424614
2019-02-15 05:46:01,464 [salt.minion      :1432][INFO    ][25085] Starting a new job with PID 25085
2019-02-15 05:46:01,512 [salt.minion      :1711][INFO    ][25085] Returning information for job: 20190215054601424614
2019-02-15 05:46:11,465 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054611439414
2019-02-15 05:46:11,491 [salt.minion      :1432][INFO    ][25103] Starting a new job with PID 25103
2019-02-15 05:46:11,525 [salt.minion      :1711][INFO    ][25103] Returning information for job: 20190215054611439414
2019-02-15 05:46:18,362 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.7180841s left)
2019-02-15 05:46:21,487 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054621457353
2019-02-15 05:46:21,518 [salt.minion      :1432][INFO    ][25264] Starting a new job with PID 25264
2019-02-15 05:46:21,554 [salt.minion      :1711][INFO    ][25264] Returning information for job: 20190215054621457353
2019-02-15 05:46:31,514 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054631489877
2019-02-15 05:46:31,538 [salt.minion      :1432][INFO    ][25303] Starting a new job with PID 25303
2019-02-15 05:46:31,571 [salt.minion      :1711][INFO    ][25303] Returning information for job: 20190215054631489877
2019-02-15 05:46:41,543 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054641521047
2019-02-15 05:46:41,569 [salt.minion      :1432][INFO    ][25321] Starting a new job with PID 25321
2019-02-15 05:46:41,593 [salt.minion      :1711][INFO    ][25321] Returning information for job: 20190215054641521047
2019-02-15 05:46:51,585 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054651561963
2019-02-15 05:46:51,612 [salt.minion      :1432][INFO    ][25465] Starting a new job with PID 25465
2019-02-15 05:46:51,646 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.43266511s left)
2019-02-15 05:46:51,649 [salt.minion      :1711][INFO    ][25465] Returning information for job: 20190215054651561963
2019-02-15 05:47:01,638 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054701616544
2019-02-15 05:47:01,658 [salt.minion      :1432][INFO    ][25474] Starting a new job with PID 25474
2019-02-15 05:47:01,688 [salt.minion      :1711][INFO    ][25474] Returning information for job: 20190215054701616544
2019-02-15 05:47:11,677 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054711654572
2019-02-15 05:47:11,707 [salt.minion      :1432][INFO    ][25497] Starting a new job with PID 25497
2019-02-15 05:47:11,736 [salt.minion      :1711][INFO    ][25497] Returning information for job: 20190215054711654572
2019-02-15 05:47:21,736 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054721714319
2019-02-15 05:47:21,764 [salt.minion      :1432][INFO    ][25827] Starting a new job with PID 25827
2019-02-15 05:47:21,792 [salt.minion      :1711][INFO    ][25827] Returning information for job: 20190215054721714319
2019-02-15 05:47:25,072 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.00710511s left)
2019-02-15 05:47:31,820 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054731796969
2019-02-15 05:47:31,845 [salt.minion      :1432][INFO    ][25843] Starting a new job with PID 25843
2019-02-15 05:47:31,873 [salt.minion      :1711][INFO    ][25843] Returning information for job: 20190215054731796969
2019-02-15 05:47:41,917 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054741891924
2019-02-15 05:47:41,947 [salt.minion      :1432][INFO    ][25853] Starting a new job with PID 25853
2019-02-15 05:47:41,975 [salt.minion      :1711][INFO    ][25853] Returning information for job: 20190215054741891924
2019-02-15 05:47:52,016 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054751987964
2019-02-15 05:47:52,042 [salt.minion      :1432][INFO    ][25960] Starting a new job with PID 25960
2019-02-15 05:47:52,066 [salt.minion      :1711][INFO    ][25960] Returning information for job: 20190215054751987964
2019-02-15 05:47:58,352 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.72678804s left)
2019-02-15 05:48:02,097 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054802073059
2019-02-15 05:48:02,125 [salt.minion      :1432][INFO    ][25979] Starting a new job with PID 25979
2019-02-15 05:48:02,155 [salt.minion      :1711][INFO    ][25979] Returning information for job: 20190215054802073059
2019-02-15 05:48:12,178 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054812155823
2019-02-15 05:48:12,207 [salt.minion      :1432][INFO    ][26003] Starting a new job with PID 26003
2019-02-15 05:48:12,240 [salt.minion      :1711][INFO    ][26003] Returning information for job: 20190215054812155823
2019-02-15 05:48:22,287 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054822264312
2019-02-15 05:48:22,314 [salt.minion      :1432][INFO    ][26128] Starting a new job with PID 26128
2019-02-15 05:48:22,339 [salt.minion      :1711][INFO    ][26128] Returning information for job: 20190215054822264312
2019-02-15 05:48:31,661 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.41786718s left)
2019-02-15 05:48:32,384 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054832360529
2019-02-15 05:48:32,406 [salt.minion      :1432][INFO    ][26148] Starting a new job with PID 26148
2019-02-15 05:48:32,435 [salt.minion      :1711][INFO    ][26148] Returning information for job: 20190215054832360529
2019-02-15 05:48:42,508 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054842483877
2019-02-15 05:48:42,537 [salt.minion      :1432][INFO    ][26167] Starting a new job with PID 26167
2019-02-15 05:48:42,567 [salt.minion      :1711][INFO    ][26167] Returning information for job: 20190215054842483877
2019-02-15 05:48:52,553 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054852530052
2019-02-15 05:48:52,581 [salt.minion      :1432][INFO    ][26224] Starting a new job with PID 26224
2019-02-15 05:48:52,608 [salt.minion      :1711][INFO    ][26224] Returning information for job: 20190215054852530052
2019-02-15 05:49:02,589 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054902570696
2019-02-15 05:49:02,620 [salt.minion      :1432][INFO    ][26247] Starting a new job with PID 26247
2019-02-15 05:49:02,663 [salt.minion      :1711][INFO    ][26247] Returning information for job: 20190215054902570696
2019-02-15 05:49:05,081 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1779.9980042s left)
2019-02-15 05:49:12,696 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054912677284
2019-02-15 05:49:12,717 [salt.minion      :1432][INFO    ][26278] Starting a new job with PID 26278
2019-02-15 05:49:12,744 [salt.minion      :1711][INFO    ][26278] Returning information for job: 20190215054912677284
2019-02-15 05:49:22,831 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054922799885
2019-02-15 05:49:22,863 [salt.minion      :1432][INFO    ][26538] Starting a new job with PID 26538
2019-02-15 05:49:22,897 [salt.minion      :1711][INFO    ][26538] Returning information for job: 20190215054922799885
2019-02-15 05:49:32,983 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054932963001
2019-02-15 05:49:33,009 [salt.minion      :1432][INFO    ][26549] Starting a new job with PID 26549
2019-02-15 05:49:33,034 [salt.minion      :1711][INFO    ][26549] Returning information for job: 20190215054932963001
2019-02-15 05:49:38,393 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1746.68586612s left)
2019-02-15 05:49:43,116 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054943097042
2019-02-15 05:49:43,141 [salt.minion      :1432][INFO    ][26575] Starting a new job with PID 26575
2019-02-15 05:49:43,170 [salt.minion      :1711][INFO    ][26575] Returning information for job: 20190215054943097042
2019-02-15 05:49:53,237 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215054953217574
2019-02-15 05:49:53,264 [salt.minion      :1432][INFO    ][26645] Starting a new job with PID 26645
2019-02-15 05:49:53,291 [salt.minion      :1711][INFO    ][26645] Returning information for job: 20190215054953217574
2019-02-15 05:50:03,420 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055003395659
2019-02-15 05:50:03,445 [salt.minion      :1432][INFO    ][26657] Starting a new job with PID 26657
2019-02-15 05:50:03,471 [salt.minion      :1711][INFO    ][26657] Returning information for job: 20190215055003395659
2019-02-15 05:50:11,596 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1713.48311806s left)
2019-02-15 05:50:13,553 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055013532680
2019-02-15 05:50:13,576 [salt.minion      :1432][INFO    ][26696] Starting a new job with PID 26696
2019-02-15 05:50:13,604 [salt.minion      :1711][INFO    ][26696] Returning information for job: 20190215055013532680
2019-02-15 05:50:23,677 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055023653985
2019-02-15 05:50:23,719 [salt.minion      :1432][INFO    ][26758] Starting a new job with PID 26758
2019-02-15 05:50:23,751 [salt.minion      :1711][INFO    ][26758] Returning information for job: 20190215055023653985
2019-02-15 05:50:33,686 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055033666406
2019-02-15 05:50:33,716 [salt.minion      :1432][INFO    ][26771] Starting a new job with PID 26771
2019-02-15 05:50:33,739 [salt.minion      :1711][INFO    ][26771] Returning information for job: 20190215055033666406
2019-02-15 05:50:43,860 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055043842452
2019-02-15 05:50:43,880 [salt.minion      :1432][INFO    ][26800] Starting a new job with PID 26800
2019-02-15 05:50:43,907 [salt.minion      :1711][INFO    ][26800] Returning information for job: 20190215055043842452
2019-02-15 05:50:44,778 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1680.30094504s left)
2019-02-15 05:50:54,043 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055054019975
2019-02-15 05:50:54,069 [salt.minion      :1432][INFO    ][26827] Starting a new job with PID 26827
2019-02-15 05:50:54,092 [salt.minion      :1711][INFO    ][26827] Returning information for job: 20190215055054019975
2019-02-15 05:51:04,180 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055104157588
2019-02-15 05:51:04,204 [salt.minion      :1432][INFO    ][26836] Starting a new job with PID 26836
2019-02-15 05:51:04,232 [salt.minion      :1711][INFO    ][26836] Returning information for job: 20190215055104157588
2019-02-15 05:51:14,394 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055114371477
2019-02-15 05:51:14,421 [salt.minion      :1432][INFO    ][26929] Starting a new job with PID 26929
2019-02-15 05:51:14,454 [salt.minion      :1711][INFO    ][26929] Returning information for job: 20190215055114371477
2019-02-15 05:51:18,218 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1646.86139607s left)
2019-02-15 05:51:24,531 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055124504949
2019-02-15 05:51:24,563 [salt.minion      :1432][INFO    ][26978] Starting a new job with PID 26978
2019-02-15 05:51:24,592 [salt.minion      :1711][INFO    ][26978] Returning information for job: 20190215055124504949
2019-02-15 05:51:34,568 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055134543559
2019-02-15 05:51:34,595 [salt.minion      :1432][INFO    ][26983] Starting a new job with PID 26983
2019-02-15 05:51:34,627 [salt.minion      :1711][INFO    ][26983] Returning information for job: 20190215055134543559
2019-02-15 05:51:44,591 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055144566628
2019-02-15 05:51:44,616 [salt.minion      :1432][INFO    ][27035] Starting a new job with PID 27035
2019-02-15 05:51:44,638 [salt.minion      :1711][INFO    ][27035] Returning information for job: 20190215055144566628
2019-02-15 05:51:51,692 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1613.38721704s left)
2019-02-15 05:51:54,812 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055154787417
2019-02-15 05:51:54,842 [salt.minion      :1432][INFO    ][27050] Starting a new job with PID 27050
2019-02-15 05:51:54,875 [salt.minion      :1711][INFO    ][27050] Returning information for job: 20190215055154787417
2019-02-15 05:52:04,884 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055204860201
2019-02-15 05:52:04,907 [salt.minion      :1432][INFO    ][27056] Starting a new job with PID 27056
2019-02-15 05:52:04,929 [salt.minion      :1711][INFO    ][27056] Returning information for job: 20190215055204860201
2019-02-15 05:52:14,925 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055214904985
2019-02-15 05:52:14,949 [salt.minion      :1432][INFO    ][27089] Starting a new job with PID 27089
2019-02-15 05:52:14,975 [salt.minion      :1711][INFO    ][27089] Returning information for job: 20190215055214904985
2019-02-15 05:52:24,869 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1580.20986199s left)
2019-02-15 05:52:24,936 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055224917904
2019-02-15 05:52:24,957 [salt.minion      :1432][INFO    ][27102] Starting a new job with PID 27102
2019-02-15 05:52:24,986 [salt.minion      :1711][INFO    ][27102] Returning information for job: 20190215055224917904
2019-02-15 05:52:35,148 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055235123025
2019-02-15 05:52:35,177 [salt.minion      :1432][INFO    ][27108] Starting a new job with PID 27108
2019-02-15 05:52:35,204 [salt.minion      :1711][INFO    ][27108] Returning information for job: 20190215055235123025
2019-02-15 05:52:45,223 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055245204093
2019-02-15 05:52:45,253 [salt.minion      :1432][INFO    ][27140] Starting a new job with PID 27140
2019-02-15 05:52:45,280 [salt.minion      :1711][INFO    ][27140] Returning information for job: 20190215055245204093
2019-02-15 05:52:55,312 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055255291321
2019-02-15 05:52:55,330 [salt.minion      :1432][INFO    ][27147] Starting a new job with PID 27147
2019-02-15 05:52:55,358 [salt.minion      :1711][INFO    ][27147] Returning information for job: 20190215055255291321
2019-02-15 05:52:58,288 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1546.7914772s left)
2019-02-15 05:53:05,347 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055305330592
2019-02-15 05:53:05,371 [salt.minion      :1432][INFO    ][27157] Starting a new job with PID 27157
2019-02-15 05:53:05,392 [salt.minion      :1711][INFO    ][27157] Returning information for job: 20190215055305330592
2019-02-15 05:53:15,392 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055315370398
2019-02-15 05:53:15,416 [salt.minion      :1432][INFO    ][27362] Starting a new job with PID 27362
2019-02-15 05:53:15,445 [salt.minion      :1711][INFO    ][27362] Returning information for job: 20190215055315370398
2019-02-15 05:53:25,472 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055325447623
2019-02-15 05:53:25,500 [salt.minion      :1432][INFO    ][27395] Starting a new job with PID 27395
2019-02-15 05:53:25,523 [salt.minion      :1711][INFO    ][27395] Returning information for job: 20190215055325447623
2019-02-15 05:53:31,600 [salt.loaded.ext.module.maas:1023][INFO    ][24059] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1513.47923303s left)
2019-02-15 05:53:35,556 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055335532275
2019-02-15 05:53:35,579 [salt.minion      :1432][INFO    ][27436] Starting a new job with PID 27436
2019-02-15 05:53:35,604 [salt.minion      :1711][INFO    ][27436] Returning information for job: 20190215055335532275
2019-02-15 05:53:45,581 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055345554899
2019-02-15 05:53:45,608 [salt.minion      :1432][INFO    ][27551] Starting a new job with PID 27551
2019-02-15 05:53:45,641 [salt.minion      :1711][INFO    ][27551] Returning information for job: 20190215055345554899
2019-02-15 05:53:55,703 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command saltutil.find_job with jid 20190215055355677535
2019-02-15 05:53:55,734 [salt.minion      :1432][INFO    ][27577] Starting a new job with PID 27577
2019-02-15 05:53:55,766 [salt.minion      :1711][INFO    ][27577] Returning information for job: 20190215055355677535
2019-02-15 05:54:04,902 [salt.state       :300 ][INFO    ][24059] {'ret': True}
2019-02-15 05:54:04,903 [salt.state       :1951][INFO    ][24059] Completed state [maas.wait_for_machine_status] at time 05:54:04.903235 duration_in_ms=769832.943
2019-02-15 05:54:04,910 [salt.minion      :1711][INFO    ][24059] Returning information for job: 20190215054107354760
2019-02-15 06:13:50,851 [salt.utils.schedule:1377][INFO    ][6679] Running scheduled job: __mine_interval
2019-02-15 07:08:25,912 [salt.minion      :1308][INFO    ][6679] User sudo_ubuntu Executing command cp.push_dir with jid 20190215070825900012
2019-02-15 07:08:26,271 [salt.minion      :1432][INFO    ][2073] Starting a new job with PID 2073
