2019-05-17 02:06:45,048 [salt.utils.decorators:613 ][WARNING ][2143] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 02:06:45,996 [salt.utils.decorators:613 ][WARNING ][2143] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 02:06:48,953 [salt.loaded.int.states.file:2298][WARNING ][2574] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-17 02:07:06,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-17 02:07:06,978 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3037] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-17 02:07:07,035 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-05-17 02:07:08,035 [salt.cli.daemons :293 ][INFO    ][3147] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-05-17 02:07:08,156 [salt.cli.daemons :82  ][INFO    ][3147] Starting up the Salt Minion
2019-05-17 02:07:08,156 [salt.utils.event :1017][INFO    ][3147] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-05-17 02:07:09,293 [salt.minion      :976 ][INFO    ][3147] Creating minion process manager
2019-05-17 02:07:11,228 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3147] Executing command ['date', '+%z'] in directory '/root'
2019-05-17 02:07:11,254 [salt.utils.schedule:568 ][INFO    ][3147] Updating job settings for scheduled job: __mine_interval
2019-05-17 02:07:11,256 [salt.minion      :1108][INFO    ][3147] Added mine.update to scheduler
2019-05-17 02:07:11,264 [salt.minion      :1975][INFO    ][3147] Minion is starting as user 'root'
2019-05-17 02:07:11,280 [salt.minion      :2336][INFO    ][3147] Minion is ready to receive requests!
2019-05-17 02:07:16,103 [salt.state       :2022][WARNING ][3042] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-17 02:07:19,207 [salt.utils.decorators:613 ][WARNING ][3042] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 02:07:20,027 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517020720013423
2019-05-17 02:07:20,051 [salt.minion      :1432][INFO    ][3644] Starting a new job with PID 3644
2019-05-17 02:07:20,084 [salt.minion      :1711][INFO    ][3644] Returning information for job: 20190517020720013423
2019-05-17 02:07:50,166 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517020750155012
2019-05-17 02:07:50,181 [salt.minion      :1432][INFO    ][4017] Starting a new job with PID 4017
2019-05-17 02:07:50,207 [salt.minion      :1711][INFO    ][4017] Returning information for job: 20190517020750155012
2019-05-17 02:08:20,250 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517020820239723
2019-05-17 02:08:20,269 [salt.minion      :1432][INFO    ][4247] Starting a new job with PID 4247
2019-05-17 02:08:20,298 [salt.minion      :1711][INFO    ][4247] Returning information for job: 20190517020820239723
2019-05-17 02:08:50,349 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517020850335400
2019-05-17 02:08:50,372 [salt.minion      :1432][INFO    ][4509] Starting a new job with PID 4509
2019-05-17 02:08:50,398 [salt.minion      :1711][INFO    ][4509] Returning information for job: 20190517020850335400
2019-05-17 02:09:20,492 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517020920478090
2019-05-17 02:09:20,514 [salt.minion      :1432][INFO    ][4794] Starting a new job with PID 4794
2019-05-17 02:09:20,545 [salt.minion      :1711][INFO    ][4794] Returning information for job: 20190517020920478090
2019-05-17 02:09:50,651 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517020950632850
2019-05-17 02:09:50,677 [salt.minion      :1432][INFO    ][5057] Starting a new job with PID 5057
2019-05-17 02:09:50,710 [salt.minion      :1711][INFO    ][5057] Returning information for job: 20190517020950632850
2019-05-17 02:10:20,795 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021020777391
2019-05-17 02:10:20,819 [salt.minion      :1432][INFO    ][5341] Starting a new job with PID 5341
2019-05-17 02:10:20,844 [salt.minion      :1711][INFO    ][5341] Returning information for job: 20190517021020777391
2019-05-17 02:10:50,904 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021050892524
2019-05-17 02:10:50,926 [salt.minion      :1432][INFO    ][5599] Starting a new job with PID 5599
2019-05-17 02:10:50,956 [salt.minion      :1711][INFO    ][5599] Returning information for job: 20190517021050892524
2019-05-17 02:11:21,049 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021121041310
2019-05-17 02:11:21,065 [salt.minion      :1432][INFO    ][5875] Starting a new job with PID 5875
2019-05-17 02:11:21,094 [salt.minion      :1711][INFO    ][5875] Returning information for job: 20190517021121041310
2019-05-17 02:11:51,184 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021151170460
2019-05-17 02:11:51,204 [salt.minion      :1432][INFO    ][6135] Starting a new job with PID 6135
2019-05-17 02:11:51,237 [salt.minion      :1711][INFO    ][6135] Returning information for job: 20190517021151170460
2019-05-17 02:12:21,333 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021221322084
2019-05-17 02:12:21,357 [salt.minion      :1432][INFO    ][6417] Starting a new job with PID 6417
2019-05-17 02:12:21,385 [salt.minion      :1711][INFO    ][6417] Returning information for job: 20190517021221322084
2019-05-17 02:12:51,467 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021251454877
2019-05-17 02:12:51,487 [salt.minion      :1432][INFO    ][6690] Starting a new job with PID 6690
2019-05-17 02:12:51,516 [salt.minion      :1711][INFO    ][6690] Returning information for job: 20190517021251454877
2019-05-17 02:13:21,636 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021321627651
2019-05-17 02:13:21,665 [salt.minion      :1432][INFO    ][6994] Starting a new job with PID 6994
2019-05-17 02:13:21,692 [salt.minion      :1711][INFO    ][6994] Returning information for job: 20190517021321627651
2019-05-17 02:13:51,766 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021351754784
2019-05-17 02:13:51,787 [salt.minion      :1432][INFO    ][7270] Starting a new job with PID 7270
2019-05-17 02:13:51,816 [salt.minion      :1711][INFO    ][7270] Returning information for job: 20190517021351754784
2019-05-17 02:14:21,912 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021421904730
2019-05-17 02:14:21,932 [salt.minion      :1432][INFO    ][7568] Starting a new job with PID 7568
2019-05-17 02:14:21,958 [salt.minion      :1711][INFO    ][7568] Returning information for job: 20190517021421904730
2019-05-17 02:14:52,068 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021452061234
2019-05-17 02:14:52,085 [salt.minion      :1432][INFO    ][7832] Starting a new job with PID 7832
2019-05-17 02:14:52,111 [salt.minion      :1711][INFO    ][7832] Returning information for job: 20190517021452061234
2019-05-17 02:15:22,232 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021522222893
2019-05-17 02:15:22,255 [salt.minion      :1432][INFO    ][8125] Starting a new job with PID 8125
2019-05-17 02:15:22,287 [salt.minion      :1711][INFO    ][8125] Returning information for job: 20190517021522222893
2019-05-17 02:15:52,415 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021552405692
2019-05-17 02:15:52,441 [salt.minion      :1432][INFO    ][8339] Starting a new job with PID 8339
2019-05-17 02:15:52,471 [salt.minion      :1711][INFO    ][8339] Returning information for job: 20190517021552405692
2019-05-17 02:16:22,597 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021622584635
2019-05-17 02:16:22,626 [salt.minion      :1432][INFO    ][8580] Starting a new job with PID 8580
2019-05-17 02:16:22,662 [salt.minion      :1711][INFO    ][8580] Returning information for job: 20190517021622584635
2019-05-17 02:16:52,785 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021652778169
2019-05-17 02:16:52,801 [salt.minion      :1432][INFO    ][8829] Starting a new job with PID 8829
2019-05-17 02:16:52,832 [salt.minion      :1711][INFO    ][8829] Returning information for job: 20190517021652778169
2019-05-17 02:17:22,977 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021722962140
2019-05-17 02:17:23,005 [salt.minion      :1432][INFO    ][9140] Starting a new job with PID 9140
2019-05-17 02:17:23,037 [salt.minion      :1711][INFO    ][9140] Returning information for job: 20190517021722962140
2019-05-17 02:17:53,179 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021753165573
2019-05-17 02:17:53,196 [salt.minion      :1432][INFO    ][9401] Starting a new job with PID 9401
2019-05-17 02:17:53,233 [salt.minion      :1711][INFO    ][9401] Returning information for job: 20190517021753165573
2019-05-17 02:18:23,369 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021823357606
2019-05-17 02:18:23,394 [salt.minion      :1432][INFO    ][9647] Starting a new job with PID 9647
2019-05-17 02:18:23,423 [salt.minion      :1711][INFO    ][9647] Returning information for job: 20190517021823357606
2019-05-17 02:18:53,573 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021853560231
2019-05-17 02:18:53,590 [salt.minion      :1432][INFO    ][9855] Starting a new job with PID 9855
2019-05-17 02:18:53,625 [salt.minion      :1711][INFO    ][9855] Returning information for job: 20190517021853560231
2019-05-17 02:19:23,778 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021923760514
2019-05-17 02:19:23,799 [salt.minion      :1432][INFO    ][10128] Starting a new job with PID 10128
2019-05-17 02:19:23,829 [salt.minion      :1711][INFO    ][10128] Returning information for job: 20190517021923760514
2019-05-17 02:19:53,986 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517021953971881
2019-05-17 02:19:54,012 [salt.minion      :1432][INFO    ][10311] Starting a new job with PID 10311
2019-05-17 02:19:54,045 [salt.minion      :1711][INFO    ][10311] Returning information for job: 20190517021953971881
2019-05-17 02:20:24,005 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022023995237
2019-05-17 02:20:24,022 [salt.minion      :1432][INFO    ][10498] Starting a new job with PID 10498
2019-05-17 02:20:24,060 [salt.minion      :1711][INFO    ][10498] Returning information for job: 20190517022023995237
2019-05-17 02:20:54,240 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022054224516
2019-05-17 02:20:54,265 [salt.minion      :1432][INFO    ][10752] Starting a new job with PID 10752
2019-05-17 02:20:54,296 [salt.minion      :1711][INFO    ][10752] Returning information for job: 20190517022054224516
2019-05-17 02:21:24,299 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022124284316
2019-05-17 02:21:24,331 [salt.minion      :1432][INFO    ][11032] Starting a new job with PID 11032
2019-05-17 02:21:24,372 [salt.minion      :1711][INFO    ][11032] Returning information for job: 20190517022124284316
2019-05-17 02:21:54,356 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022154343294
2019-05-17 02:21:54,373 [salt.minion      :1432][INFO    ][11303] Starting a new job with PID 11303
2019-05-17 02:21:54,408 [salt.minion      :1711][INFO    ][11303] Returning information for job: 20190517022154343294
2019-05-17 02:22:24,416 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022224402589
2019-05-17 02:22:24,442 [salt.minion      :1432][INFO    ][11579] Starting a new job with PID 11579
2019-05-17 02:22:24,475 [salt.minion      :1711][INFO    ][11579] Returning information for job: 20190517022224402589
2019-05-17 02:22:26,610 [salt.utils.decorators:613 ][WARNING ][3042] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 02:22:54,526 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022254505692
2019-05-17 02:22:54,551 [salt.minion      :1432][INFO    ][11862] Starting a new job with PID 11862
2019-05-17 02:22:54,592 [salt.minion      :1711][INFO    ][11862] Returning information for job: 20190517022254505692
2019-05-17 02:23:24,648 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022324629582
2019-05-17 02:23:24,672 [salt.minion      :1432][INFO    ][12130] Starting a new job with PID 12130
2019-05-17 02:23:24,714 [salt.minion      :1711][INFO    ][12130] Returning information for job: 20190517022324629582
2019-05-17 02:23:54,792 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022354770886
2019-05-17 02:23:54,820 [salt.minion      :1432][INFO    ][12328] Starting a new job with PID 12328
2019-05-17 02:23:54,853 [salt.minion      :1711][INFO    ][12328] Returning information for job: 20190517022354770886
2019-05-17 02:24:24,927 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022424918626
2019-05-17 02:24:24,946 [salt.minion      :1432][INFO    ][12575] Starting a new job with PID 12575
2019-05-17 02:24:24,979 [salt.minion      :1711][INFO    ][12575] Returning information for job: 20190517022424918626
2019-05-17 02:24:55,076 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022455057266
2019-05-17 02:24:55,103 [salt.minion      :1432][INFO    ][12855] Starting a new job with PID 12855
2019-05-17 02:24:55,131 [salt.minion      :1711][INFO    ][12855] Returning information for job: 20190517022455057266
2019-05-17 02:25:25,389 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022525359230
2019-05-17 02:25:25,411 [salt.minion      :1432][INFO    ][13163] Starting a new job with PID 13163
2019-05-17 02:25:25,446 [salt.minion      :1711][INFO    ][13163] Returning information for job: 20190517022525359230
2019-05-17 02:25:55,549 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022555541629
2019-05-17 02:25:55,566 [salt.minion      :1432][INFO    ][13448] Starting a new job with PID 13448
2019-05-17 02:25:55,597 [salt.minion      :1711][INFO    ][13448] Returning information for job: 20190517022555541629
2019-05-17 02:26:25,725 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022625714483
2019-05-17 02:26:25,748 [salt.minion      :1432][INFO    ][13745] Starting a new job with PID 13745
2019-05-17 02:26:25,782 [salt.minion      :1711][INFO    ][13745] Returning information for job: 20190517022625714483
2019-05-17 02:26:55,937 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022655922788
2019-05-17 02:26:55,957 [salt.minion      :1432][INFO    ][14029] Starting a new job with PID 14029
2019-05-17 02:26:55,998 [salt.minion      :1711][INFO    ][14029] Returning information for job: 20190517022655922788
2019-05-17 02:27:26,120 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022726110251
2019-05-17 02:27:26,143 [salt.minion      :1432][INFO    ][14355] Starting a new job with PID 14355
2019-05-17 02:27:26,183 [salt.minion      :1711][INFO    ][14355] Returning information for job: 20190517022726110251
2019-05-17 02:27:47,143 [salt.utils.decorators:613 ][WARNING ][3042] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 02:27:56,303 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022756291669
2019-05-17 02:27:56,322 [salt.minion      :1432][INFO    ][14570] Starting a new job with PID 14570
2019-05-17 02:27:56,361 [salt.minion      :1711][INFO    ][14570] Returning information for job: 20190517022756291669
2019-05-17 02:28:26,508 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022826500642
2019-05-17 02:28:26,537 [salt.minion      :1432][INFO    ][14633] Starting a new job with PID 14633
2019-05-17 02:28:26,567 [salt.minion      :1711][INFO    ][14633] Returning information for job: 20190517022826500642
2019-05-17 02:28:56,529 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022856516418
2019-05-17 02:28:56,549 [salt.minion      :1432][INFO    ][14672] Starting a new job with PID 14672
2019-05-17 02:28:56,581 [salt.minion      :1711][INFO    ][14672] Returning information for job: 20190517022856516418
2019-05-17 02:29:26,559 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022926549095
2019-05-17 02:29:26,576 [salt.minion      :1432][INFO    ][14729] Starting a new job with PID 14729
2019-05-17 02:29:26,615 [salt.minion      :1711][INFO    ][14729] Returning information for job: 20190517022926549095
2019-05-17 02:29:56,637 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517022956627370
2019-05-17 02:29:56,658 [salt.minion      :1432][INFO    ][14760] Starting a new job with PID 14760
2019-05-17 02:29:56,686 [salt.minion      :1711][INFO    ][14760] Returning information for job: 20190517022956627370
2019-05-17 02:30:15,828 [salt.utils.decorators:613 ][WARNING ][3042] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 02:30:18,952 [salt.loaded.ext.module.maasng:1008][WARNING ][3042] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-17 02:30:18,953 [salt.loaded.ext.module.maasng:1011][WARNING ][3042] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-17 02:30:19,879 [salt.loaded.ext.module.maasng:1235][WARNING ][3042] Ignoring parameter vlan:0
2019-05-17 02:30:22,403 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command state.apply with jid 20190517023022393486
2019-05-17 02:30:22,422 [salt.minion      :1432][INFO    ][15039] Starting a new job with PID 15039
2019-05-17 02:30:29,760 [salt.state       :915 ][INFO    ][15039] Loading fresh modules for state activity
2019-05-17 02:30:29,902 [salt.fileclient  :1219][INFO    ][15039] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-05-17 02:30:29,990 [salt.state       :1780][INFO    ][15039] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:30:29.990847
2019-05-17 02:30:29,991 [salt.state       :1813][INFO    ][15039] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 02:30:30,000 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15039] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 02:30:32,402 [salt.state       :300 ][INFO    ][15039] {'pid': 15063, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 02:30:32,403 [salt.state       :1951][INFO    ][15039] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:30:32.403640 duration_in_ms=2412.793
2019-05-17 02:30:32,408 [salt.state       :1780][INFO    ][15039] Running state [maas.process_machines] at time 02:30:32.408527
2019-05-17 02:30:32,409 [salt.state       :1813][INFO    ][15039] Executing state module.run for [maas.process_machines]
2019-05-17 02:30:32,411 [salt.utils.decorators:613 ][WARNING ][15039] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 02:30:32,505 [salt.loaded.ext.module.maas:412 ][WARNING ][15039] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 02:30:32,505 [salt.loaded.ext.module.maas:92  ][INFO    ][15039] 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-05-17 02:30:34,198 [salt.loaded.ext.module.maas:412 ][WARNING ][15039] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 02:30:34,199 [salt.loaded.ext.module.maas:92  ][INFO    ][15039] 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-05-17 02:30:35,644 [salt.loaded.ext.module.maas:412 ][WARNING ][15039] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 02:30:35,645 [salt.loaded.ext.module.maas:92  ][INFO    ][15039] 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-05-17 02:30:37,266 [salt.loaded.ext.module.maas:412 ][WARNING ][15039] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 02:30:37,267 [salt.loaded.ext.module.maas:92  ][INFO    ][15039] 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-05-17 02:30:37,520 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023037507892
2019-05-17 02:30:37,543 [salt.minion      :1432][INFO    ][15243] Starting a new job with PID 15243
2019-05-17 02:30:37,577 [salt.minion      :1711][INFO    ][15243] Returning information for job: 20190517023037507892
2019-05-17 02:30:38,752 [salt.loaded.ext.module.maas:412 ][WARNING ][15039] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 02:30:38,753 [salt.loaded.ext.module.maas:92  ][INFO    ][15039] 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-05-17 02:30:40,255 [salt.state       :300 ][INFO    ][15039] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-17 02:30:40,256 [salt.state       :1951][INFO    ][15039] Completed state [maas.process_machines] at time 02:30:40.256270 duration_in_ms=7847.743
2019-05-17 02:30:40,260 [salt.minion      :1711][INFO    ][15039] Returning information for job: 20190517023022393486
2019-05-17 02:31:11,610 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command state.apply with jid 20190517023111598793
2019-05-17 02:31:11,634 [salt.minion      :1432][INFO    ][15390] Starting a new job with PID 15390
2019-05-17 02:31:17,615 [salt.state       :915 ][INFO    ][15390] Loading fresh modules for state activity
2019-05-17 02:31:17,696 [salt.fileclient  :1219][INFO    ][15390] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-17 02:31:17,754 [salt.state       :1780][INFO    ][15390] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:31:17.754422
2019-05-17 02:31:17,754 [salt.state       :1813][INFO    ][15390] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 02:31:17,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15390] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 02:31:19,629 [salt.state       :300 ][INFO    ][15390] {'pid': 15397, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 02:31:19,630 [salt.state       :1951][INFO    ][15390] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:31:19.630331 duration_in_ms=1875.909
2019-05-17 02:31:19,633 [salt.state       :1780][INFO    ][15390] Running state [maas.wait_for_machine_status] at time 02:31:19.633043
2019-05-17 02:31:19,634 [salt.state       :1813][INFO    ][15390] Executing state module.run for [maas.wait_for_machine_status]
2019-05-17 02:31:19,635 [salt.utils.decorators:613 ][WARNING ][15390] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 02:31:20,697 [salt.loaded.ext.module.maas:1023][INFO    ][15390] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.95562696s left)
2019-05-17 02:31:26,691 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023126681479
2019-05-17 02:31:26,710 [salt.minion      :1432][INFO    ][15439] Starting a new job with PID 15439
2019-05-17 02:31:26,738 [salt.minion      :1711][INFO    ][15439] Returning information for job: 20190517023126681479
2019-05-17 02:31:51,572 [salt.loaded.ext.module.maas:1023][INFO    ][15390] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.08118391s left)
2019-05-17 02:31:56,775 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023156756720
2019-05-17 02:31:56,803 [salt.minion      :1432][INFO    ][15462] Starting a new job with PID 15462
2019-05-17 02:31:56,830 [salt.minion      :1711][INFO    ][15462] Returning information for job: 20190517023156756720
2019-05-17 02:32:22,522 [salt.loaded.ext.module.maas:1023][INFO    ][15390] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.13085604s left)
2019-05-17 02:32:26,875 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023226859578
2019-05-17 02:32:26,908 [salt.minion      :1432][INFO    ][15514] Starting a new job with PID 15514
2019-05-17 02:32:26,943 [salt.minion      :1711][INFO    ][15514] Returning information for job: 20190517023226859578
2019-05-17 02:32:53,403 [salt.loaded.ext.module.maas:1023][INFO    ][15390] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.24988198s left)
2019-05-17 02:32:56,968 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023256951292
2019-05-17 02:32:56,995 [salt.minion      :1432][INFO    ][15539] Starting a new job with PID 15539
2019-05-17 02:32:57,024 [salt.minion      :1711][INFO    ][15539] Returning information for job: 20190517023256951292
2019-05-17 02:33:24,490 [salt.loaded.ext.module.maas:1023][INFO    ][15390] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.16245484s left)
2019-05-17 02:33:27,044 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023327029946
2019-05-17 02:33:27,068 [salt.minion      :1432][INFO    ][15621] Starting a new job with PID 15621
2019-05-17 02:33:27,096 [salt.minion      :1711][INFO    ][15621] Returning information for job: 20190517023327029946
2019-05-17 02:33:55,636 [salt.loaded.ext.module.maas:1023][INFO    ][15390] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.01691294s left)
2019-05-17 02:33:57,136 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023357125394
2019-05-17 02:33:57,161 [salt.minion      :1432][INFO    ][15764] Starting a new job with PID 15764
2019-05-17 02:33:57,195 [salt.minion      :1711][INFO    ][15764] Returning information for job: 20190517023357125394
2019-05-17 02:34:27,096 [salt.loaded.ext.module.maas:1023][INFO    ][15390] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.55726504s left)
2019-05-17 02:34:27,263 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023427251389
2019-05-17 02:34:27,286 [salt.minion      :1432][INFO    ][15998] Starting a new job with PID 15998
2019-05-17 02:34:27,319 [salt.minion      :1711][INFO    ][15998] Returning information for job: 20190517023427251389
2019-05-17 02:34:57,368 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023457356941
2019-05-17 02:34:57,384 [salt.minion      :1432][INFO    ][16145] Starting a new job with PID 16145
2019-05-17 02:34:57,419 [salt.minion      :1711][INFO    ][16145] Returning information for job: 20190517023457356941
2019-05-17 02:34:58,460 [salt.loaded.ext.module.maas:1023][INFO    ][15390] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.19324207s left)
2019-05-17 02:35:27,484 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023527471003
2019-05-17 02:35:27,500 [salt.minion      :1432][INFO    ][16313] Starting a new job with PID 16313
2019-05-17 02:35:27,535 [salt.minion      :1711][INFO    ][16313] Returning information for job: 20190517023527471003
2019-05-17 02:35:30,572 [salt.loaded.ext.module.maas:1023][INFO    ][15390] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.08078194s left)
2019-05-17 02:35:57,607 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023557587751
2019-05-17 02:35:57,629 [salt.minion      :1432][INFO    ][16514] Starting a new job with PID 16514
2019-05-17 02:35:57,659 [salt.minion      :1711][INFO    ][16514] Returning information for job: 20190517023557587751
2019-05-17 02:36:02,681 [salt.loaded.ext.module.maas:1023][INFO    ][15390] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.97176003s left)
2019-05-17 02:36:27,764 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023627747340
2019-05-17 02:36:27,792 [salt.minion      :1432][INFO    ][16859] Starting a new job with PID 16859
2019-05-17 02:36:27,824 [salt.minion      :1711][INFO    ][16859] Returning information for job: 20190517023627747340
2019-05-17 02:36:34,950 [salt.loaded.ext.module.maas:1023][INFO    ][15390] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.70278502s left)
2019-05-17 02:36:57,881 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023657868582
2019-05-17 02:36:57,899 [salt.minion      :1432][INFO    ][16920] Starting a new job with PID 16920
2019-05-17 02:36:57,930 [salt.minion      :1711][INFO    ][16920] Returning information for job: 20190517023657868582
2019-05-17 02:37:07,493 [salt.loaded.ext.module.maas:1023][INFO    ][15390] Waiting status:Ready|Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1152.16019106s left)
2019-05-17 02:37:28,025 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023728012498
2019-05-17 02:37:28,053 [salt.minion      :1432][INFO    ][17126] Starting a new job with PID 17126
2019-05-17 02:37:28,091 [salt.minion      :1711][INFO    ][17126] Returning information for job: 20190517023728012498
2019-05-17 02:37:40,503 [salt.state       :300 ][INFO    ][15390] {'ret': True}
2019-05-17 02:37:40,504 [salt.state       :1951][INFO    ][15390] Completed state [maas.wait_for_machine_status] at time 02:37:40.504263 duration_in_ms=380871.219
2019-05-17 02:37:40,510 [salt.minion      :1711][INFO    ][15390] Returning information for job: 20190517023111598793
2019-05-17 02:37:41,225 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command state.apply with jid 20190517023741205957
2019-05-17 02:37:41,247 [salt.minion      :1432][INFO    ][17151] Starting a new job with PID 17151
2019-05-17 02:37:47,608 [salt.state       :915 ][INFO    ][17151] Loading fresh modules for state activity
2019-05-17 02:37:47,696 [salt.fileclient  :1219][INFO    ][17151] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-17 02:37:47,812 [salt.state       :1780][INFO    ][17151] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:47.812757
2019-05-17 02:37:47,813 [salt.state       :1813][INFO    ][17151] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 02:37:47,815 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17151] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 02:37:49,691 [salt.state       :300 ][INFO    ][17151] {'pid': 17165, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 02:37:49,692 [salt.state       :1951][INFO    ][17151] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:49.692599 duration_in_ms=1879.841
2019-05-17 02:37:49,696 [salt.state       :1780][INFO    ][17151] Running state [maas_machines_storage_cmp002_lvm] at time 02:37:49.696768
2019-05-17 02:37:49,697 [salt.state       :1813][INFO    ][17151] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-17 02:37:51,075 [salt.loaded.ext.module.maasng:610 ][INFO    ][17151] w887nb
2019-05-17 02:37:51,075 [salt.loaded.ext.module.maasng:626 ][INFO    ][17151] sda
2019-05-17 02:37:51,645 [salt.loaded.ext.module.maasng:361 ][INFO    ][17151] w887nb
2019-05-17 02:37:51,741 [salt.loaded.ext.module.maasng:367 ][INFO    ][17151] [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'd4328e7f-5177-4e49-8a50-d6c23b77324a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w887nb', u'device_id': 1, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'5040ba41-000a-4967-8632-25dc4814a81d', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/blockdevices/1/partition/1'}], u'filesystem': None, u'uuid': None, u'id': 1, u'system_id': u'w887nb', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/blockdevices/1/'}, {u'size': 800097042432, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'9ad915d4-446d-4e36-8861-f1720d139266', u'label': u'root'}, u'uuid': u'7c01ebca-9055-41fe-9544-c5985bb2edb7', u'id': 3, u'system_id': u'w887nb', 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'block_size': 4096, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/blockdevices/3/'}]
2019-05-17 02:37:51,742 [salt.loaded.ext.module.maasng:632 ][INFO    ][17151] vgroot
2019-05-17 02:37:51,742 [salt.loaded.ext.module.maasng:635 ][INFO    ][17151] lvroot
2019-05-17 02:37:51,743 [salt.loaded.ext.module.maasng:639 ][INFO    ][17151] 107374182400
2019-05-17 02:37:52,352 [salt.loaded.ext.module.maasng:645 ][INFO    ][17151] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w887nb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/interfaces/4/'}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/w887nb/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'w887nb', 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'disable_ipv4': False, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'e3a61090-2679-4254-a989-819b81ecc9bc', u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w887nb', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2ad9240c-3bf0-4df3-a432-172503d8ff5b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'name': u'sda', u'system_id': u'w887nb', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/blockdevices/11/', u'uuid': u'66273516-6f14-40f7-b54f-e6fdc536a1aa', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5cf771dd-7661-42f5-add8-a94ca1d25e23', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'w887nb', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'w887nb', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'partition_table_type': u'MBR', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'e3a61090-2679-4254-a989-819b81ecc9bc', u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w887nb', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2ad9240c-3bf0-4df3-a432-172503d8ff5b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'w887nb', u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/blockdevices/1/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w887nb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 15, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w887nb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/interfaces/15/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 12, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w887nb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/interfaces/12/'}, {u'name': u'ens2f1', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 14, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w887nb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w887nb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/interfaces/11/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w887nb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/interfaces/13/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'partition_table_type': u'MBR', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'e3a61090-2679-4254-a989-819b81ecc9bc', u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w887nb', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2ad9240c-3bf0-4df3-a432-172503d8ff5b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'w887nb', u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/blockdevices/1/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'virtualblockdevice_set': [{u'partition_table_type': None, u'uuid': u'66273516-6f14-40f7-b54f-e6fdc536a1aa', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5cf771dd-7661-42f5-add8-a94ca1d25e23', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'w887nb', u'resource_uri': u'/MAAS/api/2.0/nodes/w887nb/blockdevices/11/', u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-17 02:37:52,356 [salt.state       :300 ][INFO    ][17151] {'new': {'storage_layout': 'lvm'}}
2019-05-17 02:37:52,356 [salt.state       :1951][INFO    ][17151] Completed state [maas_machines_storage_cmp002_lvm] at time 02:37:52.356456 duration_in_ms=2659.685
2019-05-17 02:37:52,357 [salt.state       :1780][INFO    ][17151] Running state [maas_machines_storage_cmp001_lvm] at time 02:37:52.357285
2019-05-17 02:37:52,360 [salt.state       :1813][INFO    ][17151] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-17 02:37:53,476 [salt.loaded.ext.module.maasng:610 ][INFO    ][17151] 6x3hw7
2019-05-17 02:37:53,476 [salt.loaded.ext.module.maasng:626 ][INFO    ][17151] sda
2019-05-17 02:37:54,056 [salt.loaded.ext.module.maasng:361 ][INFO    ][17151] 6x3hw7
2019-05-17 02:37:54,149 [salt.loaded.ext.module.maasng:367 ][INFO    ][17151] [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'cfa02099-8560-48c8-aa68-1d1dec2c50a1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6x3hw7', u'device_id': 2, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'33583739-882f-4a08-8d3c-d8723b0a032a', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/blockdevices/2/partition/2'}], u'filesystem': None, u'uuid': None, u'id': 2, u'system_id': u'6x3hw7', 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'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/blockdevices/2/'}, {u'size': 800097042432, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'ca39aa68-03cc-445b-9e09-1c9e54fb9c2b', u'label': u'root'}, u'uuid': u'1966aa20-20d3-49a8-b6ae-558e9e358935', u'id': 4, u'system_id': u'6x3hw7', 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'block_size': 4096, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/blockdevices/4/'}]
2019-05-17 02:37:54,150 [salt.loaded.ext.module.maasng:632 ][INFO    ][17151] vgroot
2019-05-17 02:37:54,151 [salt.loaded.ext.module.maasng:635 ][INFO    ][17151] lvroot
2019-05-17 02:37:54,151 [salt.loaded.ext.module.maasng:639 ][INFO    ][17151] 107374182400
2019-05-17 02:37:54,767 [salt.loaded.ext.module.maasng:645 ][INFO    ][17151] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dwrap4', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dwrap4', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dwrap4', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6x3hw7', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/6x3hw7/', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'6x3hw7', 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'disable_ipv4': False, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'6x3hw7', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8cae29d0-00a5-4d3a-922d-f4d2df8f6c3d', u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6x3hw7', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'6666b8ed-e577-4dc6-9e1c-9c1b6c910db5', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'size': 107374182400, u'model': None, u'uuid': u'17311c90-79b2-4005-ba3e-50566f846bb2', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/blockdevices/12/', u'used_size': 107374182400, u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6x3hw7', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'0b68ca5c-3036-4285-80fd-e432974727f2', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6x3hw7', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, 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'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'6x3hw7', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8cae29d0-00a5-4d3a-922d-f4d2df8f6c3d', u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6x3hw7', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'6666b8ed-e577-4dc6-9e1c-9c1b6c910db5', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'uuid': u'17311c90-79b2-4005-ba3e-50566f846bb2', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/blockdevices/12/', u'used_size': 107374182400, u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6x3hw7', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'0b68ca5c-3036-4285-80fd-e432974727f2', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dwrap4', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dwrap4', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dwrap4', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6x3hw7', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6x3hw7', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/interfaces/17/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6x3hw7', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6x3hw7', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/interfaces/16/'}, {u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6x3hw7', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/interfaces/19/'}, {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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dwrap4', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dwrap4', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dwrap4', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6x3hw7', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'6x3hw7', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8cae29d0-00a5-4d3a-922d-f4d2df8f6c3d', u'resource_uri': u'/MAAS/api/2.0/nodes/6x3hw7/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6x3hw7', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'6666b8ed-e577-4dc6-9e1c-9c1b6c910db5', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}}
2019-05-17 02:37:54,769 [salt.state       :300 ][INFO    ][17151] {'new': {'storage_layout': 'lvm'}}
2019-05-17 02:37:54,770 [salt.state       :1951][INFO    ][17151] Completed state [maas_machines_storage_cmp001_lvm] at time 02:37:54.770598 duration_in_ms=2413.313
2019-05-17 02:37:54,774 [salt.minion      :1711][INFO    ][17151] Returning information for job: 20190517023741205957
2019-05-17 02:37:55,497 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command state.apply with jid 20190517023755484400
2019-05-17 02:37:55,525 [salt.minion      :1432][INFO    ][17203] Starting a new job with PID 17203
2019-05-17 02:37:56,821 [salt.state       :915 ][INFO    ][17203] Loading fresh modules for state activity
2019-05-17 02:37:56,897 [salt.fileclient  :1219][INFO    ][17203] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-17 02:37:56,951 [salt.state       :1780][INFO    ][17203] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:56.950997
2019-05-17 02:37:56,951 [salt.state       :1813][INFO    ][17203] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 02:37:56,953 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17203] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 02:37:58,810 [salt.state       :300 ][INFO    ][17203] {'pid': 17210, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 02:37:58,811 [salt.state       :1951][INFO    ][17203] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:58.811243 duration_in_ms=1860.246
2019-05-17 02:37:58,813 [salt.state       :1780][INFO    ][17203] Running state [maas.deploy_machines] at time 02:37:58.813146
2019-05-17 02:37:58,813 [salt.state       :1813][INFO    ][17203] Executing state module.run for [maas.deploy_machines]
2019-05-17 02:37:58,815 [salt.utils.decorators:613 ][WARNING ][17203] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 02:37:59,365 [salt.loaded.ext.module.maas:684 ][INFO    ][17203] deploymachines hwe_kernel=ga-16.04 system_id=w887nb distro_series=xenial
2019-05-17 02:38:01,765 [salt.loaded.ext.module.maas:684 ][INFO    ][17203] deploymachines hwe_kernel=ga-16.04 system_id=6x3hw7 distro_series=xenial
2019-05-17 02:38:04,136 [salt.loaded.ext.module.maas:684 ][INFO    ][17203] deploymachines hwe_kernel=ga-16.04 system_id=4fdxxx distro_series=xenial
2019-05-17 02:38:06,609 [salt.loaded.ext.module.maas:684 ][INFO    ][17203] deploymachines hwe_kernel=ga-16.04 system_id=r4fftm distro_series=xenial
2019-05-17 02:38:09,163 [salt.loaded.ext.module.maas:684 ][INFO    ][17203] deploymachines hwe_kernel=ga-16.04 system_id=n6srab distro_series=xenial
2019-05-17 02:38:10,559 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023810547384
2019-05-17 02:38:10,578 [salt.minion      :1432][INFO    ][17479] Starting a new job with PID 17479
2019-05-17 02:38:10,609 [salt.minion      :1711][INFO    ][17479] Returning information for job: 20190517023810547384
2019-05-17 02:38:11,783 [salt.state       :300 ][INFO    ][17203] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-17 02:38:11,783 [salt.state       :1951][INFO    ][17203] Completed state [maas.deploy_machines] at time 02:38:11.783786 duration_in_ms=12970.639
2019-05-17 02:38:11,788 [salt.minion      :1711][INFO    ][17203] Returning information for job: 20190517023755484400
2019-05-17 02:38:12,487 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command state.apply with jid 20190517023812475834
2019-05-17 02:38:12,512 [salt.minion      :1432][INFO    ][17498] Starting a new job with PID 17498
2019-05-17 02:38:18,609 [salt.state       :915 ][INFO    ][17498] Loading fresh modules for state activity
2019-05-17 02:38:18,691 [salt.fileclient  :1219][INFO    ][17498] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-17 02:38:18,748 [salt.state       :1780][INFO    ][17498] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:38:18.748347
2019-05-17 02:38:18,748 [salt.state       :1813][INFO    ][17498] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 02:38:18,750 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17498] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 02:38:20,835 [salt.state       :300 ][INFO    ][17498] {'pid': 17510, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 02:38:20,836 [salt.state       :1951][INFO    ][17498] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:38:20.836204 duration_in_ms=2087.858
2019-05-17 02:38:20,839 [salt.state       :1780][INFO    ][17498] Running state [maas.wait_for_machine_status] at time 02:38:20.839834
2019-05-17 02:38:20,840 [salt.state       :1813][INFO    ][17498] Executing state module.run for [maas.wait_for_machine_status]
2019-05-17 02:38:20,840 [salt.utils.decorators:613 ][WARNING ][17498] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 02:38:23,879 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.97741294s left)
2019-05-17 02:38:27,581 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023827571508
2019-05-17 02:38:27,606 [salt.minion      :1432][INFO    ][17555] Starting a new job with PID 17555
2019-05-17 02:38:27,645 [salt.minion      :1711][INFO    ][17555] Returning information for job: 20190517023827571508
2019-05-17 02:38:56,909 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.94735384s left)
2019-05-17 02:38:57,683 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023857671025
2019-05-17 02:38:57,713 [salt.minion      :1432][INFO    ][17588] Starting a new job with PID 17588
2019-05-17 02:38:57,750 [salt.minion      :1711][INFO    ][17588] Returning information for job: 20190517023857671025
2019-05-17 02:39:27,775 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023927761403
2019-05-17 02:39:27,801 [salt.minion      :1432][INFO    ][17630] Starting a new job with PID 17630
2019-05-17 02:39:27,832 [salt.minion      :1711][INFO    ][17630] Returning information for job: 20190517023927761403
2019-05-17 02:39:29,808 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.04784703s left)
2019-05-17 02:39:57,863 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517023957851691
2019-05-17 02:39:57,891 [salt.minion      :1432][INFO    ][17652] Starting a new job with PID 17652
2019-05-17 02:39:57,925 [salt.minion      :1711][INFO    ][17652] Returning information for job: 20190517023957851691
2019-05-17 02:40:02,801 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.05535793s left)
2019-05-17 02:40:27,941 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024027929556
2019-05-17 02:40:27,971 [salt.minion      :1432][INFO    ][17703] Starting a new job with PID 17703
2019-05-17 02:40:28,002 [salt.minion      :1711][INFO    ][17703] Returning information for job: 20190517024027929556
2019-05-17 02:40:35,772 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.08438683s left)
2019-05-17 02:40:58,040 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024058020807
2019-05-17 02:40:58,063 [salt.minion      :1432][INFO    ][17766] Starting a new job with PID 17766
2019-05-17 02:40:58,104 [salt.minion      :1711][INFO    ][17766] Returning information for job: 20190517024058020807
2019-05-17 02:41:08,795 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.06063604s left)
2019-05-17 02:41:28,161 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024128145163
2019-05-17 02:41:28,194 [salt.minion      :1432][INFO    ][17926] Starting a new job with PID 17926
2019-05-17 02:41:28,230 [salt.minion      :1711][INFO    ][17926] Returning information for job: 20190517024128145163
2019-05-17 02:41:41,796 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.06031084s left)
2019-05-17 02:41:58,292 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024158279017
2019-05-17 02:41:58,317 [salt.minion      :1432][INFO    ][18030] Starting a new job with PID 18030
2019-05-17 02:41:58,352 [salt.minion      :1711][INFO    ][18030] Returning information for job: 20190517024158279017
2019-05-17 02:42:14,755 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.10120392s left)
2019-05-17 02:42:28,409 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024228395931
2019-05-17 02:42:28,436 [salt.minion      :1432][INFO    ][18301] Starting a new job with PID 18301
2019-05-17 02:42:28,467 [salt.minion      :1711][INFO    ][18301] Returning information for job: 20190517024228395931
2019-05-17 02:42:48,403 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.45312285s left)
2019-05-17 02:42:58,527 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024258514501
2019-05-17 02:42:58,558 [salt.minion      :1432][INFO    ][18436] Starting a new job with PID 18436
2019-05-17 02:42:58,588 [salt.minion      :1711][INFO    ][18436] Returning information for job: 20190517024258514501
2019-05-17 02:43:22,835 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1948.02062488s left)
2019-05-17 02:43:28,711 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024328702800
2019-05-17 02:43:28,734 [salt.minion      :1432][INFO    ][18791] Starting a new job with PID 18791
2019-05-17 02:43:28,765 [salt.minion      :1711][INFO    ][18791] Returning information for job: 20190517024328702800
2019-05-17 02:43:55,731 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1915.12484002s left)
2019-05-17 02:43:58,829 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024358816053
2019-05-17 02:43:58,857 [salt.minion      :1432][INFO    ][18935] Starting a new job with PID 18935
2019-05-17 02:43:58,890 [salt.minion      :1711][INFO    ][18935] Returning information for job: 20190517024358816053
2019-05-17 02:44:28,651 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1882.20528793s left)
2019-05-17 02:44:28,996 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024428983230
2019-05-17 02:44:29,023 [salt.minion      :1432][INFO    ][19182] Starting a new job with PID 19182
2019-05-17 02:44:29,057 [salt.minion      :1711][INFO    ][19182] Returning information for job: 20190517024428983230
2019-05-17 02:44:59,153 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024459138710
2019-05-17 02:44:59,173 [salt.minion      :1432][INFO    ][19259] Starting a new job with PID 19259
2019-05-17 02:44:59,204 [salt.minion      :1711][INFO    ][19259] Returning information for job: 20190517024459138710
2019-05-17 02:45:01,732 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1849.12379599s left)
2019-05-17 02:45:29,357 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024529346534
2019-05-17 02:45:29,383 [salt.minion      :1432][INFO    ][19544] Starting a new job with PID 19544
2019-05-17 02:45:29,416 [salt.minion      :1711][INFO    ][19544] Returning information for job: 20190517024529346534
2019-05-17 02:45:34,774 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1816.08170485s left)
2019-05-17 02:45:59,519 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024559510992
2019-05-17 02:45:59,542 [salt.minion      :1432][INFO    ][19625] Starting a new job with PID 19625
2019-05-17 02:45:59,572 [salt.minion      :1711][INFO    ][19625] Returning information for job: 20190517024559510992
2019-05-17 02:46:07,864 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1782.99186492s left)
2019-05-17 02:46:29,672 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024629662683
2019-05-17 02:46:29,693 [salt.minion      :1432][INFO    ][19845] Starting a new job with PID 19845
2019-05-17 02:46:29,724 [salt.minion      :1711][INFO    ][19845] Returning information for job: 20190517024629662683
2019-05-17 02:46:40,739 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.11709189s left)
2019-05-17 02:46:59,844 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024659831243
2019-05-17 02:46:59,873 [salt.minion      :1432][INFO    ][19930] Starting a new job with PID 19930
2019-05-17 02:46:59,904 [salt.minion      :1711][INFO    ][19930] Returning information for job: 20190517024659831243
2019-05-17 02:47:13,688 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.16757703s left)
2019-05-17 02:47:30,043 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024730023432
2019-05-17 02:47:30,071 [salt.minion      :1432][INFO    ][20190] Starting a new job with PID 20190
2019-05-17 02:47:30,101 [salt.minion      :1711][INFO    ][20190] Returning information for job: 20190517024730023432
2019-05-17 02:47:46,655 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.20129395s left)
2019-05-17 02:48:00,212 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024800199709
2019-05-17 02:48:00,240 [salt.minion      :1432][INFO    ][20240] Starting a new job with PID 20240
2019-05-17 02:48:00,271 [salt.minion      :1711][INFO    ][20240] Returning information for job: 20190517024800199709
2019-05-17 02:48:19,748 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.10779405s left)
2019-05-17 02:48:30,394 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024830380259
2019-05-17 02:48:30,424 [salt.minion      :1432][INFO    ][20393] Starting a new job with PID 20393
2019-05-17 02:48:30,457 [salt.minion      :1711][INFO    ][20393] Returning information for job: 20190517024830380259
2019-05-17 02:48:52,739 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.11665392s left)
2019-05-17 02:49:00,511 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024900498507
2019-05-17 02:49:00,527 [salt.minion      :1432][INFO    ][20436] Starting a new job with PID 20436
2019-05-17 02:49:00,560 [salt.minion      :1711][INFO    ][20436] Returning information for job: 20190517024900498507
2019-05-17 02:49:25,774 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.08242488s left)
2019-05-17 02:49:30,713 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517024930697358
2019-05-17 02:49:30,742 [salt.minion      :1432][INFO    ][20621] Starting a new job with PID 20621
2019-05-17 02:49:30,776 [salt.minion      :1711][INFO    ][20621] Returning information for job: 20190517024930697358
2019-05-17 02:49:58,650 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.20607305s left)
2019-05-17 02:50:00,918 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025000909177
2019-05-17 02:50:00,940 [salt.minion      :1432][INFO    ][20645] Starting a new job with PID 20645
2019-05-17 02:50:00,972 [salt.minion      :1711][INFO    ][20645] Returning information for job: 20190517025000909177
2019-05-17 02:50:31,123 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025031110945
2019-05-17 02:50:31,147 [salt.minion      :1432][INFO    ][20694] Starting a new job with PID 20694
2019-05-17 02:50:31,182 [salt.minion      :1711][INFO    ][20694] Returning information for job: 20190517025031110945
2019-05-17 02:50:31,851 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1519.00461197s left)
2019-05-17 02:51:01,243 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025101229470
2019-05-17 02:51:01,271 [salt.minion      :1432][INFO    ][20784] Starting a new job with PID 20784
2019-05-17 02:51:01,300 [salt.minion      :1711][INFO    ][20784] Returning information for job: 20190517025101229470
2019-05-17 02:51:04,804 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1486.05206585s left)
2019-05-17 02:51:31,305 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025131292647
2019-05-17 02:51:31,330 [salt.minion      :1432][INFO    ][21044] Starting a new job with PID 21044
2019-05-17 02:51:31,360 [salt.minion      :1711][INFO    ][21044] Returning information for job: 20190517025131292647
2019-05-17 02:51:37,811 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.04553103s left)
2019-05-17 02:52:01,338 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025201319255
2019-05-17 02:52:01,365 [salt.minion      :1432][INFO    ][21123] Starting a new job with PID 21123
2019-05-17 02:52:01,397 [salt.minion      :1711][INFO    ][21123] Returning information for job: 20190517025201319255
2019-05-17 02:52:10,774 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.08162785s left)
2019-05-17 02:52:31,372 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025231357052
2019-05-17 02:52:31,401 [salt.minion      :1432][INFO    ][21174] Starting a new job with PID 21174
2019-05-17 02:52:31,434 [salt.minion      :1711][INFO    ][21174] Returning information for job: 20190517025231357052
2019-05-17 02:52:43,787 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.06892586s left)
2019-05-17 02:53:01,445 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025301427943
2019-05-17 02:53:01,469 [salt.minion      :1432][INFO    ][21197] Starting a new job with PID 21197
2019-05-17 02:53:01,499 [salt.minion      :1711][INFO    ][21197] Returning information for job: 20190517025301427943
2019-05-17 02:53:16,623 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1354.23289204s left)
2019-05-17 02:53:31,525 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025331511731
2019-05-17 02:53:31,552 [salt.minion      :1432][INFO    ][21257] Starting a new job with PID 21257
2019-05-17 02:53:31,582 [salt.minion      :1711][INFO    ][21257] Returning information for job: 20190517025331511731
2019-05-17 02:53:49,455 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1321.40071487s left)
2019-05-17 02:54:01,604 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025401593120
2019-05-17 02:54:01,630 [salt.minion      :1432][INFO    ][21278] Starting a new job with PID 21278
2019-05-17 02:54:01,659 [salt.minion      :1711][INFO    ][21278] Returning information for job: 20190517025401593120
2019-05-17 02:54:22,468 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1288.38800192s left)
2019-05-17 02:54:31,695 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025431679582
2019-05-17 02:54:31,712 [salt.minion      :1432][INFO    ][21330] Starting a new job with PID 21330
2019-05-17 02:54:31,743 [salt.minion      :1711][INFO    ][21330] Returning information for job: 20190517025431679582
2019-05-17 02:54:55,231 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1255.62550282s left)
2019-05-17 02:55:01,794 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025501781137
2019-05-17 02:55:01,819 [salt.minion      :1432][INFO    ][21360] Starting a new job with PID 21360
2019-05-17 02:55:01,848 [salt.minion      :1711][INFO    ][21360] Returning information for job: 20190517025501781137
2019-05-17 02:55:28,216 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1222.63995504s left)
2019-05-17 02:55:31,919 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025531902768
2019-05-17 02:55:31,949 [salt.minion      :1432][INFO    ][21415] Starting a new job with PID 21415
2019-05-17 02:55:31,988 [salt.minion      :1711][INFO    ][21415] Returning information for job: 20190517025531902768
2019-05-17 02:56:01,244 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1189.61250591s left)
2019-05-17 02:56:02,045 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025602030423
2019-05-17 02:56:02,064 [salt.minion      :1432][INFO    ][21441] Starting a new job with PID 21441
2019-05-17 02:56:02,103 [salt.minion      :1711][INFO    ][21441] Returning information for job: 20190517025602030423
2019-05-17 02:56:32,216 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025632204993
2019-05-17 02:56:32,239 [salt.minion      :1432][INFO    ][21492] Starting a new job with PID 21492
2019-05-17 02:56:32,279 [salt.minion      :1711][INFO    ][21492] Returning information for job: 20190517025632204993
2019-05-17 02:56:34,045 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1156.81154394s left)
2019-05-17 02:57:02,384 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025702368231
2019-05-17 02:57:02,411 [salt.minion      :1432][INFO    ][21514] Starting a new job with PID 21514
2019-05-17 02:57:02,442 [salt.minion      :1711][INFO    ][21514] Returning information for job: 20190517025702368231
2019-05-17 02:57:07,128 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1123.72792888s left)
2019-05-17 02:57:32,584 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025732570442
2019-05-17 02:57:32,611 [salt.minion      :1432][INFO    ][21587] Starting a new job with PID 21587
2019-05-17 02:57:32,645 [salt.minion      :1711][INFO    ][21587] Returning information for job: 20190517025732570442
2019-05-17 02:57:39,853 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.00342298s left)
2019-05-17 02:58:02,764 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025802748085
2019-05-17 02:58:02,791 [salt.minion      :1432][INFO    ][21615] Starting a new job with PID 21615
2019-05-17 02:58:02,828 [salt.minion      :1711][INFO    ][21615] Returning information for job: 20190517025802748085
2019-05-17 02:58:12,604 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1058.25198889s left)
2019-05-17 02:58:32,780 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025832769704
2019-05-17 02:58:32,804 [salt.minion      :1432][INFO    ][21670] Starting a new job with PID 21670
2019-05-17 02:58:32,851 [salt.minion      :1711][INFO    ][21670] Returning information for job: 20190517025832769704
2019-05-17 02:58:45,493 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1025.36311603s left)
2019-05-17 02:59:02,989 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025902976772
2019-05-17 02:59:03,019 [salt.minion      :1432][INFO    ][21698] Starting a new job with PID 21698
2019-05-17 02:59:03,052 [salt.minion      :1711][INFO    ][21698] Returning information for job: 20190517025902976772
2019-05-17 02:59:18,369 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (992.487169027s left)
2019-05-17 02:59:33,061 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517025933046698
2019-05-17 02:59:33,087 [salt.minion      :1432][INFO    ][21751] Starting a new job with PID 21751
2019-05-17 02:59:33,124 [salt.minion      :1711][INFO    ][21751] Returning information for job: 20190517025933046698
2019-05-17 02:59:51,413 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (959.443265915s left)
2019-05-17 03:00:03,104 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030003090256
2019-05-17 03:00:03,131 [salt.minion      :1432][INFO    ][21778] Starting a new job with PID 21778
2019-05-17 03:00:03,164 [salt.minion      :1711][INFO    ][21778] Returning information for job: 20190517030003090256
2019-05-17 03:00:24,257 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (926.599154949s left)
2019-05-17 03:00:33,187 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030033177618
2019-05-17 03:00:33,211 [salt.minion      :1432][INFO    ][21830] Starting a new job with PID 21830
2019-05-17 03:00:33,243 [salt.minion      :1711][INFO    ][21830] Returning information for job: 20190517030033177618
2019-05-17 03:00:57,020 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (893.835598946s left)
2019-05-17 03:01:03,288 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030103274436
2019-05-17 03:01:03,308 [salt.minion      :1432][INFO    ][21857] Starting a new job with PID 21857
2019-05-17 03:01:03,344 [salt.minion      :1711][INFO    ][21857] Returning information for job: 20190517030103274436
2019-05-17 03:01:29,787 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (861.068944931s left)
2019-05-17 03:01:33,385 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030133373273
2019-05-17 03:01:33,409 [salt.minion      :1432][INFO    ][21910] Starting a new job with PID 21910
2019-05-17 03:01:33,444 [salt.minion      :1711][INFO    ][21910] Returning information for job: 20190517030133373273
2019-05-17 03:02:02,836 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (828.020531893s left)
2019-05-17 03:02:03,523 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030203509626
2019-05-17 03:02:03,550 [salt.minion      :1432][INFO    ][21936] Starting a new job with PID 21936
2019-05-17 03:02:03,584 [salt.minion      :1711][INFO    ][21936] Returning information for job: 20190517030203509626
2019-05-17 03:02:33,667 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030233652830
2019-05-17 03:02:33,685 [salt.minion      :1432][INFO    ][21983] Starting a new job with PID 21983
2019-05-17 03:02:33,716 [salt.minion      :1711][INFO    ][21983] Returning information for job: 20190517030233652830
2019-05-17 03:02:35,572 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (795.284373999s left)
2019-05-17 03:03:03,827 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030303811330
2019-05-17 03:03:03,859 [salt.minion      :1432][INFO    ][22004] Starting a new job with PID 22004
2019-05-17 03:03:03,894 [salt.minion      :1711][INFO    ][22004] Returning information for job: 20190517030303811330
2019-05-17 03:03:08,458 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (762.398294926s left)
2019-05-17 03:03:34,040 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030334024450
2019-05-17 03:03:34,065 [salt.minion      :1432][INFO    ][22055] Starting a new job with PID 22055
2019-05-17 03:03:34,100 [salt.minion      :1711][INFO    ][22055] Returning information for job: 20190517030334024450
2019-05-17 03:03:41,371 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (729.485087872s left)
2019-05-17 03:04:04,232 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030404216398
2019-05-17 03:04:04,258 [salt.minion      :1432][INFO    ][22081] Starting a new job with PID 22081
2019-05-17 03:04:04,290 [salt.minion      :1711][INFO    ][22081] Returning information for job: 20190517030404216398
2019-05-17 03:04:14,070 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (696.786180019s left)
2019-05-17 03:04:34,464 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030434449793
2019-05-17 03:04:34,495 [salt.minion      :1432][INFO    ][22137] Starting a new job with PID 22137
2019-05-17 03:04:34,526 [salt.minion      :1711][INFO    ][22137] Returning information for job: 20190517030434449793
2019-05-17 03:04:47,084 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (663.771801949s left)
2019-05-17 03:05:04,513 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030504498390
2019-05-17 03:05:04,543 [salt.minion      :1432][INFO    ][22165] Starting a new job with PID 22165
2019-05-17 03:05:04,572 [salt.minion      :1711][INFO    ][22165] Returning information for job: 20190517030504498390
2019-05-17 03:05:20,136 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (630.72022891s left)
2019-05-17 03:05:34,571 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030534556572
2019-05-17 03:05:34,592 [salt.minion      :1432][INFO    ][22216] Starting a new job with PID 22216
2019-05-17 03:05:34,625 [salt.minion      :1711][INFO    ][22216] Returning information for job: 20190517030534556572
2019-05-17 03:05:52,919 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (597.937327862s left)
2019-05-17 03:06:04,674 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030604661752
2019-05-17 03:06:04,698 [salt.minion      :1432][INFO    ][22240] Starting a new job with PID 22240
2019-05-17 03:06:04,732 [salt.minion      :1711][INFO    ][22240] Returning information for job: 20190517030604661752
2019-05-17 03:06:25,618 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (565.238067865s left)
2019-05-17 03:06:34,735 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030634721358
2019-05-17 03:06:34,752 [salt.minion      :1432][INFO    ][22290] Starting a new job with PID 22290
2019-05-17 03:06:34,784 [salt.minion      :1711][INFO    ][22290] Returning information for job: 20190517030634721358
2019-05-17 03:06:58,364 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (532.492238998s left)
2019-05-17 03:07:04,865 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030704851475
2019-05-17 03:07:04,885 [salt.minion      :1432][INFO    ][22316] Starting a new job with PID 22316
2019-05-17 03:07:04,918 [salt.minion      :1711][INFO    ][22316] Returning information for job: 20190517030704851475
2019-05-17 03:07:12,285 [salt.utils.schedule:1377][INFO    ][3147] Running scheduled job: __mine_interval
2019-05-17 03:07:31,207 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (499.649159908s left)
2019-05-17 03:07:35,020 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030735006814
2019-05-17 03:07:35,040 [salt.minion      :1432][INFO    ][22670] Starting a new job with PID 22670
2019-05-17 03:07:35,073 [salt.minion      :1711][INFO    ][22670] Returning information for job: 20190517030735006814
2019-05-17 03:08:04,199 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (466.657178879s left)
2019-05-17 03:08:05,144 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030805134672
2019-05-17 03:08:05,171 [salt.minion      :1432][INFO    ][22697] Starting a new job with PID 22697
2019-05-17 03:08:05,208 [salt.minion      :1711][INFO    ][22697] Returning information for job: 20190517030805134672
2019-05-17 03:08:35,340 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030835328667
2019-05-17 03:08:35,364 [salt.minion      :1432][INFO    ][22746] Starting a new job with PID 22746
2019-05-17 03:08:35,398 [salt.minion      :1711][INFO    ][22746] Returning information for job: 20190517030835328667
2019-05-17 03:08:37,098 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (433.757992983s left)
2019-05-17 03:09:05,360 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030905345754
2019-05-17 03:09:05,388 [salt.minion      :1432][INFO    ][22771] Starting a new job with PID 22771
2019-05-17 03:09:05,416 [salt.minion      :1711][INFO    ][22771] Returning information for job: 20190517030905345754
2019-05-17 03:09:09,881 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (400.975136042s left)
2019-05-17 03:09:35,396 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517030935387108
2019-05-17 03:09:35,422 [salt.minion      :1432][INFO    ][22824] Starting a new job with PID 22824
2019-05-17 03:09:35,455 [salt.minion      :1711][INFO    ][22824] Returning information for job: 20190517030935387108
2019-05-17 03:09:42,639 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (368.216670036s left)
2019-05-17 03:10:05,468 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031005455796
2019-05-17 03:10:05,496 [salt.minion      :1432][INFO    ][22851] Starting a new job with PID 22851
2019-05-17 03:10:05,525 [salt.minion      :1711][INFO    ][22851] Returning information for job: 20190517031005455796
2019-05-17 03:10:15,427 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (335.428632021s left)
2019-05-17 03:10:35,516 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031035505062
2019-05-17 03:10:35,545 [salt.minion      :1432][INFO    ][22903] Starting a new job with PID 22903
2019-05-17 03:10:35,573 [salt.minion      :1711][INFO    ][22903] Returning information for job: 20190517031035505062
2019-05-17 03:10:48,214 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (302.641839981s left)
2019-05-17 03:11:05,757 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031105735910
2019-05-17 03:11:05,783 [salt.minion      :1432][INFO    ][22943] Starting a new job with PID 22943
2019-05-17 03:11:05,823 [salt.minion      :1711][INFO    ][22943] Returning information for job: 20190517031105735910
2019-05-17 03:11:21,513 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (269.343417883s left)
2019-05-17 03:11:35,884 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031135871298
2019-05-17 03:11:35,905 [salt.minion      :1432][INFO    ][22990] Starting a new job with PID 22990
2019-05-17 03:11:35,939 [salt.minion      :1711][INFO    ][22990] Returning information for job: 20190517031135871298
2019-05-17 03:11:54,316 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (236.539675951s left)
2019-05-17 03:12:05,994 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031205979551
2019-05-17 03:12:06,022 [salt.minion      :1432][INFO    ][23021] Starting a new job with PID 23021
2019-05-17 03:12:06,051 [salt.minion      :1711][INFO    ][23021] Returning information for job: 20190517031205979551
2019-05-17 03:12:27,029 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (203.827231884s left)
2019-05-17 03:12:36,104 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031236089082
2019-05-17 03:12:36,130 [salt.minion      :1432][INFO    ][23064] Starting a new job with PID 23064
2019-05-17 03:12:36,161 [salt.minion      :1711][INFO    ][23064] Returning information for job: 20190517031236089082
2019-05-17 03:12:59,953 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (170.903535843s left)
2019-05-17 03:13:06,287 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031306273962
2019-05-17 03:13:06,308 [salt.minion      :1432][INFO    ][23097] Starting a new job with PID 23097
2019-05-17 03:13:06,337 [salt.minion      :1711][INFO    ][23097] Returning information for job: 20190517031306273962
2019-05-17 03:13:32,781 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (138.075404882s left)
2019-05-17 03:13:36,432 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031336420195
2019-05-17 03:13:36,456 [salt.minion      :1432][INFO    ][23141] Starting a new job with PID 23141
2019-05-17 03:13:36,489 [salt.minion      :1711][INFO    ][23141] Returning information for job: 20190517031336420195
2019-05-17 03:14:05,682 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (105.173547983s left)
2019-05-17 03:14:06,620 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031406607121
2019-05-17 03:14:06,644 [salt.minion      :1432][INFO    ][23175] Starting a new job with PID 23175
2019-05-17 03:14:06,677 [salt.minion      :1711][INFO    ][23175] Returning information for job: 20190517031406607121
2019-05-17 03:14:36,745 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031436735162
2019-05-17 03:14:36,773 [salt.minion      :1432][INFO    ][23217] Starting a new job with PID 23217
2019-05-17 03:14:36,805 [salt.minion      :1711][INFO    ][23217] Returning information for job: 20190517031436735162
2019-05-17 03:14:38,697 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (72.1592848301s left)
2019-05-17 03:15:06,772 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031506760675
2019-05-17 03:15:06,798 [salt.minion      :1432][INFO    ][23248] Starting a new job with PID 23248
2019-05-17 03:15:06,833 [salt.minion      :1711][INFO    ][23248] Returning information for job: 20190517031506760675
2019-05-17 03:15:11,476 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (39.3805088997s left)
2019-05-17 03:15:36,992 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031536980110
2019-05-17 03:15:37,019 [salt.minion      :1432][INFO    ][23293] Starting a new job with PID 23293
2019-05-17 03:15:37,051 [salt.minion      :1711][INFO    ][23293] Returning information for job: 20190517031536980110
2019-05-17 03:15:44,240 [salt.loaded.ext.module.maas:1023][INFO    ][17498] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (6.61595797539s left)
2019-05-17 03:16:07,085 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031607072148
2019-05-17 03:16:07,113 [salt.minion      :1432][INFO    ][23328] Starting a new job with PID 23328
2019-05-17 03:16:07,157 [salt.minion      :1711][INFO    ][23328] Returning information for job: 20190517031607072148
2019-05-17 03:16:17,017 [salt.state       :302 ][ERROR   ][17498] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-17 03:16:17,019 [salt.state       :1951][INFO    ][17498] Completed state [maas.wait_for_machine_status] at time 03:16:17.019402 duration_in_ms=2276179.565
2019-05-17 03:16:17,023 [salt.minion      :1711][INFO    ][17498] Returning information for job: 20190517023812475834
2019-05-17 03:16:27,981 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command pillar.get with jid 20190517031627966129
2019-05-17 03:16:28,005 [salt.minion      :1432][INFO    ][23371] Starting a new job with PID 23371
2019-05-17 03:16:28,015 [salt.minion      :1711][INFO    ][23371] Returning information for job: 20190517031627966129
2019-05-17 03:16:28,688 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command service.status with jid 20190517031628676353
2019-05-17 03:16:28,717 [salt.minion      :1432][INFO    ][23376] Starting a new job with PID 23376
2019-05-17 03:16:29,882 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][23376] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-17 03:16:29,937 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][23376] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-17 03:16:29,958 [salt.minion      :1711][INFO    ][23376] Returning information for job: 20190517031628676353
2019-05-17 03:16:30,647 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command state.apply with jid 20190517031630635016
2019-05-17 03:16:30,684 [salt.minion      :1432][INFO    ][23387] Starting a new job with PID 23387
2019-05-17 03:16:36,945 [salt.state       :915 ][INFO    ][23387] Loading fresh modules for state activity
2019-05-17 03:16:37,596 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command 'salt-minion --version' in directory '/root'
2019-05-17 03:16:38,080 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command 'salt-minion --version' in directory '/root'
2019-05-17 03:16:39,184 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command 'salt-minion --version' in directory '/root'
2019-05-17 03:16:39,525 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command 'salt-minion --version' in directory '/root'
2019-05-17 03:16:41,955 [salt.state       :1780][INFO    ][23387] Running state [salt-minion] at time 03:16:41.955604
2019-05-17 03:16:41,956 [salt.state       :1813][INFO    ][23387] Executing state pkg.installed for [salt-minion]
2019-05-17 03:16:41,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-17 03:16:42,098 [salt.state       :300 ][INFO    ][23387] All specified packages are already installed
2019-05-17 03:16:42,099 [salt.state       :1951][INFO    ][23387] Completed state [salt-minion] at time 03:16:42.098922 duration_in_ms=143.319
2019-05-17 03:16:42,099 [salt.state       :1780][INFO    ][23387] Running state [salt_minion_dependency_packages] at time 03:16:42.099289
2019-05-17 03:16:42,099 [salt.state       :1813][INFO    ][23387] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-17 03:16:42,108 [salt.state       :300 ][INFO    ][23387] All specified packages are already installed
2019-05-17 03:16:42,108 [salt.state       :1951][INFO    ][23387] Completed state [salt_minion_dependency_packages] at time 03:16:42.108874 duration_in_ms=9.585
2019-05-17 03:16:42,116 [salt.state       :1780][INFO    ][23387] Running state [/etc/salt/minion.d/minion.conf] at time 03:16:42.116724
2019-05-17 03:16:42,117 [salt.state       :1813][INFO    ][23387] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-17 03:16:42,413 [salt.state       :300 ][INFO    ][23387] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-17 03:16:42,414 [salt.state       :1951][INFO    ][23387] Completed state [/etc/salt/minion.d/minion.conf] at time 03:16:42.414383 duration_in_ms=297.66
2019-05-17 03:16:42,414 [salt.state       :1780][INFO    ][23387] Running state [python-netaddr] at time 03:16:42.414715
2019-05-17 03:16:42,415 [salt.state       :1813][INFO    ][23387] Executing state pkg.installed for [python-netaddr]
2019-05-17 03:16:42,424 [salt.state       :300 ][INFO    ][23387] All specified packages are already installed
2019-05-17 03:16:42,424 [salt.state       :1951][INFO    ][23387] Completed state [python-netaddr] at time 03:16:42.424272 duration_in_ms=9.556
2019-05-17 03:16:42,428 [salt.state       :1780][INFO    ][23387] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:16:42.428767
2019-05-17 03:16:42,429 [salt.state       :1813][INFO    ][23387] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-17 03:16:42,453 [salt.state       :300 ][INFO    ][23387] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-17 03:16:42,453 [salt.state       :1951][INFO    ][23387] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:16:42.453476 duration_in_ms=24.708
2019-05-17 03:16:42,455 [salt.state       :1780][INFO    ][23387] Running state [salt-minion] at time 03:16:42.455613
2019-05-17 03:16:42,456 [salt.state       :1813][INFO    ][23387] Executing state service.running for [salt-minion]
2019-05-17 03:16:42,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-17 03:16:42,505 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-17 03:16:42,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-17 03:16:42,548 [salt.state       :300 ][INFO    ][23387] The service salt-minion is already running
2019-05-17 03:16:42,548 [salt.state       :1951][INFO    ][23387] Completed state [salt-minion] at time 03:16:42.548582 duration_in_ms=92.968
2019-05-17 03:16:42,551 [salt.state       :1780][INFO    ][23387] Running state [/etc/salt/grains.d] at time 03:16:42.551350
2019-05-17 03:16:42,551 [salt.state       :1813][INFO    ][23387] Executing state file.directory for [/etc/salt/grains.d]
2019-05-17 03:16:42,554 [salt.state       :300 ][INFO    ][23387] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-17 03:16:42,555 [salt.state       :1951][INFO    ][23387] Completed state [/etc/salt/grains.d] at time 03:16:42.555261 duration_in_ms=3.911
2019-05-17 03:16:42,556 [salt.state       :1780][INFO    ][23387] Running state [/etc/salt/grains] at time 03:16:42.556669
2019-05-17 03:16:42,557 [salt.state       :1813][INFO    ][23387] Executing state file.managed for [/etc/salt/grains]
2019-05-17 03:16:42,559 [salt.state       :300 ][INFO    ][23387] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-17 03:16:42,560 [salt.state       :1951][INFO    ][23387] Completed state [/etc/salt/grains] at time 03:16:42.560376 duration_in_ms=3.707
2019-05-17 03:16:42,561 [salt.state       :1780][INFO    ][23387] Running state [/etc/salt/grains.d/placeholder] at time 03:16:42.561294
2019-05-17 03:16:42,562 [salt.state       :1813][INFO    ][23387] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-17 03:16:42,563 [salt.state       :300 ][INFO    ][23387] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-17 03:16:42,564 [salt.state       :1951][INFO    ][23387] Completed state [/etc/salt/grains.d/placeholder] at time 03:16:42.564045 duration_in_ms=2.752
2019-05-17 03:16:42,565 [salt.state       :1780][INFO    ][23387] Running state [/etc/salt/grains.d/sphinx] at time 03:16:42.564960
2019-05-17 03:16:42,565 [salt.state       :1813][INFO    ][23387] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-17 03:16:42,568 [salt.state       :300 ][INFO    ][23387] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-17 03:16:42,569 [salt.state       :1951][INFO    ][23387] Completed state [/etc/salt/grains.d/sphinx] at time 03:16:42.569208 duration_in_ms=4.248
2019-05-17 03:16:42,575 [salt.state       :1780][INFO    ][23387] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:16:42.575147
2019-05-17 03:16:42,575 [salt.state       :1813][INFO    ][23387] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-17 03:16:42,576 [salt.state       :300 ][INFO    ][23387] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-17 03:16:42,576 [salt.state       :1951][INFO    ][23387] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:16:42.576441 duration_in_ms=1.294
2019-05-17 03:16:42,577 [salt.state       :1780][INFO    ][23387] Running state [/etc/salt/grains.d/dns_records] at time 03:16:42.577186
2019-05-17 03:16:42,577 [salt.state       :1813][INFO    ][23387] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-17 03:16:42,580 [salt.state       :300 ][INFO    ][23387] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-17 03:16:42,581 [salt.state       :1951][INFO    ][23387] Completed state [/etc/salt/grains.d/dns_records] at time 03:16:42.580968 duration_in_ms=3.782
2019-05-17 03:16:42,582 [salt.state       :1780][INFO    ][23387] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:16:42.582712
2019-05-17 03:16:42,583 [salt.state       :1813][INFO    ][23387] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-17 03:16:42,583 [salt.state       :300 ][INFO    ][23387] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-17 03:16:42,584 [salt.state       :1951][INFO    ][23387] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:16:42.584013 duration_in_ms=1.301
2019-05-17 03:16:42,584 [salt.state       :1780][INFO    ][23387] Running state [/etc/salt/grains.d/salt] at time 03:16:42.584806
2019-05-17 03:16:42,585 [salt.state       :1813][INFO    ][23387] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-17 03:16:42,587 [salt.state       :300 ][INFO    ][23387] File /etc/salt/grains.d/salt is in the correct state
2019-05-17 03:16:42,587 [salt.state       :1951][INFO    ][23387] Completed state [/etc/salt/grains.d/salt] at time 03:16:42.587422 duration_in_ms=2.616
2019-05-17 03:16:42,588 [salt.state       :1780][INFO    ][23387] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:16:42.588353
2019-05-17 03:16:42,588 [salt.state       :1813][INFO    ][23387] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-17 03:16:42,589 [salt.state       :300 ][INFO    ][23387] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-17 03:16:42,589 [salt.state       :1951][INFO    ][23387] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:16:42.589192 duration_in_ms=0.839
2019-05-17 03:16:42,592 [salt.state       :1780][INFO    ][23387] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:16:42.592757
2019-05-17 03:16:42,593 [salt.state       :1813][INFO    ][23387] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-17 03:16:42,593 [salt.state       :300 ][INFO    ][23387] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-17 03:16:42,594 [salt.state       :1951][INFO    ][23387] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:16:42.594000 duration_in_ms=1.245
2019-05-17 03:16:42,594 [salt.state       :1780][INFO    ][23387] Running state [mine.update] at time 03:16:42.594862
2019-05-17 03:16:42,595 [salt.state       :1813][INFO    ][23387] Executing state module.wait for [mine.update]
2019-05-17 03:16:42,595 [salt.state       :300 ][INFO    ][23387] No changes made for mine.update
2019-05-17 03:16:42,595 [salt.state       :1951][INFO    ][23387] Completed state [mine.update] at time 03:16:42.595806 duration_in_ms=0.945
2019-05-17 03:16:42,596 [salt.state       :1780][INFO    ][23387] Running state [ca-certificates] at time 03:16:42.596137
2019-05-17 03:16:42,596 [salt.state       :1813][INFO    ][23387] Executing state pkg.installed for [ca-certificates]
2019-05-17 03:16:42,607 [salt.state       :300 ][INFO    ][23387] All specified packages are already installed
2019-05-17 03:16:42,607 [salt.state       :1951][INFO    ][23387] Completed state [ca-certificates] at time 03:16:42.607682 duration_in_ms=11.545
2019-05-17 03:16:42,608 [salt.state       :1780][INFO    ][23387] Running state [update-ca-certificates] at time 03:16:42.608414
2019-05-17 03:16:42,608 [salt.state       :1813][INFO    ][23387] Executing state cmd.wait for [update-ca-certificates]
2019-05-17 03:16:42,609 [salt.state       :300 ][INFO    ][23387] No changes made for update-ca-certificates
2019-05-17 03:16:42,609 [salt.state       :1951][INFO    ][23387] Completed state [update-ca-certificates] at time 03:16:42.609223 duration_in_ms=0.809
2019-05-17 03:16:42,609 [salt.state       :1780][INFO    ][23387] Running state [iptables] at time 03:16:42.609495
2019-05-17 03:16:42,610 [salt.state       :1813][INFO    ][23387] Executing state pkg.installed for [iptables]
2019-05-17 03:16:42,619 [salt.state       :300 ][INFO    ][23387] All specified packages are already installed
2019-05-17 03:16:42,620 [salt.state       :1951][INFO    ][23387] Completed state [iptables] at time 03:16:42.620046 duration_in_ms=10.551
2019-05-17 03:16:42,620 [salt.state       :1780][INFO    ][23387] Running state [iptables-persistent] at time 03:16:42.620327
2019-05-17 03:16:42,620 [salt.state       :1813][INFO    ][23387] Executing state pkg.installed for [iptables-persistent]
2019-05-17 03:16:42,630 [salt.state       :300 ][INFO    ][23387] All specified packages are already installed
2019-05-17 03:16:42,630 [salt.state       :1951][INFO    ][23387] Completed state [iptables-persistent] at time 03:16:42.630392 duration_in_ms=10.065
2019-05-17 03:16:42,631 [salt.state       :1780][INFO    ][23387] Running state [iptables_modules_v4_load] at time 03:16:42.631680
2019-05-17 03:16:42,631 [salt.state       :1813][INFO    ][23387] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-17 03:16:42,632 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command 'lsmod' in directory '/root'
2019-05-17 03:16:42,658 [salt.state       :300 ][INFO    ][23387] Kernel modules iptable_filter, ip_tables are already present
2019-05-17 03:16:42,659 [salt.state       :1951][INFO    ][23387] Completed state [iptables_modules_v4_load] at time 03:16:42.659418 duration_in_ms=27.737
2019-05-17 03:16:42,660 [salt.state       :1780][INFO    ][23387] Running state [/etc/iptables/rules.v4] at time 03:16:42.660733
2019-05-17 03:16:42,661 [salt.state       :1813][INFO    ][23387] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-17 03:16:42,784 [salt.state       :300 ][INFO    ][23387] File /etc/iptables/rules.v4 is in the correct state
2019-05-17 03:16:42,784 [salt.state       :1951][INFO    ][23387] Completed state [/etc/iptables/rules.v4] at time 03:16:42.784462 duration_in_ms=123.729
2019-05-17 03:16:42,786 [salt.state       :1780][INFO    ][23387] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:16:42.786069
2019-05-17 03:16:42,786 [salt.state       :1813][INFO    ][23387] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-17 03:16:42,787 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-17 03:16:42,811 [salt.state       :300 ][INFO    ][23387] onlyif execution failed
2019-05-17 03:16:42,812 [salt.state       :1951][INFO    ][23387] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:16:42.812437 duration_in_ms=26.366
2019-05-17 03:16:42,814 [salt.state       :1780][INFO    ][23387] Running state [netfilter-persistent] at time 03:16:42.814424
2019-05-17 03:16:42,815 [salt.state       :1813][INFO    ][23387] Executing state service.running for [netfilter-persistent]
2019-05-17 03:16:42,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-17 03:16:42,845 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-17 03:16:42,869 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-17 03:16:42,889 [salt.state       :300 ][INFO    ][23387] The service netfilter-persistent is already running
2019-05-17 03:16:42,892 [salt.state       :1951][INFO    ][23387] Completed state [netfilter-persistent] at time 03:16:42.892640 duration_in_ms=78.217
2019-05-17 03:16:42,894 [salt.state       :1780][INFO    ][23387] Running state [iptables_extra.remove_stale_tables] at time 03:16:42.894155
2019-05-17 03:16:42,894 [salt.state       :1813][INFO    ][23387] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-17 03:16:42,895 [salt.state       :300 ][INFO    ][23387] No changes made for iptables_extra.remove_stale_tables
2019-05-17 03:16:42,895 [salt.state       :1951][INFO    ][23387] Completed state [iptables_extra.remove_stale_tables] at time 03:16:42.895701 duration_in_ms=1.546
2019-05-17 03:16:42,896 [salt.state       :1780][INFO    ][23387] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:16:42.896181
2019-05-17 03:16:42,896 [salt.state       :1813][INFO    ][23387] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-17 03:16:42,897 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23387] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-17 03:16:42,918 [salt.state       :300 ][INFO    ][23387] onlyif execution failed
2019-05-17 03:16:42,918 [salt.state       :1951][INFO    ][23387] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:16:42.918738 duration_in_ms=22.557
2019-05-17 03:16:42,920 [salt.state       :1780][INFO    ][23387] Running state [/etc/iptables/rules.v6] at time 03:16:42.920541
2019-05-17 03:16:42,921 [salt.state       :1813][INFO    ][23387] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-17 03:16:42,921 [salt.state       :300 ][INFO    ][23387] File /etc/iptables/rules.v6 is not present
2019-05-17 03:16:42,922 [salt.state       :1951][INFO    ][23387] Completed state [/etc/iptables/rules.v6] at time 03:16:42.922228 duration_in_ms=1.687
2019-05-17 03:16:42,923 [salt.state       :1780][INFO    ][23387] Running state [iptables_extra.flush_all] at time 03:16:42.923473
2019-05-17 03:16:42,924 [salt.state       :1813][INFO    ][23387] Executing state module.wait for [iptables_extra.flush_all]
2019-05-17 03:16:42,924 [salt.state       :300 ][INFO    ][23387] No changes made for iptables_extra.flush_all
2019-05-17 03:16:42,924 [salt.state       :1951][INFO    ][23387] Completed state [iptables_extra.flush_all] at time 03:16:42.924857 duration_in_ms=1.384
2019-05-17 03:16:42,930 [salt.minion      :1711][INFO    ][23387] Returning information for job: 20190517031630635016
2019-05-17 03:16:43,627 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command state.apply with jid 20190517031643612217
2019-05-17 03:16:43,653 [salt.minion      :1432][INFO    ][23467] Starting a new job with PID 23467
2019-05-17 03:16:44,604 [salt.state       :915 ][INFO    ][23467] Loading fresh modules for state activity
2019-05-17 03:16:45,799 [salt.state       :1780][INFO    ][23467] Running state [maas-rack-controller] at time 03:16:45.799700
2019-05-17 03:16:45,800 [salt.state       :1813][INFO    ][23467] Executing state pkg.installed for [maas-rack-controller]
2019-05-17 03:16:45,800 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23467] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-17 03:16:45,919 [salt.state       :300 ][INFO    ][23467] All specified packages are already installed
2019-05-17 03:16:45,919 [salt.state       :1951][INFO    ][23467] Completed state [maas-rack-controller] at time 03:16:45.919693 duration_in_ms=119.994
2019-05-17 03:16:45,920 [salt.state       :1780][INFO    ][23467] Running state [ipmitool] at time 03:16:45.920054
2019-05-17 03:16:45,920 [salt.state       :1813][INFO    ][23467] Executing state pkg.installed for [ipmitool]
2019-05-17 03:16:45,929 [salt.state       :300 ][INFO    ][23467] All specified packages are already installed
2019-05-17 03:16:45,930 [salt.state       :1951][INFO    ][23467] Completed state [ipmitool] at time 03:16:45.930115 duration_in_ms=10.061
2019-05-17 03:16:45,933 [salt.state       :1780][INFO    ][23467] Running state [/etc/maas/rackd.conf] at time 03:16:45.933129
2019-05-17 03:16:45,933 [salt.state       :1813][INFO    ][23467] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-17 03:16:45,934 [salt.state       :300 ][INFO    ][23467] No changes needed to be made
2019-05-17 03:16:45,935 [salt.state       :1951][INFO    ][23467] Completed state [/etc/maas/rackd.conf] at time 03:16:45.935074 duration_in_ms=1.945
2019-05-17 03:16:45,935 [salt.state       :1780][INFO    ][23467] Running state [/etc/maas/rackd.conf] at time 03:16:45.935351
2019-05-17 03:16:45,935 [salt.state       :1813][INFO    ][23467] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-17 03:16:45,935 [salt.loaded.int.states.file:2298][WARNING ][23467] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-17 03:16:45,936 [salt.state       :300 ][INFO    ][23467] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-17 03:16:45,937 [salt.state       :1951][INFO    ][23467] Completed state [/etc/maas/rackd.conf] at time 03:16:45.936970 duration_in_ms=1.619
2019-05-17 03:16:45,939 [salt.state       :1780][INFO    ][23467] Running state [maas-rackd] at time 03:16:45.939491
2019-05-17 03:16:45,939 [salt.state       :1813][INFO    ][23467] Executing state service.running for [maas-rackd]
2019-05-17 03:16:45,940 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23467] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-17 03:16:45,982 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23467] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-17 03:16:46,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23467] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-17 03:16:46,027 [salt.state       :300 ][INFO    ][23467] The service maas-rackd is already running
2019-05-17 03:16:46,028 [salt.state       :1951][INFO    ][23467] Completed state [maas-rackd] at time 03:16:46.028072 duration_in_ms=88.581
2019-05-17 03:16:46,030 [salt.minion      :1711][INFO    ][23467] Returning information for job: 20190517031643612217
2019-05-17 03:16:46,677 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command state.apply with jid 20190517031646666784
2019-05-17 03:16:46,704 [salt.minion      :1432][INFO    ][23490] Starting a new job with PID 23490
2019-05-17 03:16:47,818 [salt.state       :915 ][INFO    ][23490] Loading fresh modules for state activity
2019-05-17 03:16:49,144 [salt.state       :1780][INFO    ][23490] Running state [maas-region-controller] at time 03:16:49.144295
2019-05-17 03:16:49,144 [salt.state       :1813][INFO    ][23490] Executing state pkg.installed for [maas-region-controller]
2019-05-17 03:16:49,145 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23490] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-17 03:16:49,278 [salt.state       :300 ][INFO    ][23490] All specified packages are already installed
2019-05-17 03:16:49,278 [salt.state       :1951][INFO    ][23490] Completed state [maas-region-controller] at time 03:16:49.278860 duration_in_ms=134.566
2019-05-17 03:16:49,279 [salt.state       :1780][INFO    ][23490] Running state [python-oauth] at time 03:16:49.279201
2019-05-17 03:16:49,279 [salt.state       :1813][INFO    ][23490] Executing state pkg.installed for [python-oauth]
2019-05-17 03:16:49,290 [salt.state       :300 ][INFO    ][23490] All specified packages are already installed
2019-05-17 03:16:49,290 [salt.state       :1951][INFO    ][23490] Completed state [python-oauth] at time 03:16:49.290588 duration_in_ms=11.386
2019-05-17 03:16:49,293 [salt.state       :1780][INFO    ][23490] Running state [/etc/maas/regiond.conf] at time 03:16:49.293775
2019-05-17 03:16:49,294 [salt.state       :1813][INFO    ][23490] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-17 03:16:49,303 [salt.state       :300 ][INFO    ][23490] No changes needed to be made
2019-05-17 03:16:49,304 [salt.state       :1951][INFO    ][23490] Completed state [/etc/maas/regiond.conf] at time 03:16:49.304100 duration_in_ms=10.324
2019-05-17 03:16:49,305 [salt.state       :1780][INFO    ][23490] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:16:49.304985
2019-05-17 03:16:49,305 [salt.state       :1813][INFO    ][23490] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-17 03:16:49,380 [salt.state       :300 ][INFO    ][23490] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-17 03:16:49,381 [salt.state       :1951][INFO    ][23490] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:16:49.381125 duration_in_ms=76.14
2019-05-17 03:16:49,382 [salt.state       :1780][INFO    ][23490] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:16:49.381704
2019-05-17 03:16:49,382 [salt.state       :1813][INFO    ][23490] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-17 03:16:49,388 [salt.state       :300 ][INFO    ][23490] No changes needed to be made
2019-05-17 03:16:49,388 [salt.state       :1951][INFO    ][23490] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:16:49.388582 duration_in_ms=6.878
2019-05-17 03:16:49,389 [salt.state       :1780][INFO    ][23490] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:16:49.389081
2019-05-17 03:16:49,389 [salt.state       :1813][INFO    ][23490] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-17 03:16:49,393 [salt.state       :300 ][INFO    ][23490] No changes needed to be made
2019-05-17 03:16:49,394 [salt.state       :1951][INFO    ][23490] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:16:49.393680 duration_in_ms=4.598
2019-05-17 03:16:49,394 [salt.state       :1780][INFO    ][23490] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:16:49.394558
2019-05-17 03:16:49,394 [salt.state       :1813][INFO    ][23490] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-17 03:16:49,427 [salt.state       :300 ][INFO    ][23490] No changes needed to be made
2019-05-17 03:16:49,427 [salt.state       :1951][INFO    ][23490] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:16:49.427704 duration_in_ms=33.145
2019-05-17 03:16:49,428 [salt.state       :1780][INFO    ][23490] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:16:49.428295
2019-05-17 03:16:49,428 [salt.state       :1813][INFO    ][23490] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-17 03:16:49,447 [salt.state       :300 ][INFO    ][23490] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-17 03:16:49,448 [salt.state       :1951][INFO    ][23490] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:16:49.448433 duration_in_ms=20.137
2019-05-17 03:16:49,451 [salt.state       :1780][INFO    ][23490] Running state [a2enmod headers] at time 03:16:49.450932
2019-05-17 03:16:49,451 [salt.state       :1813][INFO    ][23490] Executing state cmd.run for [a2enmod headers]
2019-05-17 03:16:49,452 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23490] Executing command 'a2enmod headers' in directory '/root'
2019-05-17 03:16:49,529 [salt.state       :300 ][INFO    ][23490] {'pid': 23516, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-17 03:16:49,530 [salt.state       :1951][INFO    ][23490] Completed state [a2enmod headers] at time 03:16:49.530323 duration_in_ms=79.391
2019-05-17 03:16:49,531 [salt.state       :1780][INFO    ][23490] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:16:49.530949
2019-05-17 03:16:49,531 [salt.state       :1813][INFO    ][23490] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-17 03:16:49,557 [salt.state       :300 ][INFO    ][23490] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-17 03:16:49,558 [salt.state       :1951][INFO    ][23490] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:16:49.558089 duration_in_ms=27.14
2019-05-17 03:16:49,558 [salt.state       :1780][INFO    ][23490] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:16:49.558828
2019-05-17 03:16:49,559 [salt.state       :1813][INFO    ][23490] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-17 03:16:49,614 [salt.state       :300 ][INFO    ][23490] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-17 03:16:49,614 [salt.state       :1951][INFO    ][23490] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:16:49.614208 duration_in_ms=55.38
2019-05-17 03:16:49,614 [salt.state       :1780][INFO    ][23490] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:16:49.614732
2019-05-17 03:16:49,615 [salt.state       :1813][INFO    ][23490] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-17 03:16:49,688 [salt.state       :300 ][INFO    ][23490] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-17 03:16:49,689 [salt.state       :1951][INFO    ][23490] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:16:49.688974 duration_in_ms=74.24
2019-05-17 03:16:49,689 [salt.state       :1780][INFO    ][23490] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:16:49.689627
2019-05-17 03:16:49,690 [salt.state       :1813][INFO    ][23490] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-17 03:16:49,776 [salt.state       :300 ][INFO    ][23490] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-17 03:16:49,777 [salt.state       :1951][INFO    ][23490] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:16:49.777024 duration_in_ms=87.398
2019-05-17 03:16:49,777 [salt.state       :1780][INFO    ][23490] Running state [/root/.pgpass] at time 03:16:49.777409
2019-05-17 03:16:49,778 [salt.state       :1813][INFO    ][23490] Executing state file.managed for [/root/.pgpass]
2019-05-17 03:16:49,833 [salt.state       :300 ][INFO    ][23490] File /root/.pgpass is in the correct state
2019-05-17 03:16:49,834 [salt.state       :1951][INFO    ][23490] Completed state [/root/.pgpass] at time 03:16:49.833930 duration_in_ms=56.521
2019-05-17 03:16:49,841 [salt.state       :1780][INFO    ][23490] Running state [maas-region syncdb --noinput] at time 03:16:49.841533
2019-05-17 03:16:49,842 [salt.state       :1813][INFO    ][23490] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-17 03:16:49,842 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23490] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-17 03:16:52,614 [salt.state       :300 ][INFO    ][23490] {'pid': 23529, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, piston3, auth, metadataserver, sites, sessions, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-17 03:16:52,615 [salt.state       :1951][INFO    ][23490] Completed state [maas-region syncdb --noinput] at time 03:16:52.615343 duration_in_ms=2773.808
2019-05-17 03:16:52,616 [salt.state       :2022][WARNING ][23490] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-17 03:16:52,619 [salt.state       :1780][INFO    ][23490] Running state [maas-regiond] at time 03:16:52.619742
2019-05-17 03:16:52,620 [salt.state       :1813][INFO    ][23490] Executing state service.running for [maas-regiond]
2019-05-17 03:16:52,623 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23490] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-17 03:16:52,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23490] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-17 03:16:52,688 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23490] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-17 03:16:52,713 [salt.state       :300 ][INFO    ][23490] The service maas-regiond is already running
2019-05-17 03:16:52,713 [salt.state       :1951][INFO    ][23490] Completed state [maas-regiond] at time 03:16:52.713515 duration_in_ms=93.774
2019-05-17 03:16:52,716 [salt.state       :1780][INFO    ][23490] Running state [bind9] at time 03:16:52.716521
2019-05-17 03:16:52,717 [salt.state       :1813][INFO    ][23490] Executing state service.running for [bind9]
2019-05-17 03:16:52,719 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23490] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-17 03:16:52,743 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23490] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-17 03:16:52,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23490] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-17 03:16:52,786 [salt.state       :300 ][INFO    ][23490] The service bind9 is already running
2019-05-17 03:16:52,787 [salt.state       :1951][INFO    ][23490] Completed state [bind9] at time 03:16:52.786969 duration_in_ms=70.447
2019-05-17 03:16:52,792 [salt.state       :1780][INFO    ][23490] Running state [apache2] at time 03:16:52.792312
2019-05-17 03:16:52,792 [salt.state       :1813][INFO    ][23490] Executing state service.running for [apache2]
2019-05-17 03:16:52,793 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23490] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-17 03:16:52,813 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23490] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-17 03:16:52,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23490] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-17 03:16:52,867 [salt.state       :300 ][INFO    ][23490] The service apache2 is already running
2019-05-17 03:16:52,868 [salt.state       :1951][INFO    ][23490] Completed state [apache2] at time 03:16:52.868212 duration_in_ms=75.899
2019-05-17 03:16:52,870 [salt.state       :1780][INFO    ][23490] Running state [maasng.wait_for_http_code] at time 03:16:52.870342
2019-05-17 03:16:52,871 [salt.state       :1813][INFO    ][23490] Executing state module.run for [maasng.wait_for_http_code]
2019-05-17 03:16:52,871 [salt.utils.decorators:613 ][WARNING ][23490] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 03:16:53,002 [salt.state       :300 ][INFO    ][23490] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-17 03:16:53,002 [salt.state       :1951][INFO    ][23490] Completed state [maasng.wait_for_http_code] at time 03:16:53.002663 duration_in_ms=132.321
2019-05-17 03:16:53,004 [salt.state       :1780][INFO    ][23490] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:16:53.003904
2019-05-17 03:16:53,004 [salt.state       :1813][INFO    ][23490] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-17 03:16:53,005 [salt.state       :300 ][INFO    ][23490] /var/lib/maas/.setup_admin exists
2019-05-17 03:16:53,005 [salt.state       :1951][INFO    ][23490] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:16:53.005451 duration_in_ms=1.547
2019-05-17 03:16:53,008 [salt.state       :1780][INFO    ][23490] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:16:53.008242
2019-05-17 03:16:53,008 [salt.state       :1813][INFO    ][23490] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 03:16:53,009 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23490] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 03:16:54,815 [salt.state       :300 ][INFO    ][23490] {'pid': 23553, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 03:16:54,816 [salt.state       :1951][INFO    ][23490] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:16:54.816547 duration_in_ms=1808.305
2019-05-17 03:16:54,825 [salt.state       :1780][INFO    ][23490] Running state [maas_region_boot_source_resources_mirror] at time 03:16:54.825842
2019-05-17 03:16:54,826 [salt.state       :1813][INFO    ][23490] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-17 03:16:54,920 [salt.state       :300 ][INFO    ][23490] {'changes': {}}
2019-05-17 03:16:54,920 [salt.state       :1951][INFO    ][23490] Completed state [maas_region_boot_source_resources_mirror] at time 03:16:54.920823 duration_in_ms=94.98
2019-05-17 03:16:54,922 [salt.state       :1780][INFO    ][23490] Running state [maasng.boot_resources_import] at time 03:16:54.922004
2019-05-17 03:16:54,922 [salt.state       :1813][INFO    ][23490] Executing state module.run for [maasng.boot_resources_import]
2019-05-17 03:16:54,923 [salt.utils.decorators:613 ][WARNING ][23490] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 03:16:56,428 [salt.loaded.ext.module.maasng:1600][INFO    ][23490] Waiting boot-resources import done
sleep for:5s Left:899.0/900s
2019-05-17 03:17:01,482 [salt.loaded.ext.module.maasng:1600][INFO    ][23490] Waiting boot-resources import done
sleep for:5s Left:893.0/900s
2019-05-17 03:17:01,774 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031701764439
2019-05-17 03:17:01,804 [salt.minion      :1432][INFO    ][23583] Starting a new job with PID 23583
2019-05-17 03:17:01,832 [salt.minion      :1711][INFO    ][23583] Returning information for job: 20190517031701764439
2019-05-17 03:17:06,580 [salt.state       :300 ][INFO    ][23490] {'ret': True}
2019-05-17 03:17:06,580 [salt.state       :1951][INFO    ][23490] Completed state [maasng.boot_resources_import] at time 03:17:06.580735 duration_in_ms=11658.73
2019-05-17 03:17:06,582 [salt.state       :1780][INFO    ][23490] Running state [maas_region_boot_sources_selection_xenial] at time 03:17:06.582261
2019-05-17 03:17:06,582 [salt.state       :1813][INFO    ][23490] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-17 03:17:06,767 [salt.state       :300 ][INFO    ][23490] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-17 03:17:06,767 [salt.state       :1951][INFO    ][23490] Completed state [maas_region_boot_sources_selection_xenial] at time 03:17:06.767818 duration_in_ms=185.555
2019-05-17 03:17:06,771 [salt.state       :1780][INFO    ][23490] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 03:17:06.769664
2019-05-17 03:17:06,772 [salt.state       :1813][INFO    ][23490] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-17 03:17:06,772 [salt.utils.decorators:613 ][WARNING ][23490] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 03:17:06,773 [salt.loaded.ext.module.maasng:1771][INFO    ][23490] boot-sources sync initiated for ALL Rack's
2019-05-17 03:17:07,813 [salt.state       :300 ][INFO    ][23490] {'ret': True}
2019-05-17 03:17:07,814 [salt.state       :1951][INFO    ][23490] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 03:17:07.813658 duration_in_ms=1043.994
2019-05-17 03:17:07,816 [salt.state       :1780][INFO    ][23490] Running state [maas.process_maas_config] at time 03:17:07.816561
2019-05-17 03:17:07,817 [salt.state       :1813][INFO    ][23490] Executing state module.run for [maas.process_maas_config]
2019-05-17 03:17:07,817 [salt.utils.decorators:613 ][WARNING ][23490] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 03:17:07,819 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=enable_http_proxy value=True
2019-05-17 03:17:07,879 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=upstream_dns value=8.8.8.8
2019-05-17 03:17:07,941 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=commissioning_distro_series value=xenial
2019-05-17 03:17:08,024 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=default_osystem value=ubuntu
2019-05-17 03:17:08,084 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=active_discovery_interval value=600
2019-05-17 03:17:09,432 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=dnssec_validation value=no
2019-05-17 03:17:09,485 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=maas_name value=mas01
2019-05-17 03:17:09,537 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=network_discovery value=enabled
2019-05-17 03:17:09,664 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=enable_third_party_drivers value=True
2019-05-17 03:17:09,714 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=default_storage_layout value=lvm
2019-05-17 03:17:09,763 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=ntp_external_only value=True
2019-05-17 03:17:09,826 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-17 03:17:09,898 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=default_distro_series value=xenial
2019-05-17 03:17:09,958 [salt.loaded.ext.module.maas:92  ][INFO    ][23490] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-05-17 03:17:10,089 [salt.state       :300 ][INFO    ][23490] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-17 03:17:10,090 [salt.state       :1951][INFO    ][23490] Completed state [maas.process_maas_config] at time 03:17:10.090347 duration_in_ms=2273.775
2019-05-17 03:17:10,091 [salt.state       :1780][INFO    ][23490] Running state [pxe_admin] at time 03:17:10.091655
2019-05-17 03:17:10,092 [salt.state       :1813][INFO    ][23490] Executing state maasng.fabric_present for [pxe_admin]
2019-05-17 03:17:10,149 [salt.loaded.ext.module.maasng:945 ][INFO    ][23490] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dwrap4', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-17 03:17:10,210 [salt.loaded.ext.module.maasng:1008][WARNING ][23490] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-17 03:17:10,211 [salt.loaded.ext.module.maasng:1011][WARNING ][23490] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-17 03:17:10,273 [salt.state       :300 ][INFO    ][23490] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-17 03:17:10,274 [salt.state       :1951][INFO    ][23490] Completed state [pxe_admin] at time 03:17:10.273619 duration_in_ms=181.964
2019-05-17 03:17:10,274 [salt.state       :1780][INFO    ][23490] Running state [vlan 0] at time 03:17:10.274814
2019-05-17 03:17:10,275 [salt.state       :1813][INFO    ][23490] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-17 03:17:10,329 [salt.loaded.ext.module.maasng:945 ][INFO    ][23490] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-17 03:17:10,447 [salt.loaded.ext.module.maasng:945 ][INFO    ][23490] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-17 03:17:10,756 [salt.loaded.ext.module.maasng:945 ][INFO    ][23490] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-17 03:17:10,855 [salt.state       :300 ][INFO    ][23490] {'new': 'Vlan untagged was updated'}
2019-05-17 03:17:10,855 [salt.state       :1951][INFO    ][23490] Completed state [vlan 0] at time 03:17:10.855403 duration_in_ms=580.59
2019-05-17 03:17:10,856 [salt.state       :1780][INFO    ][23490] Running state [192.168.11.0/24] at time 03:17:10.856843
2019-05-17 03:17:10,857 [salt.state       :1813][INFO    ][23490] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-17 03:17:11,036 [salt.loaded.ext.module.maasng:945 ][INFO    ][23490] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dwrap4', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-17 03:17:11,037 [salt.loaded.ext.module.maasng:1235][WARNING ][23490] Ignoring parameter vlan:0
2019-05-17 03:17:11,116 [salt.state       :300 ][INFO    ][23490] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-17 03:17:11,117 [salt.state       :1951][INFO    ][23490] Completed state [192.168.11.0/24] at time 03:17:11.117082 duration_in_ms=260.239
2019-05-17 03:17:11,119 [salt.state       :1780][INFO    ][23490] Running state [maas_create_iprange_1] at time 03:17:11.119761
2019-05-17 03:17:11,120 [salt.state       :1813][INFO    ][23490] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-17 03:17:11,178 [salt.state       :300 ][INFO    ][23490] Iprange maas_create_iprange_1 already exist.
2019-05-17 03:17:11,179 [salt.state       :1951][INFO    ][23490] Completed state [maas_create_iprange_1] at time 03:17:11.179038 duration_in_ms=59.277
2019-05-17 03:17:11,179 [salt.state       :1780][INFO    ][23490] Running state [vlan 0] at time 03:17:11.179500
2019-05-17 03:17:11,180 [salt.state       :1813][INFO    ][23490] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-17 03:17:11,235 [salt.loaded.ext.module.maasng:945 ][INFO    ][23490] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-17 03:17:11,339 [salt.loaded.ext.module.maasng:945 ][INFO    ][23490] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-17 03:17:11,603 [salt.loaded.ext.module.maasng:945 ][INFO    ][23490] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dwrap4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-05-17 03:17:11,707 [salt.state       :300 ][INFO    ][23490] {'new': 'Vlan untagged was updated'}
2019-05-17 03:17:11,708 [salt.state       :1951][INFO    ][23490] Completed state [vlan 0] at time 03:17:11.708249 duration_in_ms=528.749
2019-05-17 03:17:11,709 [salt.state       :1780][INFO    ][23490] Running state [opnfv] at time 03:17:11.708970
2019-05-17 03:17:11,709 [salt.state       :1813][INFO    ][23490] Executing state maasng.sshkey_present for [opnfv]
2019-05-17 03:17:11,760 [salt.loaded.ext.module.maasng:1903][INFO    ][23490] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-05-17 03:17:11,760 [salt.state       :300 ][INFO    ][23490] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-17 03:17:11,760 [salt.state       :1951][INFO    ][23490] Completed state [opnfv] at time 03:17:11.760813 duration_in_ms=51.843
2019-05-17 03:17:11,766 [salt.minion      :1711][INFO    ][23490] Returning information for job: 20190517031646666784
2019-05-17 03:17:12,512 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command state.apply with jid 20190517031712499124
2019-05-17 03:17:12,533 [salt.minion      :1432][INFO    ][23954] Starting a new job with PID 23954
2019-05-17 03:17:18,536 [salt.state       :915 ][INFO    ][23954] Loading fresh modules for state activity
2019-05-17 03:17:18,659 [salt.state       :1780][INFO    ][23954] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:17:18.659219
2019-05-17 03:17:18,659 [salt.state       :1813][INFO    ][23954] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 03:17:18,661 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23954] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 03:17:20,680 [salt.state       :300 ][INFO    ][23954] {'pid': 23982, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 03:17:20,681 [salt.state       :1951][INFO    ][23954] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:17:20.681142 duration_in_ms=2021.922
2019-05-17 03:17:20,684 [salt.state       :1780][INFO    ][23954] Running state [maas.process_machines] at time 03:17:20.684733
2019-05-17 03:17:20,685 [salt.state       :1813][INFO    ][23954] Executing state module.run for [maas.process_machines]
2019-05-17 03:17:20,686 [salt.utils.decorators:613 ][WARNING ][23954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 03:17:21,263 [salt.loaded.ext.module.maas:412 ][WARNING ][23954] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 03:17:21,265 [salt.loaded.ext.module.maas:92  ][INFO    ][23954] 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 system_id=w887nb architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-17 03:17:22,567 [salt.loaded.ext.module.maas:412 ][WARNING ][23954] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 03:17:22,569 [salt.loaded.ext.module.maas:92  ][INFO    ][23954] 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 system_id=6x3hw7 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-17 03:17:23,817 [salt.loaded.ext.module.maas:412 ][WARNING ][23954] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 03:17:23,818 [salt.loaded.ext.module.maas:92  ][INFO    ][23954] 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 system_id=4fdxxx architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-17 03:17:25,054 [salt.loaded.ext.module.maas:412 ][WARNING ][23954] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 03:17:25,055 [salt.loaded.ext.module.maas:92  ][INFO    ][23954] 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 system_id=r4fftm architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-17 03:17:26,296 [salt.loaded.ext.module.maas:412 ][WARNING ][23954] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 03:17:26,297 [salt.loaded.ext.module.maas:92  ][INFO    ][23954] 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 system_id=n6srab architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-17 03:17:27,496 [salt.state       :300 ][INFO    ][23954] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-17 03:17:27,496 [salt.state       :1951][INFO    ][23954] Completed state [maas.process_machines] at time 03:17:27.496702 duration_in_ms=6811.966
2019-05-17 03:17:27,503 [salt.minion      :1711][INFO    ][23954] Returning information for job: 20190517031712499124
2019-05-17 03:18:01,223 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command state.apply with jid 20190517031801209283
2019-05-17 03:18:01,253 [salt.minion      :1432][INFO    ][24251] Starting a new job with PID 24251
2019-05-17 03:18:07,266 [salt.state       :915 ][INFO    ][24251] Loading fresh modules for state activity
2019-05-17 03:18:07,392 [salt.state       :1780][INFO    ][24251] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:18:07.391902
2019-05-17 03:18:07,392 [salt.state       :1813][INFO    ][24251] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 03:18:07,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24251] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 03:18:09,258 [salt.state       :300 ][INFO    ][24251] {'pid': 24271, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 03:18:09,259 [salt.state       :1951][INFO    ][24251] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:18:09.259410 duration_in_ms=1867.508
2019-05-17 03:18:09,262 [salt.state       :1780][INFO    ][24251] Running state [maas.wait_for_machine_status] at time 03:18:09.262615
2019-05-17 03:18:09,263 [salt.state       :1813][INFO    ][24251] Executing state module.run for [maas.wait_for_machine_status]
2019-05-17 03:18:09,264 [salt.utils.decorators:613 ][WARNING ][24251] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 03:18:12,246 [salt.state       :300 ][INFO    ][24251] {'ret': True}
2019-05-17 03:18:12,247 [salt.state       :1951][INFO    ][24251] Completed state [maas.wait_for_machine_status] at time 03:18:12.247384 duration_in_ms=2984.766
2019-05-17 03:18:12,252 [salt.minion      :1711][INFO    ][24251] Returning information for job: 20190517031801209283
2019-05-17 03:18:12,936 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command state.apply with jid 20190517031812924479
2019-05-17 03:18:12,964 [salt.minion      :1432][INFO    ][24285] Starting a new job with PID 24285
2019-05-17 03:18:13,948 [salt.state       :915 ][INFO    ][24285] Loading fresh modules for state activity
2019-05-17 03:18:14,110 [salt.state       :1780][INFO    ][24285] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:18:14.110174
2019-05-17 03:18:14,110 [salt.state       :1813][INFO    ][24285] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 03:18:14,112 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24285] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 03:18:16,037 [salt.state       :300 ][INFO    ][24285] {'pid': 24292, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 03:18:16,038 [salt.state       :1951][INFO    ][24285] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:18:16.038290 duration_in_ms=1928.117
2019-05-17 03:18:16,040 [salt.state       :1780][INFO    ][24285] Running state [maas_machines_storage_cmp002_lvm] at time 03:18:16.040450
2019-05-17 03:18:16,040 [salt.state       :1813][INFO    ][24285] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-17 03:18:16,770 [salt.state       :300 ][INFO    ][24285] Machine cmp002 is not in Ready state.
2019-05-17 03:18:16,771 [salt.state       :1951][INFO    ][24285] Completed state [maas_machines_storage_cmp002_lvm] at time 03:18:16.771679 duration_in_ms=731.227
2019-05-17 03:18:16,772 [salt.state       :1780][INFO    ][24285] Running state [maas_machines_storage_cmp001_lvm] at time 03:18:16.772589
2019-05-17 03:18:16,773 [salt.state       :1813][INFO    ][24285] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-17 03:18:17,298 [salt.state       :300 ][INFO    ][24285] Machine cmp001 is not in Ready state.
2019-05-17 03:18:17,298 [salt.state       :1951][INFO    ][24285] Completed state [maas_machines_storage_cmp001_lvm] at time 03:18:17.298574 duration_in_ms=525.985
2019-05-17 03:18:17,303 [salt.minion      :1711][INFO    ][24285] Returning information for job: 20190517031812924479
2019-05-17 03:18:17,987 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command state.apply with jid 20190517031817977267
2019-05-17 03:18:18,011 [salt.minion      :1432][INFO    ][24302] Starting a new job with PID 24302
2019-05-17 03:18:19,195 [salt.state       :915 ][INFO    ][24302] Loading fresh modules for state activity
2019-05-17 03:18:19,302 [salt.state       :1780][INFO    ][24302] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:18:19.302615
2019-05-17 03:18:19,303 [salt.state       :1813][INFO    ][24302] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 03:18:19,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24302] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 03:18:21,354 [salt.state       :300 ][INFO    ][24302] {'pid': 24312, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 03:18:21,356 [salt.state       :1951][INFO    ][24302] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:18:21.355919 duration_in_ms=2053.303
2019-05-17 03:18:21,359 [salt.state       :1780][INFO    ][24302] Running state [maas.deploy_machines] at time 03:18:21.359717
2019-05-17 03:18:21,360 [salt.state       :1813][INFO    ][24302] Executing state module.run for [maas.deploy_machines]
2019-05-17 03:18:21,361 [salt.utils.decorators:613 ][WARNING ][24302] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 03:18:21,921 [salt.loaded.ext.module.maas:684 ][INFO    ][24302] deploymachines hwe_kernel=ga-16.04 system_id=n6srab distro_series=xenial
2019-05-17 03:18:24,444 [salt.state       :300 ][INFO    ][24302] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-17 03:18:24,445 [salt.state       :1951][INFO    ][24302] Completed state [maas.deploy_machines] at time 03:18:24.445255 duration_in_ms=3085.538
2019-05-17 03:18:24,450 [salt.minion      :1711][INFO    ][24302] Returning information for job: 20190517031817977267
2019-05-17 03:18:25,128 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command state.apply with jid 20190517031825115293
2019-05-17 03:18:25,153 [salt.minion      :1432][INFO    ][24397] Starting a new job with PID 24397
2019-05-17 03:18:31,398 [salt.state       :915 ][INFO    ][24397] Loading fresh modules for state activity
2019-05-17 03:18:31,516 [salt.state       :1780][INFO    ][24397] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:18:31.516325
2019-05-17 03:18:31,516 [salt.state       :1813][INFO    ][24397] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 03:18:31,518 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24397] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 03:18:33,416 [salt.state       :300 ][INFO    ][24397] {'pid': 24409, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 03:18:33,416 [salt.state       :1951][INFO    ][24397] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:18:33.416524 duration_in_ms=1900.2
2019-05-17 03:18:33,418 [salt.state       :1780][INFO    ][24397] Running state [maas.wait_for_machine_status] at time 03:18:33.418809
2019-05-17 03:18:33,419 [salt.state       :1813][INFO    ][24397] Executing state module.run for [maas.wait_for_machine_status]
2019-05-17 03:18:33,419 [salt.utils.decorators:613 ][WARNING ][24397] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 03:18:36,117 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.32407022s left)
2019-05-17 03:18:40,208 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031840146797
2019-05-17 03:18:40,229 [salt.minion      :1432][INFO    ][24427] Starting a new job with PID 24427
2019-05-17 03:18:40,258 [salt.minion      :1711][INFO    ][24427] Returning information for job: 20190517031840146797
2019-05-17 03:19:08,939 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.50224519s left)
2019-05-17 03:19:10,302 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031910289613
2019-05-17 03:19:10,333 [salt.minion      :1432][INFO    ][24462] Starting a new job with PID 24462
2019-05-17 03:19:10,361 [salt.minion      :1711][INFO    ][24462] Returning information for job: 20190517031910289613
2019-05-17 03:19:40,388 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517031940380607
2019-05-17 03:19:40,403 [salt.minion      :1432][INFO    ][24506] Starting a new job with PID 24506
2019-05-17 03:19:40,434 [salt.minion      :1711][INFO    ][24506] Returning information for job: 20190517031940380607
2019-05-17 03:19:41,605 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.83602309s left)
2019-05-17 03:20:10,472 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032010457601
2019-05-17 03:20:10,494 [salt.minion      :1432][INFO    ][24543] Starting a new job with PID 24543
2019-05-17 03:20:10,528 [salt.minion      :1711][INFO    ][24543] Returning information for job: 20190517032010457601
2019-05-17 03:20:14,343 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.0978272s left)
2019-05-17 03:20:40,548 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032040529550
2019-05-17 03:20:40,568 [salt.minion      :1432][INFO    ][24586] Starting a new job with PID 24586
2019-05-17 03:20:40,600 [salt.minion      :1711][INFO    ][24586] Returning information for job: 20190517032040529550
2019-05-17 03:20:46,858 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.58303118s left)
2019-05-17 03:21:10,609 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032110600772
2019-05-17 03:21:10,629 [salt.minion      :1432][INFO    ][24617] Starting a new job with PID 24617
2019-05-17 03:21:10,663 [salt.minion      :1711][INFO    ][24617] Returning information for job: 20190517032110600772
2019-05-17 03:21:19,563 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.87793303s left)
2019-05-17 03:21:40,702 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032140687939
2019-05-17 03:21:40,726 [salt.minion      :1432][INFO    ][24658] Starting a new job with PID 24658
2019-05-17 03:21:40,756 [salt.minion      :1711][INFO    ][24658] Returning information for job: 20190517032140687939
2019-05-17 03:21:52,084 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.35652304s left)
2019-05-17 03:22:10,780 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032210764990
2019-05-17 03:22:10,801 [salt.minion      :1432][INFO    ][24692] Starting a new job with PID 24692
2019-05-17 03:22:10,832 [salt.minion      :1711][INFO    ][24692] Returning information for job: 20190517032210764990
2019-05-17 03:22:24,886 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.55457902s left)
2019-05-17 03:22:40,868 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032240860079
2019-05-17 03:22:40,892 [salt.minion      :1432][INFO    ][24736] Starting a new job with PID 24736
2019-05-17 03:22:40,921 [salt.minion      :1711][INFO    ][24736] Returning information for job: 20190517032240860079
2019-05-17 03:22:57,310 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1986.13082409s left)
2019-05-17 03:23:10,956 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032310945289
2019-05-17 03:23:10,981 [salt.minion      :1432][INFO    ][24796] Starting a new job with PID 24796
2019-05-17 03:23:11,011 [salt.minion      :1711][INFO    ][24796] Returning information for job: 20190517032310945289
2019-05-17 03:23:30,127 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1953.31364012s left)
2019-05-17 03:23:41,077 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032341063391
2019-05-17 03:23:41,099 [salt.minion      :1432][INFO    ][24865] Starting a new job with PID 24865
2019-05-17 03:23:41,136 [salt.minion      :1711][INFO    ][24865] Returning information for job: 20190517032341063391
2019-05-17 03:24:02,637 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1920.80424714s left)
2019-05-17 03:24:11,193 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032411180864
2019-05-17 03:24:11,214 [salt.minion      :1432][INFO    ][24944] Starting a new job with PID 24944
2019-05-17 03:24:11,244 [salt.minion      :1711][INFO    ][24944] Returning information for job: 20190517032411180864
2019-05-17 03:24:35,771 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.66968513s left)
2019-05-17 03:24:41,319 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032441309857
2019-05-17 03:24:41,345 [salt.minion      :1432][INFO    ][25099] Starting a new job with PID 25099
2019-05-17 03:24:41,372 [salt.minion      :1711][INFO    ][25099] Returning information for job: 20190517032441309857
2019-05-17 03:25:08,401 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1855.04025102s left)
2019-05-17 03:25:11,431 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032511419965
2019-05-17 03:25:11,446 [salt.minion      :1432][INFO    ][25139] Starting a new job with PID 25139
2019-05-17 03:25:11,476 [salt.minion      :1711][INFO    ][25139] Returning information for job: 20190517032511419965
2019-05-17 03:25:41,069 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1822.37223721s left)
2019-05-17 03:25:41,557 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032541544853
2019-05-17 03:25:41,576 [salt.minion      :1432][INFO    ][25218] Starting a new job with PID 25218
2019-05-17 03:25:41,603 [salt.minion      :1711][INFO    ][25218] Returning information for job: 20190517032541544853
2019-05-17 03:26:11,658 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032611647603
2019-05-17 03:26:11,683 [salt.minion      :1432][INFO    ][25267] Starting a new job with PID 25267
2019-05-17 03:26:11,712 [salt.minion      :1711][INFO    ][25267] Returning information for job: 20190517032611647603
2019-05-17 03:26:13,664 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1789.77685714s left)
2019-05-17 03:26:41,805 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032641793430
2019-05-17 03:26:41,830 [salt.minion      :1432][INFO    ][25369] Starting a new job with PID 25369
2019-05-17 03:26:41,861 [salt.minion      :1711][INFO    ][25369] Returning information for job: 20190517032641793430
2019-05-17 03:26:46,767 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1756.67367506s left)
2019-05-17 03:27:11,977 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032711962774
2019-05-17 03:27:11,998 [salt.minion      :1432][INFO    ][25445] Starting a new job with PID 25445
2019-05-17 03:27:12,029 [salt.minion      :1711][INFO    ][25445] Returning information for job: 20190517032711962774
2019-05-17 03:27:19,676 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1723.76467419s left)
2019-05-17 03:27:42,137 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032742121237
2019-05-17 03:27:42,167 [salt.minion      :1432][INFO    ][25554] Starting a new job with PID 25554
2019-05-17 03:27:42,194 [salt.minion      :1711][INFO    ][25554] Returning information for job: 20190517032742121237
2019-05-17 03:27:52,716 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1690.72464204s left)
2019-05-17 03:28:12,312 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032812298205
2019-05-17 03:28:12,340 [salt.minion      :1432][INFO    ][25588] Starting a new job with PID 25588
2019-05-17 03:28:12,372 [salt.minion      :1711][INFO    ][25588] Returning information for job: 20190517032812298205
2019-05-17 03:28:25,504 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1657.93700004s left)
2019-05-17 03:28:42,498 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032842483268
2019-05-17 03:28:42,521 [salt.minion      :1432][INFO    ][25636] Starting a new job with PID 25636
2019-05-17 03:28:42,549 [salt.minion      :1711][INFO    ][25636] Returning information for job: 20190517032842483268
2019-05-17 03:28:58,384 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1625.05652213s left)
2019-05-17 03:29:12,674 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032912658939
2019-05-17 03:29:12,701 [salt.minion      :1432][INFO    ][25672] Starting a new job with PID 25672
2019-05-17 03:29:12,739 [salt.minion      :1711][INFO    ][25672] Returning information for job: 20190517032912658939
2019-05-17 03:29:31,246 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1592.19524121s left)
2019-05-17 03:29:42,864 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517032942847059
2019-05-17 03:29:42,895 [salt.minion      :1432][INFO    ][25717] Starting a new job with PID 25717
2019-05-17 03:29:42,924 [salt.minion      :1711][INFO    ][25717] Returning information for job: 20190517032942847059
2019-05-17 03:30:04,255 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1559.18629313s left)
2019-05-17 03:30:13,077 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517033013059495
2019-05-17 03:30:13,107 [salt.minion      :1432][INFO    ][25755] Starting a new job with PID 25755
2019-05-17 03:30:13,137 [salt.minion      :1711][INFO    ][25755] Returning information for job: 20190517033013059495
2019-05-17 03:30:37,316 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1526.12494302s left)
2019-05-17 03:30:43,294 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517033043277082
2019-05-17 03:30:43,325 [salt.minion      :1432][INFO    ][25798] Starting a new job with PID 25798
2019-05-17 03:30:43,355 [salt.minion      :1711][INFO    ][25798] Returning information for job: 20190517033043277082
2019-05-17 03:31:10,337 [salt.loaded.ext.module.maas:1023][INFO    ][24397] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1493.10440922s left)
2019-05-17 03:31:13,521 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517033113504292
2019-05-17 03:31:13,545 [salt.minion      :1432][INFO    ][25854] Starting a new job with PID 25854
2019-05-17 03:31:13,580 [salt.minion      :1711][INFO    ][25854] Returning information for job: 20190517033113504292
2019-05-17 03:31:43,089 [salt.state       :300 ][INFO    ][24397] {'ret': True}
2019-05-17 03:31:43,089 [salt.state       :1951][INFO    ][24397] Completed state [maas.wait_for_machine_status] at time 03:31:43.089454 duration_in_ms=789670.641
2019-05-17 03:31:43,096 [salt.minion      :1711][INFO    ][24397] Returning information for job: 20190517031825115293
2019-05-17 04:07:12,285 [salt.utils.schedule:1377][INFO    ][3147] Running scheduled job: __mine_interval
2019-05-17 05:00:52,341 [salt.minion      :1308][INFO    ][3147] User sudo_ubuntu Executing command cp.push_dir with jid 20190517050052331291
2019-05-17 05:00:52,368 [salt.minion      :1432][INFO    ][32270] Starting a new job with PID 32270
