2019-02-01 09:42:58,294 [salt.utils       :831 ][ERROR   ][1509] DNS lookup or connection check of 'salt' failed.
2019-02-01 09:42:58,295 [salt.minion      :152 ][ERROR   ][1509] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-02-01 09:43:02,266 [salt.utils.parsers:1051][WARNING ][1509] Minion received a SIGTERM. Exiting.
2019-02-01 09:44:24,296 [salt.loaded.int.states.file:2298][WARNING ][1868] 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-01 09:44:34,991 [salt.utils.decorators:613 ][WARNING ][1868] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 09:44:35,108 [salt.loaded.int.states.file:2298][WARNING ][1868] 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-01 09:44:35,110 [salt.loaded.int.states.file:2298][WARNING ][1868] 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-01 09:44:35,111 [salt.loaded.int.states.file:2298][WARNING ][1868] 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-01 09:44:35,112 [salt.loaded.int.states.file:2298][WARNING ][1868] 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-01 09:44:35,116 [salt.loaded.int.states.file:2298][WARNING ][1868] 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-01 09:44:35,117 [salt.loaded.int.states.file:2298][WARNING ][1868] 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-01 09:44:35,118 [salt.loaded.int.states.file:2298][WARNING ][1868] 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-01 09:44:35,120 [salt.loaded.int.states.file:2298][WARNING ][1868] 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-01 09:44:35,318 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1868] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-01 09:44:35,318 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1868] 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-01 09:44:35,319 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1868] retcode: 32
2019-02-01 09:44:48,453 [salt.utils.parsers:1051][WARNING ][1713] Minion received a SIGTERM. Exiting.
2019-02-01 09:45:40,654 [salt.utils.decorators:613 ][WARNING ][1965] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 09:46:14,555 [salt.loaded.int.states.file:2298][WARNING ][4657] 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-01 09:46:17,628 [salt.loaded.int.states.file:2298][WARNING ][4657] 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-01 09:46:17,629 [salt.loaded.int.states.file:2298][WARNING ][4657] 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-01 09:46:17,631 [salt.loaded.int.states.file:2298][WARNING ][4657] 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-01 09:46:17,632 [salt.loaded.int.states.file:2298][WARNING ][4657] 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-01 09:46:17,635 [salt.loaded.int.states.file:2298][WARNING ][4657] 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-01 09:46:17,636 [salt.loaded.int.states.file:2298][WARNING ][4657] 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-01 09:46:17,637 [salt.loaded.int.states.file:2298][WARNING ][4657] 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-01 09:46:17,638 [salt.loaded.int.states.file:2298][WARNING ][4657] 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-01 09:46:17,790 [salt.utils.decorators:613 ][WARNING ][4657] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 09:46:18,158 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4657] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-01 09:46:18,159 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4657] 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-01 09:46:18,159 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4657] retcode: 32
2019-02-01 09:46:25,519 [salt.utils.decorators:613 ][WARNING ][4657] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 09:46:25,556 [salt.utils.decorators:613 ][WARNING ][4657] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 09:47:26,419 [salt.loaded.int.states.file:2298][WARNING ][6589] 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-01 09:47:29,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12895] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-01 09:47:29,257 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12895] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-01 09:47:29,308 [salt.utils.parsers:1051][WARNING ][1706] Minion received a SIGTERM. Exiting.
2019-02-01 09:47:30,227 [salt.cli.daemons :293 ][INFO    ][12955] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-02-01 09:47:30,359 [salt.cli.daemons :82  ][INFO    ][12955] Starting up the Salt Minion
2019-02-01 09:47:30,360 [salt.utils.event :1017][INFO    ][12955] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-02-01 09:47:30,960 [salt.minion      :976 ][INFO    ][12955] Creating minion process manager
2019-02-01 09:47:32,260 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12955] Executing command ['date', '+%z'] in directory '/root'
2019-02-01 09:47:32,281 [salt.utils.schedule:568 ][INFO    ][12955] Updating job settings for scheduled job: __mine_interval
2019-02-01 09:47:32,283 [salt.minion      :1108][INFO    ][12955] Added mine.update to scheduler
2019-02-01 09:47:32,289 [salt.minion      :1975][INFO    ][12955] Minion is starting as user 'root'
2019-02-01 09:47:32,301 [salt.minion      :2336][INFO    ][12955] Minion is ready to receive requests!
2019-02-01 09:47:37,906 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094737885279
2019-02-01 09:47:37,928 [salt.minion      :1432][INFO    ][13300] Starting a new job with PID 13300
2019-02-01 09:47:37,952 [salt.minion      :1711][INFO    ][13300] Returning information for job: 20190201094737885279
2019-02-01 09:47:48,032 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094748013248
2019-02-01 09:47:48,054 [salt.minion      :1432][INFO    ][13492] Starting a new job with PID 13492
2019-02-01 09:47:48,089 [salt.minion      :1711][INFO    ][13492] Returning information for job: 20190201094748013248
2019-02-01 09:47:58,168 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094758148882
2019-02-01 09:47:58,201 [salt.minion      :1432][INFO    ][15041] Starting a new job with PID 15041
2019-02-01 09:47:58,235 [salt.minion      :1711][INFO    ][15041] Returning information for job: 20190201094758148882
2019-02-01 09:48:08,294 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094808274633
2019-02-01 09:48:08,326 [salt.minion      :1432][INFO    ][16746] Starting a new job with PID 16746
2019-02-01 09:48:08,352 [salt.minion      :1711][INFO    ][16746] Returning information for job: 20190201094808274633
2019-02-01 09:48:18,426 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094818409608
2019-02-01 09:48:18,455 [salt.minion      :1432][INFO    ][16755] Starting a new job with PID 16755
2019-02-01 09:48:18,479 [salt.minion      :1711][INFO    ][16755] Returning information for job: 20190201094818409608
2019-02-01 09:48:28,552 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094828537615
2019-02-01 09:48:28,571 [salt.minion      :1432][INFO    ][16770] Starting a new job with PID 16770
2019-02-01 09:48:28,593 [salt.minion      :1711][INFO    ][16770] Returning information for job: 20190201094828537615
2019-02-01 09:48:38,687 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094838673740
2019-02-01 09:48:38,707 [salt.minion      :1432][INFO    ][16916] Starting a new job with PID 16916
2019-02-01 09:48:38,731 [salt.minion      :1711][INFO    ][16916] Returning information for job: 20190201094838673740
2019-02-01 09:48:48,818 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094848802586
2019-02-01 09:48:48,846 [salt.minion      :1432][INFO    ][17677] Starting a new job with PID 17677
2019-02-01 09:48:48,879 [salt.minion      :1711][INFO    ][17677] Returning information for job: 20190201094848802586
2019-02-01 09:48:58,955 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094858940171
2019-02-01 09:48:58,975 [salt.minion      :1432][INFO    ][18238] Starting a new job with PID 18238
2019-02-01 09:48:59,005 [salt.minion      :1711][INFO    ][18238] Returning information for job: 20190201094858940171
2019-02-01 09:49:09,088 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094909071507
2019-02-01 09:49:09,121 [salt.minion      :1432][INFO    ][18486] Starting a new job with PID 18486
2019-02-01 09:49:09,143 [salt.minion      :1711][INFO    ][18486] Returning information for job: 20190201094909071507
2019-02-01 09:49:19,218 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094919201337
2019-02-01 09:49:19,272 [salt.minion      :1432][INFO    ][18951] Starting a new job with PID 18951
2019-02-01 09:49:19,312 [salt.minion      :1711][INFO    ][18951] Returning information for job: 20190201094919201337
2019-02-01 09:49:29,339 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094929324477
2019-02-01 09:49:29,371 [salt.minion      :1432][INFO    ][18962] Starting a new job with PID 18962
2019-02-01 09:49:29,424 [salt.minion      :1711][INFO    ][18962] Returning information for job: 20190201094929324477
2019-02-01 09:49:39,492 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094939477746
2019-02-01 09:49:39,537 [salt.minion      :1432][INFO    ][18967] Starting a new job with PID 18967
2019-02-01 09:49:39,585 [salt.minion      :1711][INFO    ][18967] Returning information for job: 20190201094939477746
2019-02-01 09:49:49,521 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094949507766
2019-02-01 09:49:49,571 [salt.minion      :1432][INFO    ][18972] Starting a new job with PID 18972
2019-02-01 09:49:49,604 [salt.minion      :1711][INFO    ][18972] Returning information for job: 20190201094949507766
2019-02-01 09:49:59,546 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201094959533361
2019-02-01 09:49:59,587 [salt.minion      :1432][INFO    ][18978] Starting a new job with PID 18978
2019-02-01 09:49:59,618 [salt.minion      :1711][INFO    ][18978] Returning information for job: 20190201094959533361
2019-02-01 09:50:09,576 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095009560724
2019-02-01 09:50:09,638 [salt.minion      :1432][INFO    ][18984] Starting a new job with PID 18984
2019-02-01 09:50:09,676 [salt.minion      :1711][INFO    ][18984] Returning information for job: 20190201095009560724
2019-02-01 09:50:19,595 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095019583186
2019-02-01 09:50:19,632 [salt.minion      :1432][INFO    ][18995] Starting a new job with PID 18995
2019-02-01 09:50:19,683 [salt.minion      :1711][INFO    ][18995] Returning information for job: 20190201095019583186
2019-02-01 09:50:29,613 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095029601440
2019-02-01 09:50:29,638 [salt.minion      :1432][INFO    ][19001] Starting a new job with PID 19001
2019-02-01 09:50:29,669 [salt.minion      :1711][INFO    ][19001] Returning information for job: 20190201095029601440
2019-02-01 09:50:39,745 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095039732860
2019-02-01 09:50:39,779 [salt.minion      :1432][INFO    ][19229] Starting a new job with PID 19229
2019-02-01 09:50:39,824 [salt.minion      :1711][INFO    ][19229] Returning information for job: 20190201095039732860
2019-02-01 09:50:49,784 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095049771623
2019-02-01 09:50:49,812 [salt.minion      :1432][INFO    ][19647] Starting a new job with PID 19647
2019-02-01 09:50:49,839 [salt.minion      :1711][INFO    ][19647] Returning information for job: 20190201095049771623
2019-02-01 09:50:56,087 [salt.state       :2022][WARNING ][12899] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-01 09:50:58,736 [salt.utils.decorators:613 ][WARNING ][12899] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 09:50:59,794 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095059782246
2019-02-01 09:50:59,843 [salt.minion      :1432][INFO    ][20218] Starting a new job with PID 20218
2019-02-01 09:50:59,893 [salt.minion      :1711][INFO    ][20218] Returning information for job: 20190201095059782246
2019-02-01 09:51:09,817 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095109804987
2019-02-01 09:51:09,842 [salt.minion      :1432][INFO    ][20381] Starting a new job with PID 20381
2019-02-01 09:51:09,870 [salt.minion      :1711][INFO    ][20381] Returning information for job: 20190201095109804987
2019-02-01 09:51:19,957 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095119935871
2019-02-01 09:51:19,991 [salt.minion      :1432][INFO    ][20460] Starting a new job with PID 20460
2019-02-01 09:51:20,027 [salt.minion      :1711][INFO    ][20460] Returning information for job: 20190201095119935871
2019-02-01 09:51:29,993 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095129978251
2019-02-01 09:51:30,017 [salt.minion      :1432][INFO    ][20535] Starting a new job with PID 20535
2019-02-01 09:51:30,042 [salt.minion      :1711][INFO    ][20535] Returning information for job: 20190201095129978251
2019-02-01 09:51:40,139 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095140117287
2019-02-01 09:51:40,174 [salt.minion      :1432][INFO    ][20597] Starting a new job with PID 20597
2019-02-01 09:51:40,210 [salt.minion      :1711][INFO    ][20597] Returning information for job: 20190201095140117287
2019-02-01 09:51:50,170 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095150151345
2019-02-01 09:51:50,189 [salt.minion      :1432][INFO    ][20652] Starting a new job with PID 20652
2019-02-01 09:51:50,215 [salt.minion      :1711][INFO    ][20652] Returning information for job: 20190201095150151345
2019-02-01 09:52:00,274 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095200260979
2019-02-01 09:52:00,293 [salt.minion      :1432][INFO    ][20722] Starting a new job with PID 20722
2019-02-01 09:52:00,322 [salt.minion      :1711][INFO    ][20722] Returning information for job: 20190201095200260979
2019-02-01 09:52:10,431 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095210412553
2019-02-01 09:52:10,457 [salt.minion      :1432][INFO    ][20763] Starting a new job with PID 20763
2019-02-01 09:52:10,490 [salt.minion      :1711][INFO    ][20763] Returning information for job: 20190201095210412553
2019-02-01 09:52:20,567 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095220552663
2019-02-01 09:52:20,592 [salt.minion      :1432][INFO    ][20802] Starting a new job with PID 20802
2019-02-01 09:52:20,624 [salt.minion      :1711][INFO    ][20802] Returning information for job: 20190201095220552663
2019-02-01 09:52:27,338 [salt.utils.decorators:613 ][WARNING ][12899] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 09:52:30,698 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095230684036
2019-02-01 09:52:30,719 [salt.minion      :1432][INFO    ][21029] Starting a new job with PID 21029
2019-02-01 09:52:30,742 [salt.minion      :1711][INFO    ][21029] Returning information for job: 20190201095230684036
2019-02-01 09:52:40,839 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095240814576
2019-02-01 09:52:40,874 [salt.minion      :1432][INFO    ][21104] Starting a new job with PID 21104
2019-02-01 09:52:40,910 [salt.minion      :1711][INFO    ][21104] Returning information for job: 20190201095240814576
2019-02-01 09:52:50,988 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095250966858
2019-02-01 09:52:51,013 [salt.minion      :1432][INFO    ][21129] Starting a new job with PID 21129
2019-02-01 09:52:51,039 [salt.minion      :1711][INFO    ][21129] Returning information for job: 20190201095250966858
2019-02-01 09:53:01,122 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095301101938
2019-02-01 09:53:01,147 [salt.minion      :1432][INFO    ][21192] Starting a new job with PID 21192
2019-02-01 09:53:01,176 [salt.minion      :1711][INFO    ][21192] Returning information for job: 20190201095301101938
2019-02-01 09:53:11,245 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095311228105
2019-02-01 09:53:11,280 [salt.minion      :1432][INFO    ][21201] Starting a new job with PID 21201
2019-02-01 09:53:11,310 [salt.minion      :1711][INFO    ][21201] Returning information for job: 20190201095311228105
2019-02-01 09:53:21,354 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095321338788
2019-02-01 09:53:21,375 [salt.minion      :1432][INFO    ][21215] Starting a new job with PID 21215
2019-02-01 09:53:21,406 [salt.minion      :1711][INFO    ][21215] Returning information for job: 20190201095321338788
2019-02-01 09:53:31,533 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095331514492
2019-02-01 09:53:31,558 [salt.minion      :1432][INFO    ][21240] Starting a new job with PID 21240
2019-02-01 09:53:31,584 [salt.minion      :1711][INFO    ][21240] Returning information for job: 20190201095331514492
2019-02-01 09:53:41,754 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095341732208
2019-02-01 09:53:41,778 [salt.minion      :1432][INFO    ][21249] Starting a new job with PID 21249
2019-02-01 09:53:41,803 [salt.minion      :1711][INFO    ][21249] Returning information for job: 20190201095341732208
2019-02-01 09:53:51,913 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095351894490
2019-02-01 09:53:51,939 [salt.minion      :1432][INFO    ][21273] Starting a new job with PID 21273
2019-02-01 09:53:51,964 [salt.minion      :1711][INFO    ][21273] Returning information for job: 20190201095351894490
2019-02-01 09:54:01,944 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095401920864
2019-02-01 09:54:01,972 [salt.minion      :1432][INFO    ][21295] Starting a new job with PID 21295
2019-02-01 09:54:01,997 [salt.minion      :1711][INFO    ][21295] Returning information for job: 20190201095401920864
2019-02-01 09:54:12,157 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095412140288
2019-02-01 09:54:12,173 [salt.minion      :1432][INFO    ][21306] Starting a new job with PID 21306
2019-02-01 09:54:12,197 [salt.minion      :1711][INFO    ][21306] Returning information for job: 20190201095412140288
2019-02-01 09:54:22,349 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095422325949
2019-02-01 09:54:22,374 [salt.minion      :1432][INFO    ][21317] Starting a new job with PID 21317
2019-02-01 09:54:22,398 [salt.minion      :1711][INFO    ][21317] Returning information for job: 20190201095422325949
2019-02-01 09:54:32,368 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095432349129
2019-02-01 09:54:32,400 [salt.minion      :1432][INFO    ][21347] Starting a new job with PID 21347
2019-02-01 09:54:32,428 [salt.minion      :1711][INFO    ][21347] Returning information for job: 20190201095432349129
2019-02-01 09:54:42,405 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095442384001
2019-02-01 09:54:42,429 [salt.minion      :1432][INFO    ][21356] Starting a new job with PID 21356
2019-02-01 09:54:42,458 [salt.minion      :1711][INFO    ][21356] Returning information for job: 20190201095442384001
2019-02-01 09:54:52,442 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095452417950
2019-02-01 09:54:52,471 [salt.minion      :1432][INFO    ][21377] Starting a new job with PID 21377
2019-02-01 09:54:52,500 [salt.minion      :1711][INFO    ][21377] Returning information for job: 20190201095452417950
2019-02-01 09:55:02,476 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095502450629
2019-02-01 09:55:02,510 [salt.minion      :1432][INFO    ][21409] Starting a new job with PID 21409
2019-02-01 09:55:02,543 [salt.minion      :1711][INFO    ][21409] Returning information for job: 20190201095502450629
2019-02-01 09:55:12,497 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095512474641
2019-02-01 09:55:12,525 [salt.minion      :1432][INFO    ][21420] Starting a new job with PID 21420
2019-02-01 09:55:12,549 [salt.minion      :1711][INFO    ][21420] Returning information for job: 20190201095512474641
2019-02-01 09:55:22,720 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095522700371
2019-02-01 09:55:22,749 [salt.minion      :1432][INFO    ][21431] Starting a new job with PID 21431
2019-02-01 09:55:22,775 [salt.minion      :1711][INFO    ][21431] Returning information for job: 20190201095522700371
2019-02-01 09:55:32,768 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095532740691
2019-02-01 09:55:32,803 [salt.minion      :1432][INFO    ][21460] Starting a new job with PID 21460
2019-02-01 09:55:32,837 [salt.minion      :1711][INFO    ][21460] Returning information for job: 20190201095532740691
2019-02-01 09:55:42,902 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095542883542
2019-02-01 09:55:42,926 [salt.minion      :1432][INFO    ][21476] Starting a new job with PID 21476
2019-02-01 09:55:42,952 [salt.minion      :1711][INFO    ][21476] Returning information for job: 20190201095542883542
2019-02-01 09:55:52,950 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095552921579
2019-02-01 09:55:52,977 [salt.minion      :1432][INFO    ][21498] Starting a new job with PID 21498
2019-02-01 09:55:53,003 [salt.minion      :1711][INFO    ][21498] Returning information for job: 20190201095552921579
2019-02-01 09:56:03,089 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095603065238
2019-02-01 09:56:03,120 [salt.minion      :1432][INFO    ][21521] Starting a new job with PID 21521
2019-02-01 09:56:03,146 [salt.minion      :1711][INFO    ][21521] Returning information for job: 20190201095603065238
2019-02-01 09:56:13,216 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095613187472
2019-02-01 09:56:13,245 [salt.minion      :1432][INFO    ][21532] Starting a new job with PID 21532
2019-02-01 09:56:13,274 [salt.minion      :1711][INFO    ][21532] Returning information for job: 20190201095613187472
2019-02-01 09:56:23,308 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095623289702
2019-02-01 09:56:23,333 [salt.minion      :1432][INFO    ][21543] Starting a new job with PID 21543
2019-02-01 09:56:23,357 [salt.minion      :1711][INFO    ][21543] Returning information for job: 20190201095623289702
2019-02-01 09:56:33,391 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095633370264
2019-02-01 09:56:33,422 [salt.minion      :1432][INFO    ][21571] Starting a new job with PID 21571
2019-02-01 09:56:33,452 [salt.minion      :1711][INFO    ][21571] Returning information for job: 20190201095633370264
2019-02-01 09:56:43,541 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095643517238
2019-02-01 09:56:43,568 [salt.minion      :1432][INFO    ][21580] Starting a new job with PID 21580
2019-02-01 09:56:43,597 [salt.minion      :1711][INFO    ][21580] Returning information for job: 20190201095643517238
2019-02-01 09:56:53,685 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095653657271
2019-02-01 09:56:53,713 [salt.minion      :1432][INFO    ][21601] Starting a new job with PID 21601
2019-02-01 09:56:53,742 [salt.minion      :1711][INFO    ][21601] Returning information for job: 20190201095653657271
2019-02-01 09:57:03,810 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095703789390
2019-02-01 09:57:03,831 [salt.minion      :1432][INFO    ][21625] Starting a new job with PID 21625
2019-02-01 09:57:03,855 [salt.minion      :1711][INFO    ][21625] Returning information for job: 20190201095703789390
2019-02-01 09:57:13,972 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095713945325
2019-02-01 09:57:13,999 [salt.minion      :1432][INFO    ][21635] Starting a new job with PID 21635
2019-02-01 09:57:14,026 [salt.minion      :1711][INFO    ][21635] Returning information for job: 20190201095713945325
2019-02-01 09:57:24,109 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095724090584
2019-02-01 09:57:24,140 [salt.minion      :1432][INFO    ][21648] Starting a new job with PID 21648
2019-02-01 09:57:24,170 [salt.minion      :1711][INFO    ][21648] Returning information for job: 20190201095724090584
2019-02-01 09:57:34,216 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095734195642
2019-02-01 09:57:34,236 [salt.minion      :1432][INFO    ][21677] Starting a new job with PID 21677
2019-02-01 09:57:34,263 [salt.minion      :1711][INFO    ][21677] Returning information for job: 20190201095734195642
2019-02-01 09:57:44,394 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095744364280
2019-02-01 09:57:44,421 [salt.minion      :1432][INFO    ][21690] Starting a new job with PID 21690
2019-02-01 09:57:44,448 [salt.minion      :1711][INFO    ][21690] Returning information for job: 20190201095744364280
2019-02-01 09:57:54,417 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095754392185
2019-02-01 09:57:54,443 [salt.minion      :1432][INFO    ][21711] Starting a new job with PID 21711
2019-02-01 09:57:54,465 [salt.minion      :1711][INFO    ][21711] Returning information for job: 20190201095754392185
2019-02-01 09:58:04,545 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095804524983
2019-02-01 09:58:04,567 [salt.minion      :1432][INFO    ][21735] Starting a new job with PID 21735
2019-02-01 09:58:04,591 [salt.minion      :1711][INFO    ][21735] Returning information for job: 20190201095804524983
2019-02-01 09:58:14,721 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095814694686
2019-02-01 09:58:14,746 [salt.minion      :1432][INFO    ][21745] Starting a new job with PID 21745
2019-02-01 09:58:14,772 [salt.minion      :1711][INFO    ][21745] Returning information for job: 20190201095814694686
2019-02-01 09:58:24,734 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095824714294
2019-02-01 09:58:24,758 [salt.minion      :1432][INFO    ][21756] Starting a new job with PID 21756
2019-02-01 09:58:24,782 [salt.minion      :1711][INFO    ][21756] Returning information for job: 20190201095824714294
2019-02-01 09:58:34,812 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095834789332
2019-02-01 09:58:34,839 [salt.minion      :1432][INFO    ][21785] Starting a new job with PID 21785
2019-02-01 09:58:34,869 [salt.minion      :1711][INFO    ][21785] Returning information for job: 20190201095834789332
2019-02-01 09:58:45,027 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095844998730
2019-02-01 09:58:45,062 [salt.minion      :1432][INFO    ][21793] Starting a new job with PID 21793
2019-02-01 09:58:45,094 [salt.minion      :1711][INFO    ][21793] Returning information for job: 20190201095844998730
2019-02-01 09:58:55,056 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095855033749
2019-02-01 09:58:55,083 [salt.minion      :1432][INFO    ][21814] Starting a new job with PID 21814
2019-02-01 09:58:55,106 [salt.minion      :1711][INFO    ][21814] Returning information for job: 20190201095855033749
2019-02-01 09:59:05,160 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095905139560
2019-02-01 09:59:05,190 [salt.minion      :1432][INFO    ][21839] Starting a new job with PID 21839
2019-02-01 09:59:05,214 [salt.minion      :1711][INFO    ][21839] Returning information for job: 20190201095905139560
2019-02-01 09:59:15,289 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095915259976
2019-02-01 09:59:15,322 [salt.minion      :1432][INFO    ][21849] Starting a new job with PID 21849
2019-02-01 09:59:15,349 [salt.minion      :1711][INFO    ][21849] Returning information for job: 20190201095915259976
2019-02-01 09:59:25,443 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095925421467
2019-02-01 09:59:25,476 [salt.minion      :1432][INFO    ][21861] Starting a new job with PID 21861
2019-02-01 09:59:25,499 [salt.minion      :1711][INFO    ][21861] Returning information for job: 20190201095925421467
2019-02-01 09:59:35,602 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095935579548
2019-02-01 09:59:35,624 [salt.minion      :1432][INFO    ][21889] Starting a new job with PID 21889
2019-02-01 09:59:35,645 [salt.minion      :1711][INFO    ][21889] Returning information for job: 20190201095935579548
2019-02-01 09:59:45,692 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095945665043
2019-02-01 09:59:45,721 [salt.minion      :1432][INFO    ][21897] Starting a new job with PID 21897
2019-02-01 09:59:45,751 [salt.minion      :1711][INFO    ][21897] Returning information for job: 20190201095945665043
2019-02-01 09:59:55,897 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201095955867745
2019-02-01 09:59:55,926 [salt.minion      :1432][INFO    ][21919] Starting a new job with PID 21919
2019-02-01 09:59:55,963 [salt.minion      :1711][INFO    ][21919] Returning information for job: 20190201095955867745
2019-02-01 10:00:05,936 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100005905434
2019-02-01 10:00:05,970 [salt.minion      :1432][INFO    ][21961] Starting a new job with PID 21961
2019-02-01 10:00:05,995 [salt.minion      :1711][INFO    ][21961] Returning information for job: 20190201100005905434
2019-02-01 10:00:15,960 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100015931599
2019-02-01 10:00:15,988 [salt.minion      :1432][INFO    ][21970] Starting a new job with PID 21970
2019-02-01 10:00:16,008 [salt.minion      :1711][INFO    ][21970] Returning information for job: 20190201100015931599
2019-02-01 10:00:25,992 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100025965521
2019-02-01 10:00:26,018 [salt.minion      :1432][INFO    ][21982] Starting a new job with PID 21982
2019-02-01 10:00:26,041 [salt.minion      :1711][INFO    ][21982] Returning information for job: 20190201100025965521
2019-02-01 10:00:36,139 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100036115212
2019-02-01 10:00:36,167 [salt.minion      :1432][INFO    ][22010] Starting a new job with PID 22010
2019-02-01 10:00:36,194 [salt.minion      :1711][INFO    ][22010] Returning information for job: 20190201100036115212
2019-02-01 10:00:46,359 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100046334409
2019-02-01 10:00:46,383 [salt.minion      :1432][INFO    ][22018] Starting a new job with PID 22018
2019-02-01 10:00:46,411 [salt.minion      :1711][INFO    ][22018] Returning information for job: 20190201100046334409
2019-02-01 10:00:56,541 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100056509918
2019-02-01 10:00:56,571 [salt.minion      :1432][INFO    ][22046] Starting a new job with PID 22046
2019-02-01 10:00:56,596 [salt.minion      :1711][INFO    ][22046] Returning information for job: 20190201100056509918
2019-02-01 10:01:06,650 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100106623120
2019-02-01 10:01:06,674 [salt.minion      :1432][INFO    ][22088] Starting a new job with PID 22088
2019-02-01 10:01:06,696 [salt.minion      :1711][INFO    ][22088] Returning information for job: 20190201100106623120
2019-02-01 10:01:16,819 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100116793845
2019-02-01 10:01:16,841 [salt.minion      :1432][INFO    ][22117] Starting a new job with PID 22117
2019-02-01 10:01:16,866 [salt.minion      :1711][INFO    ][22117] Returning information for job: 20190201100116793845
2019-02-01 10:01:19,099 [salt.utils.decorators:613 ][WARNING ][12899] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 10:01:26,859 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100126833181
2019-02-01 10:01:26,962 [salt.minion      :1432][INFO    ][22138] Starting a new job with PID 22138
2019-02-01 10:01:27,033 [salt.minion      :1711][INFO    ][22138] Returning information for job: 20190201100126833181
2019-02-01 10:01:36,893 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100136867751
2019-02-01 10:01:36,976 [salt.minion      :1432][INFO    ][22178] Starting a new job with PID 22178
2019-02-01 10:01:37,028 [salt.minion      :1711][INFO    ][22178] Returning information for job: 20190201100136867751
2019-02-01 10:01:46,845 [salt.utils.decorators:613 ][WARNING ][12899] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 10:01:47,101 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100147076533
2019-02-01 10:01:47,185 [salt.minion      :1432][INFO    ][22327] Starting a new job with PID 22327
2019-02-01 10:01:47,237 [salt.minion      :1711][INFO    ][22327] Returning information for job: 20190201100147076533
2019-02-01 10:01:49,821 [salt.loaded.ext.module.maasng:1008][WARNING ][12899] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-02-01 10:01:49,822 [salt.loaded.ext.module.maasng:1011][WARNING ][12899] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-02-01 10:01:50,456 [salt.loaded.ext.module.maasng:1235][WARNING ][12899] Ignoring parameter vlan:0
2019-02-01 10:01:51,352 [salt.utils.decorators:613 ][WARNING ][12899] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 10:01:52,326 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command state.apply with jid 20190201100152300991
2019-02-01 10:01:52,349 [salt.minion      :1432][INFO    ][22419] Starting a new job with PID 22419
2019-02-01 10:02:00,558 [salt.state       :915 ][INFO    ][22419] Loading fresh modules for state activity
2019-02-01 10:02:00,629 [salt.fileclient  :1219][INFO    ][22419] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-02-01 10:02:00,680 [salt.state       :1780][INFO    ][22419] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:02:00.680819
2019-02-01 10:02:00,681 [salt.state       :1813][INFO    ][22419] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-01 10:02:00,684 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22419] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-01 10:02:02,434 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100202407858
2019-02-01 10:02:02,458 [salt.minion      :1432][INFO    ][22488] Starting a new job with PID 22488
2019-02-01 10:02:02,485 [salt.minion      :1711][INFO    ][22488] Returning information for job: 20190201100202407858
2019-02-01 10:02:02,613 [salt.state       :300 ][INFO    ][22419] {'pid': 22479, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-01 10:02:02,614 [salt.state       :1951][INFO    ][22419] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:02:02.614551 duration_in_ms=1933.736
2019-02-01 10:02:02,617 [salt.state       :1780][INFO    ][22419] Running state [maas.process_machines] at time 10:02:02.617720
2019-02-01 10:02:02,618 [salt.state       :1813][INFO    ][22419] Executing state module.run for [maas.process_machines]
2019-02-01 10:02:02,619 [salt.utils.decorators:613 ][WARNING ][22419] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 10:02:02,686 [salt.loaded.ext.module.maas:412 ][WARNING ][22419] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-01 10:02:02,687 [salt.loaded.ext.module.maas:92  ][INFO    ][22419] 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-01 10:02:04,309 [salt.loaded.ext.module.maas:412 ][WARNING ][22419] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-01 10:02:04,310 [salt.loaded.ext.module.maas:92  ][INFO    ][22419] 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-01 10:02:05,652 [salt.loaded.ext.module.maas:412 ][WARNING ][22419] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-01 10:02:05,653 [salt.loaded.ext.module.maas:92  ][INFO    ][22419] 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-01 10:02:06,982 [salt.loaded.ext.module.maas:412 ][WARNING ][22419] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-01 10:02:06,983 [salt.loaded.ext.module.maas:92  ][INFO    ][22419] 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-01 10:02:08,365 [salt.loaded.ext.module.maas:412 ][WARNING ][22419] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-01 10:02:08,365 [salt.loaded.ext.module.maas:92  ][INFO    ][22419] 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-01 10:02:09,668 [salt.state       :300 ][INFO    ][22419] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-01 10:02:09,668 [salt.state       :1951][INFO    ][22419] Completed state [maas.process_machines] at time 10:02:09.668409 duration_in_ms=7050.685
2019-02-01 10:02:09,673 [salt.minion      :1711][INFO    ][22419] Returning information for job: 20190201100152300991
2019-02-01 10:02:52,397 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command state.apply with jid 20190201100252367414
2019-02-01 10:02:52,431 [salt.minion      :1432][INFO    ][22813] Starting a new job with PID 22813
2019-02-01 10:03:00,315 [salt.state       :915 ][INFO    ][22813] Loading fresh modules for state activity
2019-02-01 10:03:00,375 [salt.fileclient  :1219][INFO    ][22813] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-01 10:03:00,436 [salt.state       :1780][INFO    ][22813] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:03:00.435959
2019-02-01 10:03:00,436 [salt.state       :1813][INFO    ][22813] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-01 10:03:00,439 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22813] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-01 10:03:02,367 [salt.state       :300 ][INFO    ][22813] {'pid': 22834, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-01 10:03:02,368 [salt.state       :1951][INFO    ][22813] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:03:02.368703 duration_in_ms=1932.748
2019-02-01 10:03:02,371 [salt.state       :1780][INFO    ][22813] Running state [maas.wait_for_machine_status] at time 10:03:02.371906
2019-02-01 10:03:02,372 [salt.state       :1813][INFO    ][22813] Executing state module.run for [maas.wait_for_machine_status]
2019-02-01 10:03:02,372 [salt.utils.decorators:613 ][WARNING ][22813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 10:03:02,448 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100302387269
2019-02-01 10:03:02,469 [salt.minion      :1432][INFO    ][22843] Starting a new job with PID 22843
2019-02-01 10:03:02,491 [salt.minion      :1711][INFO    ][22843] Returning information for job: 20190201100302387269
2019-02-01 10:03:03,336 [salt.loaded.ext.module.maas:1023][INFO    ][22813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.04958606s left)
2019-02-01 10:03:12,446 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100312411902
2019-02-01 10:03:12,484 [salt.minion      :1432][INFO    ][22854] Starting a new job with PID 22854
2019-02-01 10:03:12,515 [salt.minion      :1711][INFO    ][22854] Returning information for job: 20190201100312411902
2019-02-01 10:03:22,477 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100322444380
2019-02-01 10:03:22,507 [salt.minion      :1432][INFO    ][22863] Starting a new job with PID 22863
2019-02-01 10:03:22,534 [salt.minion      :1711][INFO    ][22863] Returning information for job: 20190201100322444380
2019-02-01 10:03:32,709 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100332676950
2019-02-01 10:03:32,736 [salt.minion      :1432][INFO    ][22887] Starting a new job with PID 22887
2019-02-01 10:03:32,756 [salt.minion      :1711][INFO    ][22887] Returning information for job: 20190201100332676950
2019-02-01 10:03:34,189 [salt.loaded.ext.module.maas:1023][INFO    ][22813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.19720387s left)
2019-02-01 10:03:42,922 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100342891446
2019-02-01 10:03:42,948 [salt.minion      :1432][INFO    ][22898] Starting a new job with PID 22898
2019-02-01 10:03:42,970 [salt.minion      :1711][INFO    ][22898] Returning information for job: 20190201100342891446
2019-02-01 10:03:53,132 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100353109191
2019-02-01 10:03:53,151 [salt.minion      :1432][INFO    ][22915] Starting a new job with PID 22915
2019-02-01 10:03:53,174 [salt.minion      :1711][INFO    ][22915] Returning information for job: 20190201100353109191
2019-02-01 10:04:03,346 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100403322120
2019-02-01 10:04:03,363 [salt.minion      :1432][INFO    ][22935] Starting a new job with PID 22935
2019-02-01 10:04:03,385 [salt.minion      :1711][INFO    ][22935] Returning information for job: 20190201100403322120
2019-02-01 10:04:05,030 [salt.loaded.ext.module.maas:1023][INFO    ][22813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.3562789s left)
2019-02-01 10:04:13,556 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100413523439
2019-02-01 10:04:13,581 [salt.minion      :1432][INFO    ][22947] Starting a new job with PID 22947
2019-02-01 10:04:13,602 [salt.minion      :1711][INFO    ][22947] Returning information for job: 20190201100413523439
2019-02-01 10:04:23,770 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100423741429
2019-02-01 10:04:23,799 [salt.minion      :1432][INFO    ][22956] Starting a new job with PID 22956
2019-02-01 10:04:23,825 [salt.minion      :1711][INFO    ][22956] Returning information for job: 20190201100423741429
2019-02-01 10:04:33,969 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100433946147
2019-02-01 10:04:33,992 [salt.minion      :1432][INFO    ][23009] Starting a new job with PID 23009
2019-02-01 10:04:34,014 [salt.minion      :1711][INFO    ][23009] Returning information for job: 20190201100433946147
2019-02-01 10:04:36,025 [salt.loaded.ext.module.maas:1023][INFO    ][22813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.36083198s left)
2019-02-01 10:04:44,181 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100444149131
2019-02-01 10:04:44,211 [salt.minion      :1432][INFO    ][23020] Starting a new job with PID 23020
2019-02-01 10:04:44,240 [salt.minion      :1711][INFO    ][23020] Returning information for job: 20190201100444149131
2019-02-01 10:04:54,195 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100454172416
2019-02-01 10:04:54,218 [salt.minion      :1432][INFO    ][23064] Starting a new job with PID 23064
2019-02-01 10:04:54,247 [salt.minion      :1711][INFO    ][23064] Returning information for job: 20190201100454172416
2019-02-01 10:05:04,391 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100504368132
2019-02-01 10:05:04,412 [salt.minion      :1432][INFO    ][23143] Starting a new job with PID 23143
2019-02-01 10:05:04,439 [salt.minion      :1711][INFO    ][23143] Returning information for job: 20190201100504368132
2019-02-01 10:05:07,041 [salt.loaded.ext.module.maas:1023][INFO    ][22813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.34514689s left)
2019-02-01 10:05:14,600 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100514573783
2019-02-01 10:05:14,632 [salt.minion      :1432][INFO    ][23176] Starting a new job with PID 23176
2019-02-01 10:05:14,663 [salt.minion      :1711][INFO    ][23176] Returning information for job: 20190201100514573783
2019-02-01 10:05:24,630 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100524600094
2019-02-01 10:05:24,658 [salt.minion      :1432][INFO    ][23196] Starting a new job with PID 23196
2019-02-01 10:05:24,684 [salt.minion      :1711][INFO    ][23196] Returning information for job: 20190201100524600094
2019-02-01 10:05:34,654 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100534626650
2019-02-01 10:05:34,681 [salt.minion      :1432][INFO    ][23241] Starting a new job with PID 23241
2019-02-01 10:05:34,710 [salt.minion      :1711][INFO    ][23241] Returning information for job: 20190201100534626650
2019-02-01 10:05:38,247 [salt.loaded.ext.module.maas:1023][INFO    ][22813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.13876104s left)
2019-02-01 10:05:44,674 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100544649646
2019-02-01 10:05:44,697 [salt.minion      :1432][INFO    ][23264] Starting a new job with PID 23264
2019-02-01 10:05:44,721 [salt.minion      :1711][INFO    ][23264] Returning information for job: 20190201100544649646
2019-02-01 10:05:54,687 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100554662684
2019-02-01 10:05:54,709 [salt.minion      :1432][INFO    ][23301] Starting a new job with PID 23301
2019-02-01 10:05:54,739 [salt.minion      :1711][INFO    ][23301] Returning information for job: 20190201100554662684
2019-02-01 10:06:04,717 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100604685619
2019-02-01 10:06:04,756 [salt.minion      :1432][INFO    ][23483] Starting a new job with PID 23483
2019-02-01 10:06:04,786 [salt.minion      :1711][INFO    ][23483] Returning information for job: 20190201100604685619
2019-02-01 10:06:09,657 [salt.loaded.ext.module.maas:1023][INFO    ][22813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.72864389s left)
2019-02-01 10:06:14,743 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100614717426
2019-02-01 10:06:14,764 [salt.minion      :1432][INFO    ][23564] Starting a new job with PID 23564
2019-02-01 10:06:14,794 [salt.minion      :1711][INFO    ][23564] Returning information for job: 20190201100614717426
2019-02-01 10:06:24,948 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100624923259
2019-02-01 10:06:24,973 [salt.minion      :1432][INFO    ][23611] Starting a new job with PID 23611
2019-02-01 10:06:24,999 [salt.minion      :1711][INFO    ][23611] Returning information for job: 20190201100624923259
2019-02-01 10:06:35,159 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100635129371
2019-02-01 10:06:35,190 [salt.minion      :1432][INFO    ][23642] Starting a new job with PID 23642
2019-02-01 10:06:35,214 [salt.minion      :1711][INFO    ][23642] Returning information for job: 20190201100635129371
2019-02-01 10:06:41,155 [salt.loaded.ext.module.maas:1023][INFO    ][22813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.23129487s left)
2019-02-01 10:06:45,191 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100645165475
2019-02-01 10:06:45,220 [salt.minion      :1432][INFO    ][23668] Starting a new job with PID 23668
2019-02-01 10:06:45,242 [salt.minion      :1711][INFO    ][23668] Returning information for job: 20190201100645165475
2019-02-01 10:06:55,219 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100655195192
2019-02-01 10:06:55,244 [salt.minion      :1432][INFO    ][23741] Starting a new job with PID 23741
2019-02-01 10:06:55,269 [salt.minion      :1711][INFO    ][23741] Returning information for job: 20190201100655195192
2019-02-01 10:07:05,258 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100705228277
2019-02-01 10:07:05,286 [salt.minion      :1432][INFO    ][23961] Starting a new job with PID 23961
2019-02-01 10:07:05,310 [salt.minion      :1711][INFO    ][23961] Returning information for job: 20190201100705228277
2019-02-01 10:07:13,500 [salt.loaded.ext.module.maas:1023][INFO    ][22813] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1248.88564491s left)
2019-02-01 10:07:15,290 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100715266340
2019-02-01 10:07:15,311 [salt.minion      :1432][INFO    ][24061] Starting a new job with PID 24061
2019-02-01 10:07:15,331 [salt.minion      :1711][INFO    ][24061] Returning information for job: 20190201100715266340
2019-02-01 10:07:25,510 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100725485040
2019-02-01 10:07:25,533 [salt.minion      :1432][INFO    ][24106] Starting a new job with PID 24106
2019-02-01 10:07:25,559 [salt.minion      :1711][INFO    ][24106] Returning information for job: 20190201100725485040
2019-02-01 10:07:35,556 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100735526669
2019-02-01 10:07:35,587 [salt.minion      :1432][INFO    ][24249] Starting a new job with PID 24249
2019-02-01 10:07:35,616 [salt.minion      :1711][INFO    ][24249] Returning information for job: 20190201100735526669
2019-02-01 10:07:45,607 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100745585112
2019-02-01 10:07:45,632 [salt.minion      :1432][INFO    ][24288] Starting a new job with PID 24288
2019-02-01 10:07:45,659 [salt.minion      :1711][INFO    ][24288] Returning information for job: 20190201100745585112
2019-02-01 10:07:45,984 [salt.loaded.ext.module.maas:1023][INFO    ][22813] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.40194201s left)
2019-02-01 10:07:55,668 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100755640323
2019-02-01 10:07:55,700 [salt.minion      :1432][INFO    ][24322] Starting a new job with PID 24322
2019-02-01 10:07:55,740 [salt.minion      :1711][INFO    ][24322] Returning information for job: 20190201100755640323
2019-02-01 10:08:05,700 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100805674236
2019-02-01 10:08:05,730 [salt.minion      :1432][INFO    ][24491] Starting a new job with PID 24491
2019-02-01 10:08:05,756 [salt.minion      :1711][INFO    ][24491] Returning information for job: 20190201100805674236
2019-02-01 10:08:15,921 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100815896967
2019-02-01 10:08:15,951 [salt.minion      :1432][INFO    ][24498] Starting a new job with PID 24498
2019-02-01 10:08:15,980 [salt.minion      :1711][INFO    ][24498] Returning information for job: 20190201100815896967
2019-02-01 10:08:18,469 [salt.loaded.ext.module.maas:1023][INFO    ][22813] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1183.91683102s left)
2019-02-01 10:08:25,959 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100825937530
2019-02-01 10:08:25,982 [salt.minion      :1432][INFO    ][24586] Starting a new job with PID 24586
2019-02-01 10:08:26,008 [salt.minion      :1711][INFO    ][24586] Returning information for job: 20190201100825937530
2019-02-01 10:08:35,990 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100835957992
2019-02-01 10:08:36,024 [salt.minion      :1432][INFO    ][24612] Starting a new job with PID 24612
2019-02-01 10:08:36,055 [salt.minion      :1711][INFO    ][24612] Returning information for job: 20190201100835957992
2019-02-01 10:08:46,071 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100846049965
2019-02-01 10:08:46,092 [salt.minion      :1432][INFO    ][24712] Starting a new job with PID 24712
2019-02-01 10:08:46,113 [salt.minion      :1711][INFO    ][24712] Returning information for job: 20190201100846049965
2019-02-01 10:08:51,707 [salt.loaded.ext.module.maas:1023][INFO    ][22813] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:1500s (1150.67956805s left)
2019-02-01 10:08:56,136 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100856106448
2019-02-01 10:08:56,164 [salt.minion      :1432][INFO    ][24795] Starting a new job with PID 24795
2019-02-01 10:08:56,188 [salt.minion      :1711][INFO    ][24795] Returning information for job: 20190201100856106448
2019-02-01 10:09:06,232 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100906201915
2019-02-01 10:09:06,264 [salt.minion      :1432][INFO    ][24819] Starting a new job with PID 24819
2019-02-01 10:09:06,289 [salt.minion      :1711][INFO    ][24819] Returning information for job: 20190201100906201915
2019-02-01 10:09:16,323 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100916291582
2019-02-01 10:09:16,347 [salt.minion      :1432][INFO    ][24845] Starting a new job with PID 24845
2019-02-01 10:09:16,382 [salt.minion      :1711][INFO    ][24845] Returning information for job: 20190201100916291582
2019-02-01 10:09:25,190 [salt.state       :300 ][INFO    ][22813] {'ret': True}
2019-02-01 10:09:25,194 [salt.state       :1951][INFO    ][22813] Completed state [maas.wait_for_machine_status] at time 10:09:25.194228 duration_in_ms=382822.317
2019-02-01 10:09:25,201 [salt.minion      :1711][INFO    ][22813] Returning information for job: 20190201100252367414
2019-02-01 10:09:25,921 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command state.apply with jid 20190201100925894289
2019-02-01 10:09:25,952 [salt.minion      :1432][INFO    ][24861] Starting a new job with PID 24861
2019-02-01 10:09:33,712 [salt.state       :915 ][INFO    ][24861] Loading fresh modules for state activity
2019-02-01 10:09:33,769 [salt.fileclient  :1219][INFO    ][24861] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-01 10:09:33,876 [salt.state       :1780][INFO    ][24861] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:09:33.876125
2019-02-01 10:09:33,876 [salt.state       :1813][INFO    ][24861] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-01 10:09:33,879 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24861] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-01 10:09:35,871 [salt.state       :300 ][INFO    ][24861] {'pid': 24890, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-01 10:09:35,872 [salt.state       :1951][INFO    ][24861] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:09:35.872408 duration_in_ms=1996.282
2019-02-01 10:09:35,876 [salt.state       :1780][INFO    ][24861] Running state [maas_machines_storage_cmp002_lvm] at time 10:09:35.876302
2019-02-01 10:09:35,877 [salt.state       :1813][INFO    ][24861] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-01 10:09:36,024 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100936001868
2019-02-01 10:09:36,046 [salt.minion      :1432][INFO    ][24897] Starting a new job with PID 24897
2019-02-01 10:09:36,067 [salt.minion      :1711][INFO    ][24897] Returning information for job: 20190201100936001868
2019-02-01 10:09:37,142 [salt.loaded.ext.module.maasng:610 ][INFO    ][24861] r33mbc
2019-02-01 10:09:37,142 [salt.loaded.ext.module.maasng:626 ][INFO    ][24861] sda
2019-02-01 10:09:37,787 [salt.loaded.ext.module.maasng:361 ][INFO    ][24861] r33mbc
2019-02-01 10:09:37,887 [salt.loaded.ext.module.maasng:367 ][INFO    ][24861] [{u'size': 800109715456, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'7dfbf3c2-62e1-4850-9eb1-f4b50f543131', u'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/blockdevices/2/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r33mbc', u'filesystem': {u'mount_options': None, u'uuid': u'6f68a628-faba-4f37-90e9-7521db0dc236', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 2}], u'tags': [u'ssd'], u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'r33mbc', u'partition_table_type': u'MBR', 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'type': u'physical', u'model': u'LOGICAL VOLUME', u'uuid': None}, {u'size': 800097042432, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/blockdevices/4/', u'used_size': 800097042432, u'partitions': [], u'tags': [], u'id': 4, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'r33mbc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'6b052668-1036-48d3-8a02-eb38e17f4f9a', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'uuid': u'462201c4-7e44-4ed5-ade3-8b245ac11c7f'}]
2019-02-01 10:09:37,888 [salt.loaded.ext.module.maasng:632 ][INFO    ][24861] vgroot
2019-02-01 10:09:37,888 [salt.loaded.ext.module.maasng:635 ][INFO    ][24861] lvroot
2019-02-01 10:09:37,888 [salt.loaded.ext.module.maasng:639 ][INFO    ][24861] 107374182400
2019-02-01 10:09:38,572 [salt.loaded.ext.module.maasng:645 ][INFO    ][24861] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, 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'min_hwe_kernel': u'hwe-16.04', 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'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'r33mbc', 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'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'uuid': u'6f699607-b93f-448b-bc73-c2e9c876c4e4', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'r33mbc', u'partition_table_type': None, u'filesystem': {u'uuid': u'f588ed9c-1c80-40ba-8675-f2b7c44b9a5e', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/blockdevices/12/', u'used_size': 107374182400, u'model': None, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/blockdevices/2/', u'type': u'physical', u'id': 2, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'r33mbc', u'partition_table_type': u'MBR', 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'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'e9ca4712-a8ae-442c-b979-19703b4b140c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r33mbc', u'device_id': 2, u'filesystem': {u'uuid': u'0a8fa4f6-1862-45ac-b425-30965d12c609', 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/r33mbc/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}]}, {u'size': 107374182400, u'available_size': 0, u'uuid': u'6f699607-b93f-448b-bc73-c2e9c876c4e4', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/blockdevices/12/', u'type': u'virtual', u'id': 12, u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'r33mbc', u'partition_table_type': None, u'filesystem': {u'uuid': u'f588ed9c-1c80-40ba-8675-f2b7c44b9a5e', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'r33mbc', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'r33mbc', u'partition_table_type': u'MBR', 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'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/blockdevices/2/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'e9ca4712-a8ae-442c-b979-19703b4b140c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r33mbc', u'device_id': 2, u'filesystem': {u'uuid': u'0a8fa4f6-1862-45ac-b425-30965d12c609', 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/r33mbc/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}]}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'r33mbc', u'partition_table_type': u'MBR', 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'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/blockdevices/2/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'e9ca4712-a8ae-442c-b979-19703b4b140c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r33mbc', u'device_id': 2, u'filesystem': {u'uuid': u'0a8fa4f6-1862-45ac-b425-30965d12c609', 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/r33mbc/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gp8baf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gp8baf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 23}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gp8baf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'r33mbc', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/interfaces/5/'}, u'interface_set': [{u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gp8baf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gp8baf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 23}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gp8baf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'r33mbc', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 15, u'discovered': None, u'system_id': u'r33mbc', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/interfaces/15/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 16, u'discovered': None, u'system_id': u'r33mbc', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/interfaces/16/'}, {u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', 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'ens2f1', u'links': [{u'mode': u'link_up', u'id': 24}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 12, u'discovered': None, u'system_id': u'r33mbc', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/interfaces/12/'}, {u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gp8baf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gp8baf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 13, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gp8baf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'r33mbc', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/interfaces/13/'}, {u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', 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'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 14, u'discovered': None, u'system_id': u'r33mbc', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r33mbc/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/r33mbc/'}
2019-02-01 10:09:38,574 [salt.state       :300 ][INFO    ][24861] {'new': {'storage_layout': 'lvm'}}
2019-02-01 10:09:38,574 [salt.state       :1951][INFO    ][24861] Completed state [maas_machines_storage_cmp002_lvm] at time 10:09:38.574599 duration_in_ms=2698.298
2019-02-01 10:09:38,575 [salt.state       :1780][INFO    ][24861] Running state [maas_machines_storage_cmp001_lvm] at time 10:09:38.575075
2019-02-01 10:09:38,575 [salt.state       :1813][INFO    ][24861] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-01 10:09:39,960 [salt.loaded.ext.module.maasng:610 ][INFO    ][24861] hrs7tp
2019-02-01 10:09:39,960 [salt.loaded.ext.module.maasng:626 ][INFO    ][24861] sda
2019-02-01 10:09:40,608 [salt.loaded.ext.module.maasng:361 ][INFO    ][24861] hrs7tp
2019-02-01 10:09:40,709 [salt.loaded.ext.module.maasng:367 ][INFO    ][24861] [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'system_id': u'hrs7tp', u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/blockdevices/3/', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'51096dc9-a133-47eb-b85c-0f2500348149', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hrs7tp', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'0503afa1-8017-42c6-a2ff-db779d753ec8', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/blockdevices/3/partition/2'}], u'used_size': 800106479616, u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, {u'size': 800097042432, u'block_size': 4096, u'uuid': u'7bfafd6b-44bd-4db8-9297-b3fa348332e6', u'tags': [], u'system_id': u'hrs7tp', u'type': u'virtual', u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'7d326822-322d-442e-b6e6-cedfd05a41d3', u'fstype': u'ext4', u'mount_options': None}, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/blockdevices/5/', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 800097042432, u'id': 5, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-02-01 10:09:40,711 [salt.loaded.ext.module.maasng:632 ][INFO    ][24861] vgroot
2019-02-01 10:09:40,711 [salt.loaded.ext.module.maasng:635 ][INFO    ][24861] lvroot
2019-02-01 10:09:40,712 [salt.loaded.ext.module.maasng:639 ][INFO    ][24861] 107374182400
2019-02-01 10:09:41,433 [salt.loaded.ext.module.maasng:645 ][INFO    ][24861] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, u'owner_data': {}, u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gp8baf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 29}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gp8baf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gp8baf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'hrs7tp', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/interfaces/6/'}, 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'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/blockdevices/3/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hrs7tp', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'4a9d0569-77b6-427d-8f6b-56626b80100c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hrs7tp', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'd8ee9e7d-3d5f-42a2-af03-36e0c475b8fe', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/blockdevices/3/partition/7'}]}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'hrs7tp', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/blockdevices/13/', u'uuid': u'1c7133f0-1baf-4a64-9cb6-0e41f07b4677', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'99e30755-2fd0-4f24-97aa-18d33276364a', u'fstype': u'ext4', u'mount_options': None}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hrs7tp', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'system_id': u'hrs7tp', u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/blockdevices/3/', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'4a9d0569-77b6-427d-8f6b-56626b80100c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hrs7tp', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'd8ee9e7d-3d5f-42a2-af03-36e0c475b8fe', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/blockdevices/3/partition/7'}], u'used_size': 800106479616, u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, {u'size': 107374182400, u'block_size': 4096, u'uuid': u'1c7133f0-1baf-4a64-9cb6-0e41f07b4677', u'tags': [], u'system_id': u'hrs7tp', u'type': u'virtual', u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'99e30755-2fd0-4f24-97aa-18d33276364a', u'fstype': u'ext4', u'mount_options': None}, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/blockdevices/13/', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 13, u'serial': None, u'name': u'vgroot-lvroot'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/blockdevices/3/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hrs7tp', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'4a9d0569-77b6-427d-8f6b-56626b80100c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hrs7tp', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'd8ee9e7d-3d5f-42a2-af03-36e0c475b8fe', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/blockdevices/3/partition/7'}]}], u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hrs7tp', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gp8baf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 29}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gp8baf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gp8baf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'hrs7tp', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/interfaces/6/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'hrs7tp', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/interfaces/17/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'hrs7tp', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/interfaces/20/'}, {u'name': u'ens2f1', u'links': [{u'id': 30, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'hrs7tp', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/interfaces/18/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gp8baf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 31, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gp8baf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gp8baf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'hrs7tp', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{u'id': 32, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'hrs7tp', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/hrs7tp/interfaces/21/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/hrs7tp/'}
2019-02-01 10:09:41,435 [salt.state       :300 ][INFO    ][24861] {'new': {'storage_layout': 'lvm'}}
2019-02-01 10:09:41,435 [salt.state       :1951][INFO    ][24861] Completed state [maas_machines_storage_cmp001_lvm] at time 10:09:41.435724 duration_in_ms=2860.648
2019-02-01 10:09:41,441 [salt.minion      :1711][INFO    ][24861] Returning information for job: 20190201100925894289
2019-02-01 10:09:42,131 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command state.apply with jid 20190201100942104906
2019-02-01 10:09:42,157 [salt.minion      :1432][INFO    ][24917] Starting a new job with PID 24917
2019-02-01 10:09:43,017 [salt.state       :915 ][INFO    ][24917] Loading fresh modules for state activity
2019-02-01 10:09:43,070 [salt.fileclient  :1219][INFO    ][24917] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-01 10:09:43,120 [salt.state       :1780][INFO    ][24917] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:09:43.120478
2019-02-01 10:09:43,121 [salt.state       :1813][INFO    ][24917] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-01 10:09:43,123 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24917] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-01 10:09:45,107 [salt.state       :300 ][INFO    ][24917] {'pid': 24924, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-01 10:09:45,108 [salt.state       :1951][INFO    ][24917] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:09:45.108551 duration_in_ms=1988.074
2019-02-01 10:09:45,110 [salt.state       :1780][INFO    ][24917] Running state [maas.deploy_machines] at time 10:09:45.110756
2019-02-01 10:09:45,111 [salt.state       :1813][INFO    ][24917] Executing state module.run for [maas.deploy_machines]
2019-02-01 10:09:45,112 [salt.utils.decorators:613 ][WARNING ][24917] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 10:09:45,761 [salt.loaded.ext.module.maas:684 ][INFO    ][24917] deploymachines hwe_kernel=hwe-16.04 system_id=r33mbc distro_series=xenial
2019-02-01 10:09:48,312 [salt.loaded.ext.module.maas:684 ][INFO    ][24917] deploymachines hwe_kernel=hwe-16.04 system_id=hrs7tp distro_series=xenial
2019-02-01 10:09:50,895 [salt.loaded.ext.module.maas:684 ][INFO    ][24917] deploymachines hwe_kernel=hwe-16.04 system_id=4e7mnx distro_series=xenial
2019-02-01 10:09:52,193 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201100952166627
2019-02-01 10:09:52,216 [salt.minion      :1432][INFO    ][25083] Starting a new job with PID 25083
2019-02-01 10:09:52,243 [salt.minion      :1711][INFO    ][25083] Returning information for job: 20190201100952166627
2019-02-01 10:09:53,560 [salt.loaded.ext.module.maas:684 ][INFO    ][24917] deploymachines hwe_kernel=hwe-16.04 system_id=6xendc distro_series=xenial
2019-02-01 10:09:56,127 [salt.loaded.ext.module.maas:684 ][INFO    ][24917] deploymachines hwe_kernel=hwe-16.04 system_id=k3sny4 distro_series=xenial
2019-02-01 10:09:58,679 [salt.state       :300 ][INFO    ][24917] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-01 10:09:58,680 [salt.state       :1951][INFO    ][24917] Completed state [maas.deploy_machines] at time 10:09:58.680089 duration_in_ms=13569.33
2019-02-01 10:09:58,688 [salt.minion      :1711][INFO    ][24917] Returning information for job: 20190201100942104906
2019-02-01 10:09:59,391 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command state.apply with jid 20190201100959364959
2019-02-01 10:09:59,416 [salt.minion      :1432][INFO    ][25226] Starting a new job with PID 25226
2019-02-01 10:10:07,126 [salt.state       :915 ][INFO    ][25226] Loading fresh modules for state activity
2019-02-01 10:10:07,179 [salt.fileclient  :1219][INFO    ][25226] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-01 10:10:07,235 [salt.state       :1780][INFO    ][25226] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:10:07.235612
2019-02-01 10:10:07,235 [salt.state       :1813][INFO    ][25226] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-01 10:10:07,238 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25226] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-01 10:10:09,147 [salt.state       :300 ][INFO    ][25226] {'pid': 25251, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-01 10:10:09,148 [salt.state       :1951][INFO    ][25226] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:10:09.148202 duration_in_ms=1912.589
2019-02-01 10:10:09,152 [salt.state       :1780][INFO    ][25226] Running state [maas.wait_for_machine_status] at time 10:10:09.152771
2019-02-01 10:10:09,153 [salt.state       :1813][INFO    ][25226] Executing state module.run for [maas.wait_for_machine_status]
2019-02-01 10:10:09,154 [salt.utils.decorators:613 ][WARNING ][25226] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 10:10:09,443 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101009419687
2019-02-01 10:10:09,467 [salt.minion      :1432][INFO    ][25261] Starting a new job with PID 25261
2019-02-01 10:10:09,494 [salt.minion      :1711][INFO    ][25261] Returning information for job: 20190201101009419687
2019-02-01 10:10:12,439 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.72692204s left)
2019-02-01 10:10:19,648 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101019623579
2019-02-01 10:10:19,679 [salt.minion      :1432][INFO    ][25271] Starting a new job with PID 25271
2019-02-01 10:10:19,704 [salt.minion      :1711][INFO    ][25271] Returning information for job: 20190201101019623579
2019-02-01 10:10:29,863 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101029838592
2019-02-01 10:10:29,890 [salt.minion      :1432][INFO    ][25299] Starting a new job with PID 25299
2019-02-01 10:10:29,914 [salt.minion      :1711][INFO    ][25299] Returning information for job: 20190201101029838592
2019-02-01 10:10:40,064 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101040038264
2019-02-01 10:10:40,089 [salt.minion      :1432][INFO    ][25305] Starting a new job with PID 25305
2019-02-01 10:10:40,117 [salt.minion      :1711][INFO    ][25305] Returning information for job: 20190201101040038264
2019-02-01 10:10:45,914 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.2522471s left)
2019-02-01 10:10:50,283 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101050250280
2019-02-01 10:10:50,324 [salt.minion      :1432][INFO    ][25316] Starting a new job with PID 25316
2019-02-01 10:10:50,354 [salt.minion      :1711][INFO    ][25316] Returning information for job: 20190201101050250280
2019-02-01 10:11:00,309 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101100279330
2019-02-01 10:11:00,339 [salt.minion      :1432][INFO    ][25357] Starting a new job with PID 25357
2019-02-01 10:11:00,362 [salt.minion      :1711][INFO    ][25357] Returning information for job: 20190201101100279330
2019-02-01 10:11:10,345 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101110315149
2019-02-01 10:11:10,375 [salt.minion      :1432][INFO    ][25370] Starting a new job with PID 25370
2019-02-01 10:11:10,400 [salt.minion      :1711][INFO    ][25370] Returning information for job: 20190201101110315149
2019-02-01 10:11:19,328 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.83879519s left)
2019-02-01 10:11:20,559 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101120531304
2019-02-01 10:11:20,587 [salt.minion      :1432][INFO    ][25386] Starting a new job with PID 25386
2019-02-01 10:11:20,615 [salt.minion      :1711][INFO    ][25386] Returning information for job: 20190201101120531304
2019-02-01 10:11:30,784 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101130755638
2019-02-01 10:11:30,805 [salt.minion      :1432][INFO    ][25416] Starting a new job with PID 25416
2019-02-01 10:11:30,827 [salt.minion      :1711][INFO    ][25416] Returning information for job: 20190201101130755638
2019-02-01 10:11:40,993 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101140964979
2019-02-01 10:11:41,021 [salt.minion      :1432][INFO    ][25422] Starting a new job with PID 25422
2019-02-01 10:11:41,049 [salt.minion      :1711][INFO    ][25422] Returning information for job: 20190201101140964979
2019-02-01 10:11:51,180 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101151156447
2019-02-01 10:11:51,203 [salt.minion      :1432][INFO    ][25432] Starting a new job with PID 25432
2019-02-01 10:11:51,232 [salt.minion      :1711][INFO    ][25432] Returning information for job: 20190201101151156447
2019-02-01 10:11:52,233 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.93381596s left)
2019-02-01 10:12:01,394 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101201367955
2019-02-01 10:12:01,419 [salt.minion      :1432][INFO    ][25466] Starting a new job with PID 25466
2019-02-01 10:12:01,448 [salt.minion      :1711][INFO    ][25466] Returning information for job: 20190201101201367955
2019-02-01 10:12:11,598 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101211573881
2019-02-01 10:12:11,625 [salt.minion      :1432][INFO    ][25474] Starting a new job with PID 25474
2019-02-01 10:12:11,648 [salt.minion      :1711][INFO    ][25474] Returning information for job: 20190201101211573881
2019-02-01 10:12:21,789 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101221766595
2019-02-01 10:12:21,809 [salt.minion      :1432][INFO    ][25487] Starting a new job with PID 25487
2019-02-01 10:12:21,835 [salt.minion      :1711][INFO    ][25487] Returning information for job: 20190201101221766595
2019-02-01 10:12:25,454 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.71209717s left)
2019-02-01 10:12:31,984 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101231960374
2019-02-01 10:12:32,007 [salt.minion      :1432][INFO    ][25538] Starting a new job with PID 25538
2019-02-01 10:12:32,029 [salt.minion      :1711][INFO    ][25538] Returning information for job: 20190201101231960374
2019-02-01 10:12:42,170 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101242144204
2019-02-01 10:12:42,194 [salt.minion      :1432][INFO    ][25543] Starting a new job with PID 25543
2019-02-01 10:12:42,216 [salt.minion      :1711][INFO    ][25543] Returning information for job: 20190201101242144204
2019-02-01 10:12:52,366 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101252340180
2019-02-01 10:12:52,388 [salt.minion      :1432][INFO    ][25581] Starting a new job with PID 25581
2019-02-01 10:12:52,413 [salt.minion      :1711][INFO    ][25581] Returning information for job: 20190201101252340180
2019-02-01 10:12:58,779 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.38802505s left)
2019-02-01 10:13:02,568 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101302542985
2019-02-01 10:13:02,590 [salt.minion      :1432][INFO    ][25681] Starting a new job with PID 25681
2019-02-01 10:13:02,617 [salt.minion      :1711][INFO    ][25681] Returning information for job: 20190201101302542985
2019-02-01 10:13:12,784 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101312752226
2019-02-01 10:13:12,830 [salt.minion      :1432][INFO    ][25690] Starting a new job with PID 25690
2019-02-01 10:13:12,861 [salt.minion      :1711][INFO    ][25690] Returning information for job: 20190201101312752226
2019-02-01 10:13:22,825 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101322799920
2019-02-01 10:13:22,851 [salt.minion      :1432][INFO    ][25725] Starting a new job with PID 25725
2019-02-01 10:13:22,873 [salt.minion      :1711][INFO    ][25725] Returning information for job: 20190201101322799920
2019-02-01 10:13:32,211 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.95571399s left)
2019-02-01 10:13:32,842 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101332817052
2019-02-01 10:13:32,878 [salt.minion      :1432][INFO    ][25774] Starting a new job with PID 25774
2019-02-01 10:13:32,901 [salt.minion      :1711][INFO    ][25774] Returning information for job: 20190201101332817052
2019-02-01 10:13:42,886 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101342856052
2019-02-01 10:13:42,915 [salt.minion      :1432][INFO    ][25783] Starting a new job with PID 25783
2019-02-01 10:13:42,940 [salt.minion      :1711][INFO    ][25783] Returning information for job: 20190201101342856052
2019-02-01 10:13:52,907 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101352883233
2019-02-01 10:13:52,925 [salt.minion      :1432][INFO    ][25805] Starting a new job with PID 25805
2019-02-01 10:13:52,951 [salt.minion      :1711][INFO    ][25805] Returning information for job: 20190201101352883233
2019-02-01 10:14:02,932 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101402905769
2019-02-01 10:14:02,954 [salt.minion      :1432][INFO    ][26012] Starting a new job with PID 26012
2019-02-01 10:14:02,982 [salt.minion      :1711][INFO    ][26012] Returning information for job: 20190201101402905769
2019-02-01 10:14:05,534 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.63259006s left)
2019-02-01 10:14:13,154 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101413125545
2019-02-01 10:14:13,220 [salt.minion      :1432][INFO    ][26074] Starting a new job with PID 26074
2019-02-01 10:14:13,291 [salt.minion      :1711][INFO    ][26074] Returning information for job: 20190201101413125545
2019-02-01 10:14:23,249 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101423223340
2019-02-01 10:14:23,274 [salt.minion      :1432][INFO    ][26085] Starting a new job with PID 26085
2019-02-01 10:14:23,312 [salt.minion      :1711][INFO    ][26085] Returning information for job: 20190201101423223340
2019-02-01 10:14:33,270 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101433239668
2019-02-01 10:14:33,299 [salt.minion      :1432][INFO    ][26146] Starting a new job with PID 26146
2019-02-01 10:14:33,317 [salt.minion      :1711][INFO    ][26146] Returning information for job: 20190201101433239668
2019-02-01 10:14:38,953 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.21388507s left)
2019-02-01 10:14:43,305 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101443279207
2019-02-01 10:14:43,331 [salt.minion      :1432][INFO    ][26176] Starting a new job with PID 26176
2019-02-01 10:14:43,367 [salt.minion      :1711][INFO    ][26176] Returning information for job: 20190201101443279207
2019-02-01 10:14:53,365 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101453337788
2019-02-01 10:14:53,391 [salt.minion      :1432][INFO    ][26282] Starting a new job with PID 26282
2019-02-01 10:14:53,411 [salt.minion      :1711][INFO    ][26282] Returning information for job: 20190201101453337788
2019-02-01 10:15:03,399 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101503366747
2019-02-01 10:15:03,427 [salt.minion      :1432][INFO    ][26456] Starting a new job with PID 26456
2019-02-01 10:15:03,452 [salt.minion      :1711][INFO    ][26456] Returning information for job: 20190201101503366747
2019-02-01 10:15:12,145 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.02172017s left)
2019-02-01 10:15:13,463 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101513428271
2019-02-01 10:15:13,491 [salt.minion      :1432][INFO    ][26476] Starting a new job with PID 26476
2019-02-01 10:15:13,518 [salt.minion      :1711][INFO    ][26476] Returning information for job: 20190201101513428271
2019-02-01 10:15:23,542 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101523510013
2019-02-01 10:15:23,582 [salt.minion      :1432][INFO    ][26488] Starting a new job with PID 26488
2019-02-01 10:15:23,605 [salt.minion      :1711][INFO    ][26488] Returning information for job: 20190201101523510013
2019-02-01 10:15:33,622 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101533588803
2019-02-01 10:15:33,646 [salt.minion      :1432][INFO    ][26581] Starting a new job with PID 26581
2019-02-01 10:15:33,681 [salt.minion      :1711][INFO    ][26581] Returning information for job: 20190201101533588803
2019-02-01 10:15:43,686 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101543658741
2019-02-01 10:15:43,709 [salt.minion      :1432][INFO    ][26595] Starting a new job with PID 26595
2019-02-01 10:15:43,800 [salt.minion      :1711][INFO    ][26595] Returning information for job: 20190201101543658741
2019-02-01 10:15:45,414 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.75269318s left)
2019-02-01 10:15:53,820 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101553792219
2019-02-01 10:15:53,849 [salt.minion      :1432][INFO    ][26618] Starting a new job with PID 26618
2019-02-01 10:15:53,895 [salt.minion      :1711][INFO    ][26618] Returning information for job: 20190201101553792219
2019-02-01 10:16:03,888 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101603861720
2019-02-01 10:16:03,911 [salt.minion      :1432][INFO    ][26923] Starting a new job with PID 26923
2019-02-01 10:16:03,934 [salt.minion      :1711][INFO    ][26923] Returning information for job: 20190201101603861720
2019-02-01 10:16:13,955 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101613917780
2019-02-01 10:16:13,990 [salt.minion      :1432][INFO    ][26932] Starting a new job with PID 26932
2019-02-01 10:16:14,063 [salt.minion      :1711][INFO    ][26932] Returning information for job: 20190201101613917780
2019-02-01 10:16:19,047 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.11968517s left)
2019-02-01 10:16:24,095 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101624065552
2019-02-01 10:16:24,133 [salt.minion      :1432][INFO    ][26960] Starting a new job with PID 26960
2019-02-01 10:16:24,182 [salt.minion      :1711][INFO    ][26960] Returning information for job: 20190201101624065552
2019-02-01 10:16:34,218 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101634191773
2019-02-01 10:16:34,239 [salt.minion      :1432][INFO    ][27064] Starting a new job with PID 27064
2019-02-01 10:16:34,266 [salt.minion      :1711][INFO    ][27064] Returning information for job: 20190201101634191773
2019-02-01 10:16:44,261 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101644233927
2019-02-01 10:16:44,281 [salt.minion      :1432][INFO    ][27072] Starting a new job with PID 27072
2019-02-01 10:16:44,307 [salt.minion      :1711][INFO    ][27072] Returning information for job: 20190201101644233927
2019-02-01 10:16:52,109 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.05722404s left)
2019-02-01 10:16:54,309 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101654281988
2019-02-01 10:16:54,330 [salt.minion      :1432][INFO    ][27100] Starting a new job with PID 27100
2019-02-01 10:16:54,358 [salt.minion      :1711][INFO    ][27100] Returning information for job: 20190201101654281988
2019-02-01 10:17:04,345 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101704316521
2019-02-01 10:17:04,369 [salt.minion      :1432][INFO    ][27278] Starting a new job with PID 27278
2019-02-01 10:17:04,393 [salt.minion      :1711][INFO    ][27278] Returning information for job: 20190201101704316521
2019-02-01 10:17:14,380 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101714353274
2019-02-01 10:17:14,404 [salt.minion      :1432][INFO    ][27285] Starting a new job with PID 27285
2019-02-01 10:17:14,425 [salt.minion      :1711][INFO    ][27285] Returning information for job: 20190201101714353274
2019-02-01 10:17:24,413 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101724386360
2019-02-01 10:17:24,436 [salt.minion      :1432][INFO    ][27307] Starting a new job with PID 27307
2019-02-01 10:17:24,463 [salt.minion      :1711][INFO    ][27307] Returning information for job: 20190201101724386360
2019-02-01 10:17:24,903 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1814.26340508s left)
2019-02-01 10:17:34,459 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101734432069
2019-02-01 10:17:34,483 [salt.minion      :1432][INFO    ][27370] Starting a new job with PID 27370
2019-02-01 10:17:34,507 [salt.minion      :1711][INFO    ][27370] Returning information for job: 20190201101734432069
2019-02-01 10:17:44,495 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101744467490
2019-02-01 10:17:44,517 [salt.minion      :1432][INFO    ][27378] Starting a new job with PID 27378
2019-02-01 10:17:44,555 [salt.minion      :1711][INFO    ][27378] Returning information for job: 20190201101744467490
2019-02-01 10:17:54,579 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101754546433
2019-02-01 10:17:54,602 [salt.minion      :1432][INFO    ][27410] Starting a new job with PID 27410
2019-02-01 10:17:54,629 [salt.minion      :1711][INFO    ][27410] Returning information for job: 20190201101754546433
2019-02-01 10:17:58,091 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1781.07514501s left)
2019-02-01 10:18:04,655 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101804625051
2019-02-01 10:18:04,677 [salt.minion      :1432][INFO    ][27575] Starting a new job with PID 27575
2019-02-01 10:18:04,705 [salt.minion      :1711][INFO    ][27575] Returning information for job: 20190201101804625051
2019-02-01 10:18:14,743 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101814715530
2019-02-01 10:18:14,764 [salt.minion      :1432][INFO    ][27592] Starting a new job with PID 27592
2019-02-01 10:18:14,789 [salt.minion      :1711][INFO    ][27592] Returning information for job: 20190201101814715530
2019-02-01 10:18:24,855 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101824825745
2019-02-01 10:18:24,883 [salt.minion      :1432][INFO    ][27601] Starting a new job with PID 27601
2019-02-01 10:18:24,911 [salt.minion      :1711][INFO    ][27601] Returning information for job: 20190201101824825745
2019-02-01 10:18:31,491 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1747.67521906s left)
2019-02-01 10:18:34,998 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101834963298
2019-02-01 10:18:35,028 [salt.minion      :1432][INFO    ][27672] Starting a new job with PID 27672
2019-02-01 10:18:35,052 [salt.minion      :1711][INFO    ][27672] Returning information for job: 20190201101834963298
2019-02-01 10:18:45,182 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101845145036
2019-02-01 10:18:45,211 [salt.minion      :1432][INFO    ][27679] Starting a new job with PID 27679
2019-02-01 10:18:45,239 [salt.minion      :1711][INFO    ][27679] Returning information for job: 20190201101845145036
2019-02-01 10:18:55,358 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101855321562
2019-02-01 10:18:55,400 [salt.minion      :1432][INFO    ][27718] Starting a new job with PID 27718
2019-02-01 10:18:55,429 [salt.minion      :1711][INFO    ][27718] Returning information for job: 20190201101855321562
2019-02-01 10:19:04,684 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1714.48232102s left)
2019-02-01 10:19:05,518 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101905486280
2019-02-01 10:19:05,547 [salt.minion      :1432][INFO    ][27819] Starting a new job with PID 27819
2019-02-01 10:19:05,574 [salt.minion      :1711][INFO    ][27819] Returning information for job: 20190201101905486280
2019-02-01 10:19:15,741 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101915708251
2019-02-01 10:19:15,771 [salt.minion      :1432][INFO    ][27842] Starting a new job with PID 27842
2019-02-01 10:19:15,799 [salt.minion      :1711][INFO    ][27842] Returning information for job: 20190201101915708251
2019-02-01 10:19:25,884 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101925847669
2019-02-01 10:19:25,919 [salt.minion      :1432][INFO    ][27857] Starting a new job with PID 27857
2019-02-01 10:19:25,944 [salt.minion      :1711][INFO    ][27857] Returning information for job: 20190201101925847669
2019-02-01 10:19:36,038 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101936009924
2019-02-01 10:19:36,064 [salt.minion      :1432][INFO    ][27932] Starting a new job with PID 27932
2019-02-01 10:19:36,097 [salt.minion      :1711][INFO    ][27932] Returning information for job: 20190201101936009924
2019-02-01 10:19:38,207 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1680.95912218s left)
2019-02-01 10:19:46,224 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101946190112
2019-02-01 10:19:46,255 [salt.minion      :1432][INFO    ][27944] Starting a new job with PID 27944
2019-02-01 10:19:46,294 [salt.minion      :1711][INFO    ][27944] Returning information for job: 20190201101946190112
2019-02-01 10:19:56,450 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201101956416537
2019-02-01 10:19:56,476 [salt.minion      :1432][INFO    ][27973] Starting a new job with PID 27973
2019-02-01 10:19:56,504 [salt.minion      :1711][INFO    ][27973] Returning information for job: 20190201101956416537
2019-02-01 10:20:06,651 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102006616977
2019-02-01 10:20:06,678 [salt.minion      :1432][INFO    ][28156] Starting a new job with PID 28156
2019-02-01 10:20:06,707 [salt.minion      :1711][INFO    ][28156] Returning information for job: 20190201102006616977
2019-02-01 10:20:11,488 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1647.67862701s left)
2019-02-01 10:20:16,865 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102016830074
2019-02-01 10:20:16,893 [salt.minion      :1432][INFO    ][28167] Starting a new job with PID 28167
2019-02-01 10:20:16,922 [salt.minion      :1711][INFO    ][28167] Returning information for job: 20190201102016830074
2019-02-01 10:20:27,065 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102027034468
2019-02-01 10:20:27,091 [salt.minion      :1432][INFO    ][28176] Starting a new job with PID 28176
2019-02-01 10:20:27,117 [salt.minion      :1711][INFO    ][28176] Returning information for job: 20190201102027034468
2019-02-01 10:20:37,291 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102037258482
2019-02-01 10:20:37,319 [salt.minion      :1432][INFO    ][28224] Starting a new job with PID 28224
2019-02-01 10:20:37,343 [salt.minion      :1711][INFO    ][28224] Returning information for job: 20190201102037258482
2019-02-01 10:20:44,951 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1614.21529007s left)
2019-02-01 10:20:47,521 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102047482494
2019-02-01 10:20:47,551 [salt.minion      :1432][INFO    ][28234] Starting a new job with PID 28234
2019-02-01 10:20:47,579 [salt.minion      :1711][INFO    ][28234] Returning information for job: 20190201102047482494
2019-02-01 10:20:57,564 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102057533160
2019-02-01 10:20:57,598 [salt.minion      :1432][INFO    ][28387] Starting a new job with PID 28387
2019-02-01 10:20:57,626 [salt.minion      :1711][INFO    ][28387] Returning information for job: 20190201102057533160
2019-02-01 10:21:07,614 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102107587236
2019-02-01 10:21:07,639 [salt.minion      :1432][INFO    ][28412] Starting a new job with PID 28412
2019-02-01 10:21:07,664 [salt.minion      :1711][INFO    ][28412] Returning information for job: 20190201102107587236
2019-02-01 10:21:17,634 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102117608589
2019-02-01 10:21:17,658 [salt.minion      :1432][INFO    ][28428] Starting a new job with PID 28428
2019-02-01 10:21:17,686 [salt.minion      :1711][INFO    ][28428] Returning information for job: 20190201102117608589
2019-02-01 10:21:18,329 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1580.836941s left)
2019-02-01 10:21:27,810 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102127782742
2019-02-01 10:21:27,835 [salt.minion      :1432][INFO    ][28440] Starting a new job with PID 28440
2019-02-01 10:21:27,863 [salt.minion      :1711][INFO    ][28440] Returning information for job: 20190201102127782742
2019-02-01 10:21:37,970 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102137944340
2019-02-01 10:21:37,990 [salt.minion      :1432][INFO    ][28464] Starting a new job with PID 28464
2019-02-01 10:21:38,011 [salt.minion      :1711][INFO    ][28464] Returning information for job: 20190201102137944340
2019-02-01 10:21:48,110 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102148083462
2019-02-01 10:21:48,131 [salt.minion      :1432][INFO    ][28470] Starting a new job with PID 28470
2019-02-01 10:21:48,152 [salt.minion      :1711][INFO    ][28470] Returning information for job: 20190201102148083462
2019-02-01 10:21:51,219 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1547.94779706s left)
2019-02-01 10:21:58,235 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102158208034
2019-02-01 10:21:58,254 [salt.minion      :1432][INFO    ][28491] Starting a new job with PID 28491
2019-02-01 10:21:58,278 [salt.minion      :1711][INFO    ][28491] Returning information for job: 20190201102158208034
2019-02-01 10:22:08,354 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102208327973
2019-02-01 10:22:08,372 [salt.minion      :1432][INFO    ][28514] Starting a new job with PID 28514
2019-02-01 10:22:08,395 [salt.minion      :1711][INFO    ][28514] Returning information for job: 20190201102208327973
2019-02-01 10:22:18,479 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102218449873
2019-02-01 10:22:18,497 [salt.minion      :1432][INFO    ][28539] Starting a new job with PID 28539
2019-02-01 10:22:18,520 [salt.minion      :1711][INFO    ][28539] Returning information for job: 20190201102218449873
2019-02-01 10:22:24,229 [salt.loaded.ext.module.maas:1023][INFO    ][25226] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1514.937428s left)
2019-02-01 10:22:28,613 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102228584651
2019-02-01 10:22:28,638 [salt.minion      :1432][INFO    ][28636] Starting a new job with PID 28636
2019-02-01 10:22:28,669 [salt.minion      :1711][INFO    ][28636] Returning information for job: 20190201102228584651
2019-02-01 10:22:38,765 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102238737334
2019-02-01 10:22:38,792 [salt.minion      :1432][INFO    ][28666] Starting a new job with PID 28666
2019-02-01 10:22:38,821 [salt.minion      :1711][INFO    ][28666] Returning information for job: 20190201102238737334
2019-02-01 10:22:48,850 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201102248818264
2019-02-01 10:22:48,878 [salt.minion      :1432][INFO    ][28702] Starting a new job with PID 28702
2019-02-01 10:22:48,908 [salt.minion      :1711][INFO    ][28702] Returning information for job: 20190201102248818264
2019-02-01 10:22:57,233 [salt.state       :300 ][INFO    ][25226] {'ret': True}
2019-02-01 10:22:57,234 [salt.state       :1951][INFO    ][25226] Completed state [maas.wait_for_machine_status] at time 10:22:57.234088 duration_in_ms=768081.318
2019-02-01 10:22:57,239 [salt.minion      :1711][INFO    ][25226] Returning information for job: 20190201100959364959
2019-02-01 10:47:33,305 [salt.utils.schedule:1377][INFO    ][12955] Running scheduled job: __mine_interval
2019-02-01 11:37:22,939 [salt.minion      :1308][INFO    ][12955] User sudo_ubuntu Executing command cp.push_dir with jid 20190201113722913000
2019-02-01 11:37:22,978 [salt.minion      :1432][INFO    ][1816] Starting a new job with PID 1816
