2019-04-15 07:24:52,427 [salt.minion      :870 ][ERROR   ][386] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-15 07:25:42,464 [salt.minion      :870 ][ERROR   ][386] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-15 07:26:32,512 [salt.minion      :870 ][ERROR   ][386] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-15 07:27:22,560 [salt.minion      :870 ][ERROR   ][386] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-15 07:28:12,607 [salt.minion      :870 ][ERROR   ][386] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-15 07:29:02,653 [salt.minion      :870 ][ERROR   ][386] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-15 07:30:32,448 [salt.utils.decorators:613 ][WARNING ][2878] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 07:30:33,380 [salt.utils.decorators:613 ][WARNING ][2878] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 07:30:44,016 [salt.loaded.int.states.file:2298][WARNING ][3012] 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-04-15 07:30:47,994 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3226] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-15 07:30:48,019 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3226] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-15 07:30:48,068 [salt.utils.parsers:1051][WARNING ][386] Minion received a SIGTERM. Exiting.
2019-04-15 07:30:49,251 [salt.cli.daemons :293 ][INFO    ][3387] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-04-15 07:30:49,423 [salt.cli.daemons :82  ][INFO    ][3387] Starting up the Salt Minion
2019-04-15 07:30:49,424 [salt.utils.event :1017][INFO    ][3387] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-04-15 07:30:50,642 [salt.minion      :976 ][INFO    ][3387] Creating minion process manager
2019-04-15 07:30:52,081 [salt.state       :2022][WARNING ][3231] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-15 07:30:53,228 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3387] Executing command ['date', '+%z'] in directory '/root'
2019-04-15 07:30:53,252 [salt.utils.schedule:568 ][INFO    ][3387] Updating job settings for scheduled job: __mine_interval
2019-04-15 07:30:53,254 [salt.minion      :1108][INFO    ][3387] Added mine.update to scheduler
2019-04-15 07:30:53,261 [salt.minion      :1975][INFO    ][3387] Minion is starting as user 'root'
2019-04-15 07:30:53,275 [salt.minion      :2336][INFO    ][3387] Minion is ready to receive requests!
2019-04-15 07:30:55,135 [salt.utils.decorators:613 ][WARNING ][3231] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 07:31:00,317 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073100306539
2019-04-15 07:31:00,333 [salt.minion      :1432][INFO    ][3896] Starting a new job with PID 3896
2019-04-15 07:31:00,361 [salt.minion      :1711][INFO    ][3896] Returning information for job: 20190415073100306539
2019-04-15 07:31:05,687 [salt.utils.decorators:613 ][WARNING ][3231] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 07:31:08,180 [salt.loaded.ext.module.maasng:1694][WARNING ][3231] boot_source_selections catch error during processing. Most-probably, streams data not imported yet.
Sleep:5s Retry:0/10
2019-04-15 07:31:08,181 [salt.loaded.ext.module.maasng:1695][WARNING ][3231] Message:['{"os": ["OS ubuntu with release xenial has no available images for download"]}']
2019-04-15 07:31:30,410 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073130397568
2019-04-15 07:31:30,430 [salt.minion      :1432][INFO    ][4423] Starting a new job with PID 4423
2019-04-15 07:31:30,466 [salt.minion      :1711][INFO    ][4423] Returning information for job: 20190415073130397568
2019-04-15 07:31:33,486 [salt.utils.decorators:613 ][WARNING ][3231] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 07:31:34,261 [salt.utils.decorators:613 ][WARNING ][3231] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 07:31:36,311 [salt.loaded.ext.module.maasng:1008][WARNING ][3231] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-15 07:31:36,312 [salt.loaded.ext.module.maasng:1011][WARNING ][3231] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-15 07:31:37,096 [salt.loaded.ext.module.maasng:1235][WARNING ][3231] Ignoring parameter vlan:0
2019-04-15 07:31:38,914 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command state.apply with jid 20190415073138901373
2019-04-15 07:31:38,932 [salt.minion      :1432][INFO    ][4657] Starting a new job with PID 4657
2019-04-15 07:31:47,394 [salt.state       :915 ][INFO    ][4657] Loading fresh modules for state activity
2019-04-15 07:31:47,454 [salt.fileclient  :1219][INFO    ][4657] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-04-15 07:31:47,497 [salt.state       :1780][INFO    ][4657] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:31:47.497577
2019-04-15 07:31:47,497 [salt.state       :1813][INFO    ][4657] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-15 07:31:47,500 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4657] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-15 07:31:49,414 [salt.state       :300 ][INFO    ][4657] {'pid': 4742, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-15 07:31:49,415 [salt.state       :1951][INFO    ][4657] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:31:49.415478 duration_in_ms=1917.901
2019-04-15 07:31:49,417 [salt.state       :1780][INFO    ][4657] Running state [maas.process_machines] at time 07:31:49.417132
2019-04-15 07:31:49,417 [salt.state       :1813][INFO    ][4657] Executing state module.run for [maas.process_machines]
2019-04-15 07:31:49,418 [salt.utils.decorators:613 ][WARNING ][4657] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 07:31:49,470 [salt.loaded.ext.module.maas:412 ][WARNING ][4657] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-15 07:31:49,471 [salt.loaded.ext.module.maas:92  ][INFO    ][4657] 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-04-15 07:31:50,696 [salt.loaded.ext.module.maas:412 ][WARNING ][4657] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-15 07:31:50,696 [salt.loaded.ext.module.maas:92  ][INFO    ][4657] 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-04-15 07:31:51,937 [salt.loaded.ext.module.maas:412 ][WARNING ][4657] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-15 07:31:51,938 [salt.loaded.ext.module.maas:92  ][INFO    ][4657] 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-04-15 07:31:53,238 [salt.loaded.ext.module.maas:412 ][WARNING ][4657] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-15 07:31:53,239 [salt.loaded.ext.module.maas:92  ][INFO    ][4657] 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-04-15 07:31:54,009 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073153999580
2019-04-15 07:31:54,025 [salt.minion      :1432][INFO    ][4963] Starting a new job with PID 4963
2019-04-15 07:31:54,058 [salt.minion      :1711][INFO    ][4963] Returning information for job: 20190415073153999580
2019-04-15 07:31:54,587 [salt.loaded.ext.module.maas:412 ][WARNING ][4657] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-15 07:31:54,588 [salt.loaded.ext.module.maas:92  ][INFO    ][4657] 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-04-15 07:31:55,954 [salt.state       :300 ][INFO    ][4657] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-15 07:31:55,954 [salt.state       :1951][INFO    ][4657] Completed state [maas.process_machines] at time 07:31:55.954563 duration_in_ms=6537.43
2019-04-15 07:31:55,958 [salt.minion      :1711][INFO    ][4657] Returning information for job: 20190415073138901373
2019-04-15 07:32:40,393 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command state.apply with jid 20190415073240384061
2019-04-15 07:32:40,411 [salt.minion      :1432][INFO    ][5100] Starting a new job with PID 5100
2019-04-15 07:32:48,593 [salt.state       :915 ][INFO    ][5100] Loading fresh modules for state activity
2019-04-15 07:32:48,645 [salt.fileclient  :1219][INFO    ][5100] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-15 07:32:48,694 [salt.state       :1780][INFO    ][5100] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:32:48.694376
2019-04-15 07:32:48,694 [salt.state       :1813][INFO    ][5100] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-15 07:32:48,697 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5100] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-15 07:32:50,523 [salt.state       :300 ][INFO    ][5100] {'pid': 5133, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-15 07:32:50,524 [salt.state       :1951][INFO    ][5100] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:32:50.524753 duration_in_ms=1830.376
2019-04-15 07:32:50,529 [salt.state       :1780][INFO    ][5100] Running state [maas.wait_for_machine_status] at time 07:32:50.528883
2019-04-15 07:32:50,529 [salt.state       :1813][INFO    ][5100] Executing state module.run for [maas.wait_for_machine_status]
2019-04-15 07:32:50,530 [salt.utils.decorators:613 ][WARNING ][5100] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 07:32:51,329 [salt.loaded.ext.module.maas:1023][INFO    ][5100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.21443605s left)
2019-04-15 07:32:55,454 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073255441364
2019-04-15 07:32:55,475 [salt.minion      :1432][INFO    ][5160] Starting a new job with PID 5160
2019-04-15 07:32:55,504 [salt.minion      :1711][INFO    ][5160] Returning information for job: 20190415073255441364
2019-04-15 07:33:22,140 [salt.loaded.ext.module.maas:1023][INFO    ][5100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.40340114s left)
2019-04-15 07:33:25,630 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073325591848
2019-04-15 07:33:25,650 [salt.minion      :1432][INFO    ][5200] Starting a new job with PID 5200
2019-04-15 07:33:25,677 [salt.minion      :1711][INFO    ][5200] Returning information for job: 20190415073325591848
2019-04-15 07:33:53,042 [salt.loaded.ext.module.maas:1023][INFO    ][5100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.50179601s left)
2019-04-15 07:33:55,702 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073355686708
2019-04-15 07:33:55,724 [salt.minion      :1432][INFO    ][5266] Starting a new job with PID 5266
2019-04-15 07:33:55,752 [salt.minion      :1711][INFO    ][5266] Returning information for job: 20190415073355686708
2019-04-15 07:34:24,058 [salt.loaded.ext.module.maas:1023][INFO    ][5100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.48554897s left)
2019-04-15 07:34:25,748 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073425740458
2019-04-15 07:34:25,762 [salt.minion      :1432][INFO    ][5328] Starting a new job with PID 5328
2019-04-15 07:34:25,789 [salt.minion      :1711][INFO    ][5328] Returning information for job: 20190415073425740458
2019-04-15 07:34:55,306 [salt.loaded.ext.module.maas:1023][INFO    ][5100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.23731899s left)
2019-04-15 07:34:55,761 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073455751601
2019-04-15 07:34:55,781 [salt.minion      :1432][INFO    ][5469] Starting a new job with PID 5469
2019-04-15 07:34:55,809 [salt.minion      :1711][INFO    ][5469] Returning information for job: 20190415073455751601
2019-04-15 07:35:25,988 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073525970591
2019-04-15 07:35:26,013 [salt.minion      :1432][INFO    ][5581] Starting a new job with PID 5581
2019-04-15 07:35:26,060 [salt.minion      :1711][INFO    ][5581] Returning information for job: 20190415073525970591
2019-04-15 07:35:26,397 [salt.loaded.ext.module.maas:1023][INFO    ][5100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.14681315s left)
2019-04-15 07:35:56,119 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073556098561
2019-04-15 07:35:56,136 [salt.minion      :1432][INFO    ][5752] Starting a new job with PID 5752
2019-04-15 07:35:56,166 [salt.minion      :1711][INFO    ][5752] Returning information for job: 20190415073556098561
2019-04-15 07:35:57,985 [salt.loaded.ext.module.maas:1023][INFO    ][5100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.55836105s left)
2019-04-15 07:36:26,134 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073626122658
2019-04-15 07:36:26,149 [salt.minion      :1432][INFO    ][5992] Starting a new job with PID 5992
2019-04-15 07:36:26,178 [salt.minion      :1711][INFO    ][5992] Returning information for job: 20190415073626122658
2019-04-15 07:36:29,219 [salt.loaded.ext.module.maas:1023][INFO    ][5100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.32438397s left)
2019-04-15 07:36:56,240 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073656227319
2019-04-15 07:36:56,258 [salt.minion      :1432][INFO    ][6240] Starting a new job with PID 6240
2019-04-15 07:36:56,293 [salt.minion      :1711][INFO    ][6240] Returning information for job: 20190415073656227319
2019-04-15 07:37:00,972 [salt.loaded.ext.module.maas:1023][INFO    ][5100] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.57120609s left)
2019-04-15 07:37:26,370 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073726355199
2019-04-15 07:37:26,397 [salt.minion      :1432][INFO    ][6556] Starting a new job with PID 6556
2019-04-15 07:37:26,429 [salt.minion      :1711][INFO    ][6556] Returning information for job: 20190415073726355199
2019-04-15 07:37:32,696 [salt.loaded.ext.module.maas:1023][INFO    ][5100] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.84739709s left)
2019-04-15 07:37:56,493 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073756481216
2019-04-15 07:37:56,510 [salt.minion      :1432][INFO    ][6750] Starting a new job with PID 6750
2019-04-15 07:37:56,554 [salt.minion      :1711][INFO    ][6750] Returning information for job: 20190415073756481216
2019-04-15 07:38:05,022 [salt.loaded.ext.module.maas:1023][INFO    ][5100] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.52189016s left)
2019-04-15 07:38:26,622 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073826612767
2019-04-15 07:38:26,641 [salt.minion      :1432][INFO    ][6892] Starting a new job with PID 6892
2019-04-15 07:38:26,674 [salt.minion      :1711][INFO    ][6892] Returning information for job: 20190415073826612767
2019-04-15 07:38:37,568 [salt.state       :300 ][INFO    ][5100] {'ret': True}
2019-04-15 07:38:37,569 [salt.state       :1951][INFO    ][5100] Completed state [maas.wait_for_machine_status] at time 07:38:37.569230 duration_in_ms=347040.347
2019-04-15 07:38:37,571 [salt.minion      :1711][INFO    ][5100] Returning information for job: 20190415073240384061
2019-04-15 07:38:38,252 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command state.apply with jid 20190415073838243981
2019-04-15 07:38:38,267 [salt.minion      :1432][INFO    ][6955] Starting a new job with PID 6955
2019-04-15 07:38:46,390 [salt.state       :915 ][INFO    ][6955] Loading fresh modules for state activity
2019-04-15 07:38:46,451 [salt.fileclient  :1219][INFO    ][6955] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-15 07:38:46,567 [salt.state       :1780][INFO    ][6955] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:38:46.567470
2019-04-15 07:38:46,567 [salt.state       :1813][INFO    ][6955] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-15 07:38:46,569 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6955] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-15 07:38:48,496 [salt.state       :300 ][INFO    ][6955] {'pid': 6991, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-15 07:38:48,497 [salt.state       :1951][INFO    ][6955] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:38:48.497302 duration_in_ms=1929.832
2019-04-15 07:38:48,501 [salt.state       :1780][INFO    ][6955] Running state [maas_machines_storage_cmp002_lvm] at time 07:38:48.501041
2019-04-15 07:38:48,501 [salt.state       :1813][INFO    ][6955] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-15 07:38:49,733 [salt.loaded.ext.module.maasng:610 ][INFO    ][6955] 64kdb3
2019-04-15 07:38:49,733 [salt.loaded.ext.module.maasng:626 ][INFO    ][6955] sda
2019-04-15 07:38:50,243 [salt.loaded.ext.module.maasng:361 ][INFO    ][6955] 64kdb3
2019-04-15 07:38:50,338 [salt.loaded.ext.module.maasng:367 ][INFO    ][6955] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'c5df81ce-4064-4dcf-b777-0f01d1018a2b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64kdb3', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7abd65a5-6175-4921-8c32-9855f72fa663', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'64kdb3', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/blockdevices/1/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'046b8e2e-1e2b-49a0-9a3e-bfe6cf927623', u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'64kdb3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'08a80e47-74fe-4181-8b36-c684c2840c2c', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/blockdevices/3/', u'used_size': 800097042432, u'model': None, u'size': 800097042432}]
2019-04-15 07:38:50,339 [salt.loaded.ext.module.maasng:632 ][INFO    ][6955] vgroot
2019-04-15 07:38:50,339 [salt.loaded.ext.module.maasng:635 ][INFO    ][6955] lvroot
2019-04-15 07:38:50,339 [salt.loaded.ext.module.maasng:639 ][INFO    ][6955] 107374182400
2019-04-15 07:38:50,932 [salt.loaded.ext.module.maasng:645 ][INFO    ][6955] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': 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'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': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 22, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'64kdb3', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/interfaces/5/'}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'64kdb3', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], 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'34ff6a8d-6f78-48c9-9719-2e3e1add89aa', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64kdb3', u'device_id': 1, u'filesystem': {u'mount_options': None, u'uuid': u'6dd5bf2c-355f-4463-a429-2c505f53f008', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'64kdb3', u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'64kdb3', 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'34ff6a8d-6f78-48c9-9719-2e3e1add89aa', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64kdb3', u'device_id': 1, u'filesystem': {u'mount_options': None, u'uuid': u'6dd5bf2c-355f-4463-a429-2c505f53f008', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'64kdb3', u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, 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'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': 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'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': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 22, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'64kdb3', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'64kdb3', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/interfaces/14/'}, {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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'ens2f1', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'64kdb3', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/interfaces/13/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': 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'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': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 24, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:1c', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'64kdb3', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/interfaces/15/'}, {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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'ens2f0', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'64kdb3', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/interfaces/16/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'64kdb3', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/interfaces/12/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'partition_table_type': None, u'uuid': u'da44f2be-9bbc-429b-94c3-ba9059d02b19', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'64kdb3', u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/blockdevices/11/', u'filesystem': {u'mount_options': None, u'uuid': u'e271b0ae-a1a7-4b80-8db5-d2a7865ee625', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, 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'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 2, u'address_ttl': None, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'34ff6a8d-6f78-48c9-9719-2e3e1add89aa', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64kdb3', u'device_id': 1, u'filesystem': {u'mount_options': None, u'uuid': u'6dd5bf2c-355f-4463-a429-2c505f53f008', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/64kdb3/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'64kdb3', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'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/64kdb3/blockdevices/11/', u'available_size': 0, u'uuid': u'da44f2be-9bbc-429b-94c3-ba9059d02b19', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'64kdb3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'e271b0ae-a1a7-4b80-8db5-d2a7865ee625', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, 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'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/64kdb3/'}
2019-04-15 07:38:50,935 [salt.state       :300 ][INFO    ][6955] {'new': {'storage_layout': 'lvm'}}
2019-04-15 07:38:50,936 [salt.state       :1951][INFO    ][6955] Completed state [maas_machines_storage_cmp002_lvm] at time 07:38:50.935957 duration_in_ms=2434.915
2019-04-15 07:38:50,936 [salt.state       :1780][INFO    ][6955] Running state [maas_machines_storage_cmp001_lvm] at time 07:38:50.936561
2019-04-15 07:38:50,937 [salt.state       :1813][INFO    ][6955] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-15 07:38:52,079 [salt.loaded.ext.module.maasng:610 ][INFO    ][6955] cakrqn
2019-04-15 07:38:52,080 [salt.loaded.ext.module.maasng:626 ][INFO    ][6955] sda
2019-04-15 07:38:52,738 [salt.loaded.ext.module.maasng:361 ][INFO    ][6955] cakrqn
2019-04-15 07:38:52,829 [salt.loaded.ext.module.maasng:367 ][INFO    ][6955] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'edd57990-0b67-4c31-9128-0a5b1dcc9dcc', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cakrqn', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'fb01f133-1769-476d-885d-19b662dc5f78', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'uuid': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cakrqn', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/blockdevices/2/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'596ca6c3-45e2-4747-8d89-3c440e625cec', u'id': 4, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cakrqn', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'cd3e07c7-416a-416b-9f3f-8c2c298bf8eb', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/blockdevices/4/', u'used_size': 800097042432, u'model': None, u'size': 800097042432}]
2019-04-15 07:38:52,829 [salt.loaded.ext.module.maasng:632 ][INFO    ][6955] vgroot
2019-04-15 07:38:52,830 [salt.loaded.ext.module.maasng:635 ][INFO    ][6955] lvroot
2019-04-15 07:38:52,830 [salt.loaded.ext.module.maasng:639 ][INFO    ][6955] 107374182400
2019-04-15 07:38:53,349 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073853341228
2019-04-15 07:38:53,366 [salt.minion      :1432][INFO    ][7013] Starting a new job with PID 7013
2019-04-15 07:38:53,397 [salt.minion      :1711][INFO    ][7013] Returning information for job: 20190415073853341228
2019-04-15 07:38:53,441 [salt.loaded.ext.module.maasng:645 ][INFO    ][6955] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': 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'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': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 28, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'cakrqn', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/interfaces/6/'}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'cakrqn', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], 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'5e963008-99f2-4803-a13b-8260159865a0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cakrqn', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'26909caf-298c-4e0f-8a82-3568dbfc2563', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cakrqn', u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'cakrqn', u'id': 7}], 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'5e963008-99f2-4803-a13b-8260159865a0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cakrqn', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'26909caf-298c-4e0f-8a82-3568dbfc2563', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cakrqn', u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': 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'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': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 28, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'cakrqn', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/interfaces/6/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'cakrqn', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/interfaces/17/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'cakrqn', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/interfaces/19/'}, {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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'ens2f1', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'cakrqn', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/interfaces/18/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': 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'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': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 30, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'cakrqn', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/interfaces/20/'}, {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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'ens2f0', u'links': [{u'id': 31, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'cakrqn', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/interfaces/21/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'partition_table_type': None, u'uuid': u'4e3ac8c5-3826-45a4-8756-6ca74282c0b9', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'cakrqn', u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/blockdevices/12/', u'filesystem': {u'mount_options': None, u'uuid': u'bf0c2597-a06f-428a-9b1a-56b2dbae9098', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/blockdevices/2/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'5e963008-99f2-4803-a13b-8260159865a0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cakrqn', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'26909caf-298c-4e0f-8a82-3568dbfc2563', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cakrqn', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/cakrqn/blockdevices/12/', u'available_size': 0, u'uuid': u'4e3ac8c5-3826-45a4-8756-6ca74282c0b9', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cakrqn', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'bf0c2597-a06f-428a-9b1a-56b2dbae9098', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, 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': 12, u'serial': None, u'size': 107374182400}], u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/cakrqn/'}
2019-04-15 07:38:53,444 [salt.state       :300 ][INFO    ][6955] {'new': {'storage_layout': 'lvm'}}
2019-04-15 07:38:53,444 [salt.state       :1951][INFO    ][6955] Completed state [maas_machines_storage_cmp001_lvm] at time 07:38:53.444523 duration_in_ms=2507.962
2019-04-15 07:38:53,448 [salt.minion      :1711][INFO    ][6955] Returning information for job: 20190415073838243981
2019-04-15 07:38:54,151 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command state.apply with jid 20190415073854136552
2019-04-15 07:38:54,167 [salt.minion      :1432][INFO    ][7020] Starting a new job with PID 7020
2019-04-15 07:38:55,458 [salt.state       :915 ][INFO    ][7020] Loading fresh modules for state activity
2019-04-15 07:38:55,513 [salt.fileclient  :1219][INFO    ][7020] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-15 07:38:55,562 [salt.state       :1780][INFO    ][7020] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:38:55.562448
2019-04-15 07:38:55,563 [salt.state       :1813][INFO    ][7020] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-15 07:38:55,565 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7020] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-15 07:38:57,494 [salt.state       :300 ][INFO    ][7020] {'pid': 7081, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-15 07:38:57,496 [salt.state       :1951][INFO    ][7020] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:38:57.495880 duration_in_ms=1933.433
2019-04-15 07:38:57,498 [salt.state       :1780][INFO    ][7020] Running state [maas.deploy_machines] at time 07:38:57.498880
2019-04-15 07:38:57,499 [salt.state       :1813][INFO    ][7020] Executing state module.run for [maas.deploy_machines]
2019-04-15 07:38:57,500 [salt.utils.decorators:613 ][WARNING ][7020] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 07:38:58,074 [salt.loaded.ext.module.maas:684 ][INFO    ][7020] deploymachines hwe_kernel=hwe-16.04 system_id=64kdb3 distro_series=xenial
2019-04-15 07:39:00,384 [salt.loaded.ext.module.maas:684 ][INFO    ][7020] deploymachines hwe_kernel=hwe-16.04 system_id=cakrqn distro_series=xenial
2019-04-15 07:39:02,736 [salt.loaded.ext.module.maas:684 ][INFO    ][7020] deploymachines hwe_kernel=hwe-16.04 system_id=e6tax7 distro_series=xenial
2019-04-15 07:39:05,143 [salt.loaded.ext.module.maas:684 ][INFO    ][7020] deploymachines hwe_kernel=hwe-16.04 system_id=gm7gc6 distro_series=xenial
2019-04-15 07:39:07,548 [salt.loaded.ext.module.maas:684 ][INFO    ][7020] deploymachines hwe_kernel=hwe-16.04 system_id=nh8pqs distro_series=xenial
2019-04-15 07:39:09,187 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073909176104
2019-04-15 07:39:09,202 [salt.minion      :1432][INFO    ][7359] Starting a new job with PID 7359
2019-04-15 07:39:09,230 [salt.minion      :1711][INFO    ][7359] Returning information for job: 20190415073909176104
2019-04-15 07:39:09,859 [salt.state       :300 ][INFO    ][7020] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-15 07:39:09,860 [salt.state       :1951][INFO    ][7020] Completed state [maas.deploy_machines] at time 07:39:09.860241 duration_in_ms=12361.36
2019-04-15 07:39:09,863 [salt.minion      :1711][INFO    ][7020] Returning information for job: 20190415073854136552
2019-04-15 07:39:10,549 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command state.apply with jid 20190415073910540588
2019-04-15 07:39:10,573 [salt.minion      :1432][INFO    ][7375] Starting a new job with PID 7375
2019-04-15 07:39:18,772 [salt.state       :915 ][INFO    ][7375] Loading fresh modules for state activity
2019-04-15 07:39:18,825 [salt.fileclient  :1219][INFO    ][7375] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-15 07:39:18,875 [salt.state       :1780][INFO    ][7375] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:39:18.875247
2019-04-15 07:39:18,875 [salt.state       :1813][INFO    ][7375] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-15 07:39:18,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7375] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-15 07:39:20,800 [salt.state       :300 ][INFO    ][7375] {'pid': 7406, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-15 07:39:20,801 [salt.state       :1951][INFO    ][7375] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:39:20.801351 duration_in_ms=1926.104
2019-04-15 07:39:20,805 [salt.state       :1780][INFO    ][7375] Running state [maas.wait_for_machine_status] at time 07:39:20.805167
2019-04-15 07:39:20,805 [salt.state       :1813][INFO    ][7375] Executing state module.run for [maas.wait_for_machine_status]
2019-04-15 07:39:20,806 [salt.utils.decorators:613 ][WARNING ][7375] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 07:39:23,614 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.20486188s left)
2019-04-15 07:39:25,612 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073925604329
2019-04-15 07:39:25,628 [salt.minion      :1432][INFO    ][7429] Starting a new job with PID 7429
2019-04-15 07:39:25,661 [salt.minion      :1711][INFO    ][7429] Returning information for job: 20190415073925604329
2019-04-15 07:39:55,820 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415073955811771
2019-04-15 07:39:55,835 [salt.minion      :1432][INFO    ][7495] Starting a new job with PID 7495
2019-04-15 07:39:55,864 [salt.minion      :1711][INFO    ][7495] Returning information for job: 20190415073955811771
2019-04-15 07:39:56,741 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.07835102s left)
2019-04-15 07:40:26,041 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074026031737
2019-04-15 07:40:26,059 [salt.minion      :1432][INFO    ][7535] Starting a new job with PID 7535
2019-04-15 07:40:26,090 [salt.minion      :1711][INFO    ][7535] Returning information for job: 20190415074026031737
2019-04-15 07:40:29,518 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.301476s left)
2019-04-15 07:40:56,246 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074056233618
2019-04-15 07:40:56,276 [salt.minion      :1432][INFO    ][7603] Starting a new job with PID 7603
2019-04-15 07:40:56,303 [salt.minion      :1711][INFO    ][7603] Returning information for job: 20190415074056233618
2019-04-15 07:41:02,365 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.45457888s left)
2019-04-15 07:41:26,458 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074126445664
2019-04-15 07:41:26,480 [salt.minion      :1432][INFO    ][7658] Starting a new job with PID 7658
2019-04-15 07:41:26,507 [salt.minion      :1711][INFO    ][7658] Returning information for job: 20190415074126445664
2019-04-15 07:41:35,210 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.609519s left)
2019-04-15 07:41:56,672 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074156663364
2019-04-15 07:41:56,686 [salt.minion      :1432][INFO    ][7741] Starting a new job with PID 7741
2019-04-15 07:41:56,714 [salt.minion      :1711][INFO    ][7741] Returning information for job: 20190415074156663364
2019-04-15 07:42:08,070 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.74970698s left)
2019-04-15 07:42:26,870 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074226861770
2019-04-15 07:42:26,891 [salt.minion      :1432][INFO    ][7825] Starting a new job with PID 7825
2019-04-15 07:42:26,918 [salt.minion      :1711][INFO    ][7825] Returning information for job: 20190415074226861770
2019-04-15 07:42:40,999 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.81989288s left)
2019-04-15 07:42:57,083 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074257073155
2019-04-15 07:42:57,104 [salt.minion      :1432][INFO    ][8060] Starting a new job with PID 8060
2019-04-15 07:42:57,138 [salt.minion      :1711][INFO    ][8060] Returning information for job: 20190415074257073155
2019-04-15 07:43:14,013 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.80634999s left)
2019-04-15 07:43:27,097 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074327090251
2019-04-15 07:43:27,114 [salt.minion      :1432][INFO    ][8229] Starting a new job with PID 8229
2019-04-15 07:43:27,144 [salt.minion      :1711][INFO    ][8229] Returning information for job: 20190415074327090251
2019-04-15 07:43:46,703 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.11687899s left)
2019-04-15 07:43:57,320 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074357309570
2019-04-15 07:43:57,338 [salt.minion      :1432][INFO    ][8490] Starting a new job with PID 8490
2019-04-15 07:43:57,377 [salt.minion      :1711][INFO    ][8490] Returning information for job: 20190415074357309570
2019-04-15 07:44:19,639 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.17978692s left)
2019-04-15 07:44:27,348 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074427340707
2019-04-15 07:44:27,371 [salt.minion      :1432][INFO    ][8639] Starting a new job with PID 8639
2019-04-15 07:44:27,404 [salt.minion      :1711][INFO    ][8639] Returning information for job: 20190415074427340707
2019-04-15 07:44:52,466 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.35293984s left)
2019-04-15 07:44:57,386 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074457372633
2019-04-15 07:44:57,410 [salt.minion      :1432][INFO    ][8893] Starting a new job with PID 8893
2019-04-15 07:44:57,454 [salt.minion      :1711][INFO    ][8893] Returning information for job: 20190415074457372633
2019-04-15 07:45:25,377 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.44194484s left)
2019-04-15 07:45:27,461 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074527443195
2019-04-15 07:45:27,479 [salt.minion      :1432][INFO    ][9185] Starting a new job with PID 9185
2019-04-15 07:45:27,510 [salt.minion      :1711][INFO    ][9185] Returning information for job: 20190415074527443195
2019-04-15 07:45:57,479 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074557467210
2019-04-15 07:45:57,502 [salt.minion      :1432][INFO    ][9419] Starting a new job with PID 9419
2019-04-15 07:45:57,537 [salt.minion      :1711][INFO    ][9419] Returning information for job: 20190415074557467210
2019-04-15 07:45:58,917 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.90201187s left)
2019-04-15 07:46:27,517 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074627506267
2019-04-15 07:46:27,538 [salt.minion      :1432][INFO    ][9530] Starting a new job with PID 9530
2019-04-15 07:46:27,566 [salt.minion      :1711][INFO    ][9530] Returning information for job: 20190415074627506267
2019-04-15 07:46:31,890 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.92954993s left)
2019-04-15 07:46:57,549 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074657538772
2019-04-15 07:46:57,571 [salt.minion      :1432][INFO    ][9764] Starting a new job with PID 9764
2019-04-15 07:46:57,600 [salt.minion      :1711][INFO    ][9764] Returning information for job: 20190415074657538772
2019-04-15 07:47:05,000 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.81908488s left)
2019-04-15 07:47:27,577 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074727566306
2019-04-15 07:47:27,599 [salt.minion      :1432][INFO    ][9883] Starting a new job with PID 9883
2019-04-15 07:47:27,629 [salt.minion      :1711][INFO    ][9883] Returning information for job: 20190415074727566306
2019-04-15 07:47:37,810 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.00958991s left)
2019-04-15 07:47:57,617 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074757607907
2019-04-15 07:47:57,635 [salt.minion      :1432][INFO    ][10051] Starting a new job with PID 10051
2019-04-15 07:47:57,664 [salt.minion      :1711][INFO    ][10051] Returning information for job: 20190415074757607907
2019-04-15 07:48:10,592 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.22734284s left)
2019-04-15 07:48:27,762 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074827753219
2019-04-15 07:48:27,776 [salt.minion      :1432][INFO    ][10155] Starting a new job with PID 10155
2019-04-15 07:48:27,805 [salt.minion      :1711][INFO    ][10155] Returning information for job: 20190415074827753219
2019-04-15 07:48:43,541 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1687.27808189s left)
2019-04-15 07:48:57,858 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074857845637
2019-04-15 07:48:57,882 [salt.minion      :1432][INFO    ][10389] Starting a new job with PID 10389
2019-04-15 07:48:57,910 [salt.minion      :1711][INFO    ][10389] Returning information for job: 20190415074857845637
2019-04-15 07:49:16,366 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1654.45353985s left)
2019-04-15 07:49:27,897 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074927888749
2019-04-15 07:49:27,921 [salt.minion      :1432][INFO    ][10504] Starting a new job with PID 10504
2019-04-15 07:49:27,949 [salt.minion      :1711][INFO    ][10504] Returning information for job: 20190415074927888749
2019-04-15 07:49:49,093 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1621.7260499s left)
2019-04-15 07:49:57,932 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415074957922723
2019-04-15 07:49:57,956 [salt.minion      :1432][INFO    ][10593] Starting a new job with PID 10593
2019-04-15 07:49:57,985 [salt.minion      :1711][INFO    ][10593] Returning information for job: 20190415074957922723
2019-04-15 07:50:22,099 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1588.72001386s left)
2019-04-15 07:50:27,974 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075027965634
2019-04-15 07:50:27,994 [salt.minion      :1432][INFO    ][10629] Starting a new job with PID 10629
2019-04-15 07:50:28,025 [salt.minion      :1711][INFO    ][10629] Returning information for job: 20190415075027965634
2019-04-15 07:50:55,136 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1555.68351293s left)
2019-04-15 07:50:58,075 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075058058677
2019-04-15 07:50:58,099 [salt.minion      :1432][INFO    ][10832] Starting a new job with PID 10832
2019-04-15 07:50:58,127 [salt.minion      :1711][INFO    ][10832] Returning information for job: 20190415075058058677
2019-04-15 07:51:27,904 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1522.91476297s left)
2019-04-15 07:51:28,276 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075128264662
2019-04-15 07:51:28,291 [salt.minion      :1432][INFO    ][10920] Starting a new job with PID 10920
2019-04-15 07:51:28,320 [salt.minion      :1711][INFO    ][10920] Returning information for job: 20190415075128264662
2019-04-15 07:51:58,318 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075158309838
2019-04-15 07:51:58,340 [salt.minion      :1432][INFO    ][11160] Starting a new job with PID 11160
2019-04-15 07:51:58,374 [salt.minion      :1711][INFO    ][11160] Returning information for job: 20190415075158309838
2019-04-15 07:52:00,769 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1490.05049801s left)
2019-04-15 07:52:28,398 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075228387857
2019-04-15 07:52:28,422 [salt.minion      :1432][INFO    ][11277] Starting a new job with PID 11277
2019-04-15 07:52:28,449 [salt.minion      :1711][INFO    ][11277] Returning information for job: 20190415075228387857
2019-04-15 07:52:33,634 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1457.18484998s left)
2019-04-15 07:52:58,459 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075258451402
2019-04-15 07:52:58,478 [salt.minion      :1432][INFO    ][11367] Starting a new job with PID 11367
2019-04-15 07:52:58,508 [salt.minion      :1711][INFO    ][11367] Returning information for job: 20190415075258451402
2019-04-15 07:53:06,460 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1424.35873199s left)
2019-04-15 07:53:28,532 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075328523380
2019-04-15 07:53:28,556 [salt.minion      :1432][INFO    ][11405] Starting a new job with PID 11405
2019-04-15 07:53:28,593 [salt.minion      :1711][INFO    ][11405] Returning information for job: 20190415075328523380
2019-04-15 07:53:39,180 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1391.63945794s left)
2019-04-15 07:53:58,617 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075358609612
2019-04-15 07:53:58,634 [salt.minion      :1432][INFO    ][11470] Starting a new job with PID 11470
2019-04-15 07:53:58,664 [salt.minion      :1711][INFO    ][11470] Returning information for job: 20190415075358609612
2019-04-15 07:54:11,963 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1358.85688686s left)
2019-04-15 07:54:28,691 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075428681753
2019-04-15 07:54:28,713 [salt.minion      :1432][INFO    ][11513] Starting a new job with PID 11513
2019-04-15 07:54:28,746 [salt.minion      :1711][INFO    ][11513] Returning information for job: 20190415075428681753
2019-04-15 07:54:44,866 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1325.95349002s left)
2019-04-15 07:54:58,795 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075458784837
2019-04-15 07:54:58,820 [salt.minion      :1432][INFO    ][11580] Starting a new job with PID 11580
2019-04-15 07:54:58,850 [salt.minion      :1711][INFO    ][11580] Returning information for job: 20190415075458784837
2019-04-15 07:55:17,609 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1293.20979095s left)
2019-04-15 07:55:28,832 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075528817251
2019-04-15 07:55:28,850 [salt.minion      :1432][INFO    ][11622] Starting a new job with PID 11622
2019-04-15 07:55:28,882 [salt.minion      :1711][INFO    ][11622] Returning information for job: 20190415075528817251
2019-04-15 07:55:50,346 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1260.47310185s left)
2019-04-15 07:55:58,904 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075558891409
2019-04-15 07:55:58,926 [salt.minion      :1432][INFO    ][11692] Starting a new job with PID 11692
2019-04-15 07:55:58,958 [salt.minion      :1711][INFO    ][11692] Returning information for job: 20190415075558891409
2019-04-15 07:56:23,082 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1227.73757505s left)
2019-04-15 07:56:28,973 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075628960052
2019-04-15 07:56:28,993 [salt.minion      :1432][INFO    ][11728] Starting a new job with PID 11728
2019-04-15 07:56:29,027 [salt.minion      :1711][INFO    ][11728] Returning information for job: 20190415075628960052
2019-04-15 07:56:55,892 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1194.92730188s left)
2019-04-15 07:56:59,073 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075659058784
2019-04-15 07:56:59,095 [salt.minion      :1432][INFO    ][11794] Starting a new job with PID 11794
2019-04-15 07:56:59,125 [salt.minion      :1711][INFO    ][11794] Returning information for job: 20190415075659058784
2019-04-15 07:57:28,578 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1162.24130297s left)
2019-04-15 07:57:29,130 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075729117045
2019-04-15 07:57:29,153 [salt.minion      :1432][INFO    ][11830] Starting a new job with PID 11830
2019-04-15 07:57:29,182 [salt.minion      :1711][INFO    ][11830] Returning information for job: 20190415075729117045
2019-04-15 07:57:59,260 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075759251231
2019-04-15 07:57:59,278 [salt.minion      :1432][INFO    ][11898] Starting a new job with PID 11898
2019-04-15 07:57:59,310 [salt.minion      :1711][INFO    ][11898] Returning information for job: 20190415075759251231
2019-04-15 07:58:01,313 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1129.50599003s left)
2019-04-15 07:58:29,369 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075829359924
2019-04-15 07:58:29,388 [salt.minion      :1432][INFO    ][11932] Starting a new job with PID 11932
2019-04-15 07:58:29,420 [salt.minion      :1711][INFO    ][11932] Returning information for job: 20190415075829359924
2019-04-15 07:58:34,165 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1096.65380096s left)
2019-04-15 07:58:59,485 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075859474026
2019-04-15 07:58:59,506 [salt.minion      :1432][INFO    ][11998] Starting a new job with PID 11998
2019-04-15 07:58:59,538 [salt.minion      :1711][INFO    ][11998] Returning information for job: 20190415075859474026
2019-04-15 07:59:06,958 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1063.86170483s left)
2019-04-15 07:59:29,654 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075929644461
2019-04-15 07:59:29,676 [salt.minion      :1432][INFO    ][12034] Starting a new job with PID 12034
2019-04-15 07:59:29,705 [salt.minion      :1711][INFO    ][12034] Returning information for job: 20190415075929644461
2019-04-15 07:59:39,697 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1031.12269998s left)
2019-04-15 07:59:59,803 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415075959789870
2019-04-15 07:59:59,826 [salt.minion      :1432][INFO    ][12101] Starting a new job with PID 12101
2019-04-15 07:59:59,855 [salt.minion      :1711][INFO    ][12101] Returning information for job: 20190415075959789870
2019-04-15 08:00:12,175 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (998.643837929s left)
2019-04-15 08:00:29,945 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080029937991
2019-04-15 08:00:29,965 [salt.minion      :1432][INFO    ][12137] Starting a new job with PID 12137
2019-04-15 08:00:29,996 [salt.minion      :1711][INFO    ][12137] Returning information for job: 20190415080029937991
2019-04-15 08:00:44,859 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (965.959941864s left)
2019-04-15 08:01:00,093 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080100078744
2019-04-15 08:01:00,115 [salt.minion      :1432][INFO    ][12219] Starting a new job with PID 12219
2019-04-15 08:01:00,146 [salt.minion      :1711][INFO    ][12219] Returning information for job: 20190415080100078744
2019-04-15 08:01:17,578 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (933.241518021s left)
2019-04-15 08:01:30,227 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080130217453
2019-04-15 08:01:30,248 [salt.minion      :1432][INFO    ][12264] Starting a new job with PID 12264
2019-04-15 08:01:30,276 [salt.minion      :1711][INFO    ][12264] Returning information for job: 20190415080130217453
2019-04-15 08:01:50,386 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (900.43324399s left)
2019-04-15 08:02:00,415 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080200406055
2019-04-15 08:02:00,434 [salt.minion      :1432][INFO    ][12334] Starting a new job with PID 12334
2019-04-15 08:02:00,466 [salt.minion      :1711][INFO    ][12334] Returning information for job: 20190415080200406055
2019-04-15 08:02:23,243 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (867.576215029s left)
2019-04-15 08:02:30,562 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080230554548
2019-04-15 08:02:30,584 [salt.minion      :1432][INFO    ][12370] Starting a new job with PID 12370
2019-04-15 08:02:30,616 [salt.minion      :1711][INFO    ][12370] Returning information for job: 20190415080230554548
2019-04-15 08:02:56,166 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (834.653676033s left)
2019-04-15 08:03:00,760 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080300751821
2019-04-15 08:03:00,779 [salt.minion      :1432][INFO    ][12435] Starting a new job with PID 12435
2019-04-15 08:03:00,810 [salt.minion      :1711][INFO    ][12435] Returning information for job: 20190415080300751821
2019-04-15 08:03:28,828 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (801.992822886s left)
2019-04-15 08:03:30,872 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080330859648
2019-04-15 08:03:30,894 [salt.minion      :1432][INFO    ][12471] Starting a new job with PID 12471
2019-04-15 08:03:30,924 [salt.minion      :1711][INFO    ][12471] Returning information for job: 20190415080330859648
2019-04-15 08:04:00,920 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080400911195
2019-04-15 08:04:00,943 [salt.minion      :1432][INFO    ][12542] Starting a new job with PID 12542
2019-04-15 08:04:00,973 [salt.minion      :1711][INFO    ][12542] Returning information for job: 20190415080400911195
2019-04-15 08:04:01,463 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (769.356005907s left)
2019-04-15 08:04:31,044 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080431030545
2019-04-15 08:04:31,063 [salt.minion      :1432][INFO    ][12574] Starting a new job with PID 12574
2019-04-15 08:04:31,091 [salt.minion      :1711][INFO    ][12574] Returning information for job: 20190415080431030545
2019-04-15 08:04:34,187 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (736.632825851s left)
2019-04-15 08:05:01,256 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080501247960
2019-04-15 08:05:01,279 [salt.minion      :1432][INFO    ][12641] Starting a new job with PID 12641
2019-04-15 08:05:01,307 [salt.minion      :1711][INFO    ][12641] Returning information for job: 20190415080501247960
2019-04-15 08:05:06,895 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (703.924351931s left)
2019-04-15 08:05:31,470 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080531461013
2019-04-15 08:05:31,494 [salt.minion      :1432][INFO    ][12677] Starting a new job with PID 12677
2019-04-15 08:05:31,525 [salt.minion      :1711][INFO    ][12677] Returning information for job: 20190415080531461013
2019-04-15 08:05:39,737 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (671.082028866s left)
2019-04-15 08:06:01,554 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080601542418
2019-04-15 08:06:01,571 [salt.minion      :1432][INFO    ][12745] Starting a new job with PID 12745
2019-04-15 08:06:01,603 [salt.minion      :1711][INFO    ][12745] Returning information for job: 20190415080601542418
2019-04-15 08:06:12,504 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (638.315209866s left)
2019-04-15 08:06:31,710 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080631697211
2019-04-15 08:06:31,730 [salt.minion      :1432][INFO    ][12781] Starting a new job with PID 12781
2019-04-15 08:06:31,760 [salt.minion      :1711][INFO    ][12781] Returning information for job: 20190415080631697211
2019-04-15 08:06:45,145 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (605.674607038s left)
2019-04-15 08:07:01,841 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080701832687
2019-04-15 08:07:01,855 [salt.minion      :1432][INFO    ][12851] Starting a new job with PID 12851
2019-04-15 08:07:01,884 [salt.minion      :1711][INFO    ][12851] Returning information for job: 20190415080701832687
2019-04-15 08:07:17,961 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (572.858129025s left)
2019-04-15 08:07:31,915 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080731907378
2019-04-15 08:07:31,937 [salt.minion      :1432][INFO    ][12887] Starting a new job with PID 12887
2019-04-15 08:07:31,966 [salt.minion      :1711][INFO    ][12887] Returning information for job: 20190415080731907378
2019-04-15 08:07:50,578 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (540.240902901s left)
2019-04-15 08:08:02,009 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080801994293
2019-04-15 08:08:02,028 [salt.minion      :1432][INFO    ][12953] Starting a new job with PID 12953
2019-04-15 08:08:02,064 [salt.minion      :1711][INFO    ][12953] Returning information for job: 20190415080801994293
2019-04-15 08:08:23,226 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (507.593658924s left)
2019-04-15 08:08:32,056 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080832042555
2019-04-15 08:08:32,075 [salt.minion      :1432][INFO    ][12991] Starting a new job with PID 12991
2019-04-15 08:08:32,106 [salt.minion      :1711][INFO    ][12991] Returning information for job: 20190415080832042555
2019-04-15 08:08:56,136 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (474.683467865s left)
2019-04-15 08:09:02,234 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080902220515
2019-04-15 08:09:02,256 [salt.minion      :1432][INFO    ][13058] Starting a new job with PID 13058
2019-04-15 08:09:02,291 [salt.minion      :1711][INFO    ][13058] Returning information for job: 20190415080902220515
2019-04-15 08:09:28,868 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (441.951398849s left)
2019-04-15 08:09:32,262 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415080932248641
2019-04-15 08:09:32,282 [salt.minion      :1432][INFO    ][13094] Starting a new job with PID 13094
2019-04-15 08:09:32,313 [salt.minion      :1711][INFO    ][13094] Returning information for job: 20190415080932248641
2019-04-15 08:10:01,556 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (409.263094902s left)
2019-04-15 08:10:02,384 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081002370840
2019-04-15 08:10:02,400 [salt.minion      :1432][INFO    ][13162] Starting a new job with PID 13162
2019-04-15 08:10:02,434 [salt.minion      :1711][INFO    ][13162] Returning information for job: 20190415081002370840
2019-04-15 08:10:32,481 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081032472802
2019-04-15 08:10:32,496 [salt.minion      :1432][INFO    ][13195] Starting a new job with PID 13195
2019-04-15 08:10:32,529 [salt.minion      :1711][INFO    ][13195] Returning information for job: 20190415081032472802
2019-04-15 08:10:34,352 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (376.46735096s left)
2019-04-15 08:11:02,665 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081102653806
2019-04-15 08:11:02,684 [salt.minion      :1432][INFO    ][13401] Starting a new job with PID 13401
2019-04-15 08:11:02,714 [salt.minion      :1711][INFO    ][13401] Returning information for job: 20190415081102653806
2019-04-15 08:11:07,102 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (343.717749834s left)
2019-04-15 08:11:32,764 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081132749247
2019-04-15 08:11:32,780 [salt.minion      :1432][INFO    ][13445] Starting a new job with PID 13445
2019-04-15 08:11:32,809 [salt.minion      :1711][INFO    ][13445] Returning information for job: 20190415081132749247
2019-04-15 08:11:39,726 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (311.093303919s left)
2019-04-15 08:12:02,947 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081202937012
2019-04-15 08:12:02,962 [salt.minion      :1432][INFO    ][13515] Starting a new job with PID 13515
2019-04-15 08:12:02,996 [salt.minion      :1711][INFO    ][13515] Returning information for job: 20190415081202937012
2019-04-15 08:12:12,533 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (278.285881996s left)
2019-04-15 08:12:33,085 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081233072106
2019-04-15 08:12:33,107 [salt.minion      :1432][INFO    ][13547] Starting a new job with PID 13547
2019-04-15 08:12:33,138 [salt.minion      :1711][INFO    ][13547] Returning information for job: 20190415081233072106
2019-04-15 08:12:45,287 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (245.532598019s left)
2019-04-15 08:13:03,112 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081303097423
2019-04-15 08:13:03,130 [salt.minion      :1432][INFO    ][13620] Starting a new job with PID 13620
2019-04-15 08:13:03,158 [salt.minion      :1711][INFO    ][13620] Returning information for job: 20190415081303097423
2019-04-15 08:13:17,985 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (212.833984852s left)
2019-04-15 08:13:33,149 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081333141953
2019-04-15 08:13:33,164 [salt.minion      :1432][INFO    ][13652] Starting a new job with PID 13652
2019-04-15 08:13:33,191 [salt.minion      :1711][INFO    ][13652] Returning information for job: 20190415081333141953
2019-04-15 08:13:50,872 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (179.946943045s left)
2019-04-15 08:14:03,343 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081403331285
2019-04-15 08:14:03,366 [salt.minion      :1432][INFO    ][13724] Starting a new job with PID 13724
2019-04-15 08:14:03,395 [salt.minion      :1711][INFO    ][13724] Returning information for job: 20190415081403331285
2019-04-15 08:14:23,565 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (147.254082918s left)
2019-04-15 08:14:33,409 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081433399336
2019-04-15 08:14:33,430 [salt.minion      :1432][INFO    ][13756] Starting a new job with PID 13756
2019-04-15 08:14:33,462 [salt.minion      :1711][INFO    ][13756] Returning information for job: 20190415081433399336
2019-04-15 08:14:56,303 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (114.516365051s left)
2019-04-15 08:15:03,641 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081503628058
2019-04-15 08:15:03,659 [salt.minion      :1432][INFO    ][13828] Starting a new job with PID 13828
2019-04-15 08:15:03,686 [salt.minion      :1711][INFO    ][13828] Returning information for job: 20190415081503628058
2019-04-15 08:15:28,985 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (81.8340859413s left)
2019-04-15 08:15:33,764 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081533756179
2019-04-15 08:15:33,787 [salt.minion      :1432][INFO    ][13860] Starting a new job with PID 13860
2019-04-15 08:15:33,816 [salt.minion      :1711][INFO    ][13860] Returning information for job: 20190415081533756179
2019-04-15 08:16:01,798 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (49.0215308666s left)
2019-04-15 08:16:03,824 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081603812341
2019-04-15 08:16:03,842 [salt.minion      :1432][INFO    ][13934] Starting a new job with PID 13934
2019-04-15 08:16:03,873 [salt.minion      :1711][INFO    ][13934] Returning information for job: 20190415081603812341
2019-04-15 08:16:33,989 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081633978029
2019-04-15 08:16:34,005 [salt.minion      :1432][INFO    ][13965] Starting a new job with PID 13965
2019-04-15 08:16:34,034 [salt.minion      :1711][INFO    ][13965] Returning information for job: 20190415081633978029
2019-04-15 08:16:34,583 [salt.loaded.ext.module.maas:1023][INFO    ][7375] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (16.2360239029s left)
2019-04-15 08:17:04,094 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081704082258
2019-04-15 08:17:04,115 [salt.minion      :1432][INFO    ][14033] Starting a new job with PID 14033
2019-04-15 08:17:04,148 [salt.minion      :1711][INFO    ][14033] Returning information for job: 20190415081704082258
2019-04-15 08:17:07,297 [salt.state       :302 ][ERROR   ][7375] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-15 08:17:07,298 [salt.state       :1951][INFO    ][7375] Completed state [maas.wait_for_machine_status] at time 08:17:07.297722 duration_in_ms=2266492.552
2019-04-15 08:17:07,304 [salt.minion      :1711][INFO    ][7375] Returning information for job: 20190415073910540588
2019-04-15 08:17:18,226 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command pillar.get with jid 20190415081718217908
2019-04-15 08:17:18,241 [salt.minion      :1432][INFO    ][14053] Starting a new job with PID 14053
2019-04-15 08:17:18,250 [salt.minion      :1711][INFO    ][14053] Returning information for job: 20190415081718217908
2019-04-15 08:17:18,930 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command service.status with jid 20190415081718920367
2019-04-15 08:17:18,947 [salt.minion      :1432][INFO    ][14058] Starting a new job with PID 14058
2019-04-15 08:17:19,699 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14058] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-15 08:17:19,737 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14058] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-15 08:17:19,757 [salt.minion      :1711][INFO    ][14058] Returning information for job: 20190415081718920367
2019-04-15 08:17:20,435 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command state.apply with jid 20190415081720426486
2019-04-15 08:17:20,453 [salt.minion      :1432][INFO    ][14069] Starting a new job with PID 14069
2019-04-15 08:17:28,519 [salt.state       :915 ][INFO    ][14069] Loading fresh modules for state activity
2019-04-15 08:17:29,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command 'salt-minion --version' in directory '/root'
2019-04-15 08:17:29,505 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command 'salt-minion --version' in directory '/root'
2019-04-15 08:17:30,535 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command 'salt-minion --version' in directory '/root'
2019-04-15 08:17:30,887 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command 'salt-minion --version' in directory '/root'
2019-04-15 08:17:33,121 [salt.state       :1780][INFO    ][14069] Running state [salt-minion] at time 08:17:33.121759
2019-04-15 08:17:33,122 [salt.state       :1813][INFO    ][14069] Executing state pkg.installed for [salt-minion]
2019-04-15 08:17:33,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-15 08:17:33,245 [salt.state       :300 ][INFO    ][14069] All specified packages are already installed
2019-04-15 08:17:33,246 [salt.state       :1951][INFO    ][14069] Completed state [salt-minion] at time 08:17:33.246177 duration_in_ms=124.418
2019-04-15 08:17:33,246 [salt.state       :1780][INFO    ][14069] Running state [salt_minion_dependency_packages] at time 08:17:33.246549
2019-04-15 08:17:33,246 [salt.state       :1813][INFO    ][14069] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-15 08:17:33,257 [salt.state       :300 ][INFO    ][14069] All specified packages are already installed
2019-04-15 08:17:33,257 [salt.state       :1951][INFO    ][14069] Completed state [salt_minion_dependency_packages] at time 08:17:33.257283 duration_in_ms=10.734
2019-04-15 08:17:33,263 [salt.state       :1780][INFO    ][14069] Running state [/etc/salt/minion.d/minion.conf] at time 08:17:33.263747
2019-04-15 08:17:33,264 [salt.state       :1813][INFO    ][14069] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-15 08:17:33,545 [salt.state       :300 ][INFO    ][14069] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-15 08:17:33,545 [salt.state       :1951][INFO    ][14069] Completed state [/etc/salt/minion.d/minion.conf] at time 08:17:33.545739 duration_in_ms=281.993
2019-04-15 08:17:33,546 [salt.state       :1780][INFO    ][14069] Running state [python-netaddr] at time 08:17:33.546036
2019-04-15 08:17:33,546 [salt.state       :1813][INFO    ][14069] Executing state pkg.installed for [python-netaddr]
2019-04-15 08:17:33,555 [salt.state       :300 ][INFO    ][14069] All specified packages are already installed
2019-04-15 08:17:33,556 [salt.state       :1951][INFO    ][14069] Completed state [python-netaddr] at time 08:17:33.556154 duration_in_ms=10.117
2019-04-15 08:17:33,559 [salt.state       :1780][INFO    ][14069] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 08:17:33.559806
2019-04-15 08:17:33,560 [salt.state       :1813][INFO    ][14069] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-15 08:17:33,574 [salt.state       :300 ][INFO    ][14069] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-15 08:17:33,575 [salt.state       :1951][INFO    ][14069] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 08:17:33.575291 duration_in_ms=15.483
2019-04-15 08:17:33,577 [salt.state       :1780][INFO    ][14069] Running state [salt-minion] at time 08:17:33.577014
2019-04-15 08:17:33,577 [salt.state       :1813][INFO    ][14069] Executing state service.running for [salt-minion]
2019-04-15 08:17:33,578 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-15 08:17:33,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-15 08:17:33,642 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-15 08:17:33,660 [salt.state       :300 ][INFO    ][14069] The service salt-minion is already running
2019-04-15 08:17:33,661 [salt.state       :1951][INFO    ][14069] Completed state [salt-minion] at time 08:17:33.660908 duration_in_ms=83.894
2019-04-15 08:17:33,666 [salt.state       :1780][INFO    ][14069] Running state [/etc/salt/grains.d] at time 08:17:33.666049
2019-04-15 08:17:33,666 [salt.state       :1813][INFO    ][14069] Executing state file.directory for [/etc/salt/grains.d]
2019-04-15 08:17:33,668 [salt.state       :300 ][INFO    ][14069] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-15 08:17:33,668 [salt.state       :1951][INFO    ][14069] Completed state [/etc/salt/grains.d] at time 08:17:33.668576 duration_in_ms=2.527
2019-04-15 08:17:33,669 [salt.state       :1780][INFO    ][14069] Running state [/etc/salt/grains] at time 08:17:33.669562
2019-04-15 08:17:33,670 [salt.state       :1813][INFO    ][14069] Executing state file.managed for [/etc/salt/grains]
2019-04-15 08:17:33,670 [salt.state       :300 ][INFO    ][14069] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-15 08:17:33,671 [salt.state       :1951][INFO    ][14069] Completed state [/etc/salt/grains] at time 08:17:33.671129 duration_in_ms=1.567
2019-04-15 08:17:33,671 [salt.state       :1780][INFO    ][14069] Running state [/etc/salt/grains.d/placeholder] at time 08:17:33.671840
2019-04-15 08:17:33,672 [salt.state       :1813][INFO    ][14069] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-15 08:17:33,673 [salt.state       :300 ][INFO    ][14069] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-15 08:17:33,673 [salt.state       :1951][INFO    ][14069] Completed state [/etc/salt/grains.d/placeholder] at time 08:17:33.673358 duration_in_ms=1.517
2019-04-15 08:17:33,674 [salt.state       :1780][INFO    ][14069] Running state [/etc/salt/grains.d/sphinx] at time 08:17:33.674066
2019-04-15 08:17:33,674 [salt.state       :1813][INFO    ][14069] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-15 08:17:33,678 [salt.state       :300 ][INFO    ][14069] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-15 08:17:33,678 [salt.state       :1951][INFO    ][14069] Completed state [/etc/salt/grains.d/sphinx] at time 08:17:33.678644 duration_in_ms=4.578
2019-04-15 08:17:33,682 [salt.state       :1780][INFO    ][14069] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:17:33.682506
2019-04-15 08:17:33,683 [salt.state       :1813][INFO    ][14069] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-15 08:17:33,683 [salt.state       :300 ][INFO    ][14069] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-15 08:17:33,684 [salt.state       :1951][INFO    ][14069] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:17:33.684041 duration_in_ms=1.535
2019-04-15 08:17:33,684 [salt.state       :1780][INFO    ][14069] Running state [/etc/salt/grains.d/dns_records] at time 08:17:33.684797
2019-04-15 08:17:33,685 [salt.state       :1813][INFO    ][14069] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-15 08:17:33,689 [salt.state       :300 ][INFO    ][14069] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-15 08:17:33,689 [salt.state       :1951][INFO    ][14069] Completed state [/etc/salt/grains.d/dns_records] at time 08:17:33.689477 duration_in_ms=4.68
2019-04-15 08:17:33,691 [salt.state       :1780][INFO    ][14069] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 08:17:33.690978
2019-04-15 08:17:33,691 [salt.state       :1813][INFO    ][14069] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-15 08:17:33,691 [salt.state       :300 ][INFO    ][14069] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-15 08:17:33,692 [salt.state       :1951][INFO    ][14069] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 08:17:33.692268 duration_in_ms=1.289
2019-04-15 08:17:33,693 [salt.state       :1780][INFO    ][14069] Running state [/etc/salt/grains.d/salt] at time 08:17:33.693070
2019-04-15 08:17:33,693 [salt.state       :1813][INFO    ][14069] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-15 08:17:33,695 [salt.state       :300 ][INFO    ][14069] File /etc/salt/grains.d/salt is in the correct state
2019-04-15 08:17:33,695 [salt.state       :1951][INFO    ][14069] Completed state [/etc/salt/grains.d/salt] at time 08:17:33.695882 duration_in_ms=2.813
2019-04-15 08:17:33,697 [salt.state       :1780][INFO    ][14069] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 08:17:33.697322
2019-04-15 08:17:33,697 [salt.state       :1813][INFO    ][14069] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-15 08:17:33,698 [salt.state       :300 ][INFO    ][14069] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-15 08:17:33,698 [salt.state       :1951][INFO    ][14069] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 08:17:33.698614 duration_in_ms=1.292
2019-04-15 08:17:33,702 [salt.state       :1780][INFO    ][14069] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:17:33.702572
2019-04-15 08:17:33,702 [salt.state       :1813][INFO    ][14069] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-15 08:17:33,703 [salt.state       :300 ][INFO    ][14069] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-15 08:17:33,703 [salt.state       :1951][INFO    ][14069] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:17:33.703448 duration_in_ms=0.876
2019-04-15 08:17:33,704 [salt.state       :1780][INFO    ][14069] Running state [mine.update] at time 08:17:33.704143
2019-04-15 08:17:33,704 [salt.state       :1813][INFO    ][14069] Executing state module.wait for [mine.update]
2019-04-15 08:17:33,704 [salt.state       :300 ][INFO    ][14069] No changes made for mine.update
2019-04-15 08:17:33,704 [salt.state       :1951][INFO    ][14069] Completed state [mine.update] at time 08:17:33.704935 duration_in_ms=0.792
2019-04-15 08:17:33,705 [salt.state       :1780][INFO    ][14069] Running state [ca-certificates] at time 08:17:33.705207
2019-04-15 08:17:33,705 [salt.state       :1813][INFO    ][14069] Executing state pkg.installed for [ca-certificates]
2019-04-15 08:17:33,716 [salt.state       :300 ][INFO    ][14069] All specified packages are already installed
2019-04-15 08:17:33,716 [salt.state       :1951][INFO    ][14069] Completed state [ca-certificates] at time 08:17:33.716826 duration_in_ms=11.619
2019-04-15 08:17:33,717 [salt.state       :1780][INFO    ][14069] Running state [update-ca-certificates] at time 08:17:33.717544
2019-04-15 08:17:33,717 [salt.state       :1813][INFO    ][14069] Executing state cmd.wait for [update-ca-certificates]
2019-04-15 08:17:33,718 [salt.state       :300 ][INFO    ][14069] No changes made for update-ca-certificates
2019-04-15 08:17:33,718 [salt.state       :1951][INFO    ][14069] Completed state [update-ca-certificates] at time 08:17:33.718362 duration_in_ms=0.818
2019-04-15 08:17:33,718 [salt.state       :1780][INFO    ][14069] Running state [iptables] at time 08:17:33.718643
2019-04-15 08:17:33,719 [salt.state       :1813][INFO    ][14069] Executing state pkg.installed for [iptables]
2019-04-15 08:17:33,729 [salt.state       :300 ][INFO    ][14069] All specified packages are already installed
2019-04-15 08:17:33,729 [salt.state       :1951][INFO    ][14069] Completed state [iptables] at time 08:17:33.729776 duration_in_ms=11.133
2019-04-15 08:17:33,730 [salt.state       :1780][INFO    ][14069] Running state [iptables-persistent] at time 08:17:33.730065
2019-04-15 08:17:33,730 [salt.state       :1813][INFO    ][14069] Executing state pkg.installed for [iptables-persistent]
2019-04-15 08:17:33,740 [salt.state       :300 ][INFO    ][14069] All specified packages are already installed
2019-04-15 08:17:33,740 [salt.state       :1951][INFO    ][14069] Completed state [iptables-persistent] at time 08:17:33.740301 duration_in_ms=10.236
2019-04-15 08:17:33,742 [salt.state       :1780][INFO    ][14069] Running state [iptables_modules_v4_load] at time 08:17:33.742380
2019-04-15 08:17:33,743 [salt.state       :1813][INFO    ][14069] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-15 08:17:33,743 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command 'lsmod' in directory '/root'
2019-04-15 08:17:33,765 [salt.state       :300 ][INFO    ][14069] Kernel modules iptable_filter, ip_tables are already present
2019-04-15 08:17:33,766 [salt.state       :1951][INFO    ][14069] Completed state [iptables_modules_v4_load] at time 08:17:33.766341 duration_in_ms=23.962
2019-04-15 08:17:33,767 [salt.state       :1780][INFO    ][14069] Running state [/etc/iptables/rules.v4] at time 08:17:33.767274
2019-04-15 08:17:33,767 [salt.state       :1813][INFO    ][14069] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-15 08:17:33,869 [salt.state       :300 ][INFO    ][14069] File /etc/iptables/rules.v4 is in the correct state
2019-04-15 08:17:33,870 [salt.state       :1951][INFO    ][14069] Completed state [/etc/iptables/rules.v4] at time 08:17:33.870004 duration_in_ms=102.73
2019-04-15 08:17:33,871 [salt.state       :1780][INFO    ][14069] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 08:17:33.871473
2019-04-15 08:17:33,871 [salt.state       :1813][INFO    ][14069] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-15 08:17:33,872 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-15 08:17:33,896 [salt.state       :300 ][INFO    ][14069] onlyif execution failed
2019-04-15 08:17:33,897 [salt.state       :1951][INFO    ][14069] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 08:17:33.897391 duration_in_ms=25.917
2019-04-15 08:17:33,899 [salt.state       :1780][INFO    ][14069] Running state [netfilter-persistent] at time 08:17:33.899409
2019-04-15 08:17:33,900 [salt.state       :1813][INFO    ][14069] Executing state service.running for [netfilter-persistent]
2019-04-15 08:17:33,901 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-15 08:17:33,926 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-15 08:17:33,945 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-15 08:17:33,962 [salt.state       :300 ][INFO    ][14069] The service netfilter-persistent is already running
2019-04-15 08:17:33,962 [salt.state       :1951][INFO    ][14069] Completed state [netfilter-persistent] at time 08:17:33.962456 duration_in_ms=63.048
2019-04-15 08:17:33,965 [salt.state       :1780][INFO    ][14069] Running state [iptables_extra.remove_stale_tables] at time 08:17:33.964993
2019-04-15 08:17:33,965 [salt.state       :1813][INFO    ][14069] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-15 08:17:33,966 [salt.state       :300 ][INFO    ][14069] No changes made for iptables_extra.remove_stale_tables
2019-04-15 08:17:33,966 [salt.state       :1951][INFO    ][14069] Completed state [iptables_extra.remove_stale_tables] at time 08:17:33.966491 duration_in_ms=1.498
2019-04-15 08:17:33,967 [salt.state       :1780][INFO    ][14069] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 08:17:33.967595
2019-04-15 08:17:33,968 [salt.state       :1813][INFO    ][14069] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-15 08:17:33,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14069] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-15 08:17:33,984 [salt.state       :300 ][INFO    ][14069] onlyif execution failed
2019-04-15 08:17:33,984 [salt.state       :1951][INFO    ][14069] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 08:17:33.984600 duration_in_ms=17.005
2019-04-15 08:17:33,985 [salt.state       :1780][INFO    ][14069] Running state [/etc/iptables/rules.v6] at time 08:17:33.985703
2019-04-15 08:17:33,986 [salt.state       :1813][INFO    ][14069] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-15 08:17:33,986 [salt.state       :300 ][INFO    ][14069] File /etc/iptables/rules.v6 is not present
2019-04-15 08:17:33,988 [salt.state       :1951][INFO    ][14069] Completed state [/etc/iptables/rules.v6] at time 08:17:33.988653 duration_in_ms=2.95
2019-04-15 08:17:33,989 [salt.state       :1780][INFO    ][14069] Running state [iptables_extra.flush_all] at time 08:17:33.989673
2019-04-15 08:17:33,990 [salt.state       :1813][INFO    ][14069] Executing state module.wait for [iptables_extra.flush_all]
2019-04-15 08:17:33,990 [salt.state       :300 ][INFO    ][14069] No changes made for iptables_extra.flush_all
2019-04-15 08:17:33,991 [salt.state       :1951][INFO    ][14069] Completed state [iptables_extra.flush_all] at time 08:17:33.990836 duration_in_ms=1.162
2019-04-15 08:17:33,995 [salt.minion      :1711][INFO    ][14069] Returning information for job: 20190415081720426486
2019-04-15 08:17:34,677 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command state.apply with jid 20190415081734667023
2019-04-15 08:17:34,698 [salt.minion      :1432][INFO    ][14160] Starting a new job with PID 14160
2019-04-15 08:17:35,812 [salt.state       :915 ][INFO    ][14160] Loading fresh modules for state activity
2019-04-15 08:17:37,030 [salt.state       :1780][INFO    ][14160] Running state [maas-rack-controller] at time 08:17:37.030410
2019-04-15 08:17:37,030 [salt.state       :1813][INFO    ][14160] Executing state pkg.installed for [maas-rack-controller]
2019-04-15 08:17:37,031 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14160] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-15 08:17:37,151 [salt.state       :300 ][INFO    ][14160] All specified packages are already installed
2019-04-15 08:17:37,152 [salt.state       :1951][INFO    ][14160] Completed state [maas-rack-controller] at time 08:17:37.151964 duration_in_ms=121.554
2019-04-15 08:17:37,152 [salt.state       :1780][INFO    ][14160] Running state [ipmitool] at time 08:17:37.152622
2019-04-15 08:17:37,153 [salt.state       :1813][INFO    ][14160] Executing state pkg.installed for [ipmitool]
2019-04-15 08:17:37,163 [salt.state       :300 ][INFO    ][14160] All specified packages are already installed
2019-04-15 08:17:37,163 [salt.state       :1951][INFO    ][14160] Completed state [ipmitool] at time 08:17:37.163708 duration_in_ms=11.086
2019-04-15 08:17:37,166 [salt.state       :1780][INFO    ][14160] Running state [/etc/maas/rackd.conf] at time 08:17:37.166672
2019-04-15 08:17:37,167 [salt.state       :1813][INFO    ][14160] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-15 08:17:37,168 [salt.state       :300 ][INFO    ][14160] No changes needed to be made
2019-04-15 08:17:37,168 [salt.state       :1951][INFO    ][14160] Completed state [/etc/maas/rackd.conf] at time 08:17:37.168817 duration_in_ms=2.145
2019-04-15 08:17:37,169 [salt.state       :1780][INFO    ][14160] Running state [/etc/maas/rackd.conf] at time 08:17:37.169094
2019-04-15 08:17:37,169 [salt.state       :1813][INFO    ][14160] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-15 08:17:37,169 [salt.loaded.int.states.file:2298][WARNING ][14160] 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-04-15 08:17:37,170 [salt.state       :300 ][INFO    ][14160] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-15 08:17:37,170 [salt.state       :1951][INFO    ][14160] Completed state [/etc/maas/rackd.conf] at time 08:17:37.170733 duration_in_ms=1.638
2019-04-15 08:17:37,171 [salt.state       :1780][INFO    ][14160] Running state [maas-rackd] at time 08:17:37.171772
2019-04-15 08:17:37,172 [salt.state       :1813][INFO    ][14160] Executing state service.running for [maas-rackd]
2019-04-15 08:17:37,172 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14160] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-15 08:17:37,206 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14160] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-15 08:17:37,225 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14160] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-15 08:17:37,248 [salt.state       :300 ][INFO    ][14160] The service maas-rackd is already running
2019-04-15 08:17:37,248 [salt.state       :1951][INFO    ][14160] Completed state [maas-rackd] at time 08:17:37.248585 duration_in_ms=76.812
2019-04-15 08:17:37,250 [salt.minion      :1711][INFO    ][14160] Returning information for job: 20190415081734667023
2019-04-15 08:17:37,928 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command state.apply with jid 20190415081737918015
2019-04-15 08:17:37,945 [salt.minion      :1432][INFO    ][14184] Starting a new job with PID 14184
2019-04-15 08:17:39,056 [salt.state       :915 ][INFO    ][14184] Loading fresh modules for state activity
2019-04-15 08:17:40,429 [salt.state       :1780][INFO    ][14184] Running state [maas-region-controller] at time 08:17:40.429084
2019-04-15 08:17:40,429 [salt.state       :1813][INFO    ][14184] Executing state pkg.installed for [maas-region-controller]
2019-04-15 08:17:40,430 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14184] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-15 08:17:40,538 [salt.state       :300 ][INFO    ][14184] All specified packages are already installed
2019-04-15 08:17:40,538 [salt.state       :1951][INFO    ][14184] Completed state [maas-region-controller] at time 08:17:40.538689 duration_in_ms=109.605
2019-04-15 08:17:40,539 [salt.state       :1780][INFO    ][14184] Running state [python-oauth] at time 08:17:40.539638
2019-04-15 08:17:40,540 [salt.state       :1813][INFO    ][14184] Executing state pkg.installed for [python-oauth]
2019-04-15 08:17:40,548 [salt.state       :300 ][INFO    ][14184] All specified packages are already installed
2019-04-15 08:17:40,549 [salt.state       :1951][INFO    ][14184] Completed state [python-oauth] at time 08:17:40.548952 duration_in_ms=9.314
2019-04-15 08:17:40,553 [salt.state       :1780][INFO    ][14184] Running state [/etc/maas/regiond.conf] at time 08:17:40.553088
2019-04-15 08:17:40,553 [salt.state       :1813][INFO    ][14184] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-15 08:17:40,558 [salt.state       :300 ][INFO    ][14184] No changes needed to be made
2019-04-15 08:17:40,558 [salt.state       :1951][INFO    ][14184] Completed state [/etc/maas/regiond.conf] at time 08:17:40.558515 duration_in_ms=5.427
2019-04-15 08:17:40,559 [salt.state       :1780][INFO    ][14184] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:17:40.559227
2019-04-15 08:17:40,559 [salt.state       :1813][INFO    ][14184] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-15 08:17:40,613 [salt.state       :300 ][INFO    ][14184] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-15 08:17:40,614 [salt.state       :1951][INFO    ][14184] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:17:40.614186 duration_in_ms=54.959
2019-04-15 08:17:40,614 [salt.state       :1780][INFO    ][14184] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:17:40.614778
2019-04-15 08:17:40,615 [salt.state       :1813][INFO    ][14184] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-15 08:17:40,620 [salt.state       :300 ][INFO    ][14184] No changes needed to be made
2019-04-15 08:17:40,620 [salt.state       :1951][INFO    ][14184] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:17:40.620271 duration_in_ms=5.492
2019-04-15 08:17:40,620 [salt.state       :1780][INFO    ][14184] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:17:40.620800
2019-04-15 08:17:40,621 [salt.state       :1813][INFO    ][14184] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-15 08:17:40,626 [salt.state       :300 ][INFO    ][14184] No changes needed to be made
2019-04-15 08:17:40,626 [salt.state       :1951][INFO    ][14184] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:17:40.626504 duration_in_ms=5.704
2019-04-15 08:17:40,627 [salt.state       :1780][INFO    ][14184] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:17:40.627646
2019-04-15 08:17:40,628 [salt.state       :1813][INFO    ][14184] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-15 08:17:40,656 [salt.state       :300 ][INFO    ][14184] No changes needed to be made
2019-04-15 08:17:40,657 [salt.state       :1951][INFO    ][14184] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:17:40.656972 duration_in_ms=29.328
2019-04-15 08:17:40,657 [salt.state       :1780][INFO    ][14184] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:17:40.657495
2019-04-15 08:17:40,657 [salt.state       :1813][INFO    ][14184] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-15 08:17:40,673 [salt.state       :300 ][INFO    ][14184] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-15 08:17:40,674 [salt.state       :1951][INFO    ][14184] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:17:40.674000 duration_in_ms=16.505
2019-04-15 08:17:40,675 [salt.state       :1780][INFO    ][14184] Running state [a2enmod headers] at time 08:17:40.675691
2019-04-15 08:17:40,676 [salt.state       :1813][INFO    ][14184] Executing state cmd.run for [a2enmod headers]
2019-04-15 08:17:40,676 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14184] Executing command 'a2enmod headers' in directory '/root'
2019-04-15 08:17:40,761 [salt.state       :300 ][INFO    ][14184] {'pid': 14203, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-15 08:17:40,762 [salt.state       :1951][INFO    ][14184] Completed state [a2enmod headers] at time 08:17:40.762094 duration_in_ms=86.403
2019-04-15 08:17:40,762 [salt.state       :1780][INFO    ][14184] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:17:40.762763
2019-04-15 08:17:40,763 [salt.state       :1813][INFO    ][14184] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-15 08:17:40,787 [salt.state       :300 ][INFO    ][14184] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-15 08:17:40,787 [salt.state       :1951][INFO    ][14184] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:17:40.787436 duration_in_ms=24.674
2019-04-15 08:17:40,788 [salt.state       :1780][INFO    ][14184] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:17:40.788073
2019-04-15 08:17:40,788 [salt.state       :1813][INFO    ][14184] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-15 08:17:40,842 [salt.state       :300 ][INFO    ][14184] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-15 08:17:40,842 [salt.state       :1951][INFO    ][14184] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:17:40.842682 duration_in_ms=54.609
2019-04-15 08:17:40,843 [salt.state       :1780][INFO    ][14184] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:17:40.843230
2019-04-15 08:17:40,843 [salt.state       :1813][INFO    ][14184] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-15 08:17:40,901 [salt.state       :300 ][INFO    ][14184] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-15 08:17:40,902 [salt.state       :1951][INFO    ][14184] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:17:40.902049 duration_in_ms=58.819
2019-04-15 08:17:40,902 [salt.state       :1780][INFO    ][14184] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:17:40.902600
2019-04-15 08:17:40,903 [salt.state       :1813][INFO    ][14184] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-15 08:17:40,971 [salt.state       :300 ][INFO    ][14184] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-15 08:17:40,971 [salt.state       :1951][INFO    ][14184] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:17:40.971816 duration_in_ms=69.215
2019-04-15 08:17:40,972 [salt.state       :1780][INFO    ][14184] Running state [/root/.pgpass] at time 08:17:40.972105
2019-04-15 08:17:40,972 [salt.state       :1813][INFO    ][14184] Executing state file.managed for [/root/.pgpass]
2019-04-15 08:17:41,022 [salt.state       :300 ][INFO    ][14184] File /root/.pgpass is in the correct state
2019-04-15 08:17:41,022 [salt.state       :1951][INFO    ][14184] Completed state [/root/.pgpass] at time 08:17:41.022408 duration_in_ms=50.302
2019-04-15 08:17:41,028 [salt.state       :1780][INFO    ][14184] Running state [maas-region syncdb --noinput] at time 08:17:41.028073
2019-04-15 08:17:41,028 [salt.state       :1813][INFO    ][14184] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-15 08:17:41,029 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14184] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-15 08:17:43,828 [salt.state       :300 ][INFO    ][14184] {'pid': 14216, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, piston3, auth, sessions, contenttypes, sites, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-15 08:17:43,829 [salt.state       :1951][INFO    ][14184] Completed state [maas-region syncdb --noinput] at time 08:17:43.828887 duration_in_ms=2800.812
2019-04-15 08:17:43,829 [salt.state       :2022][WARNING ][14184] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-15 08:17:43,833 [salt.state       :1780][INFO    ][14184] Running state [maas-regiond] at time 08:17:43.833608
2019-04-15 08:17:43,834 [salt.state       :1813][INFO    ][14184] Executing state service.running for [maas-regiond]
2019-04-15 08:17:43,835 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14184] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-15 08:17:43,878 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14184] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-15 08:17:43,894 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14184] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-15 08:17:43,914 [salt.state       :300 ][INFO    ][14184] The service maas-regiond is already running
2019-04-15 08:17:43,915 [salt.state       :1951][INFO    ][14184] Completed state [maas-regiond] at time 08:17:43.915409 duration_in_ms=81.801
2019-04-15 08:17:43,918 [salt.state       :1780][INFO    ][14184] Running state [bind9] at time 08:17:43.918580
2019-04-15 08:17:43,919 [salt.state       :1813][INFO    ][14184] Executing state service.running for [bind9]
2019-04-15 08:17:43,920 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14184] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-15 08:17:43,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14184] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-15 08:17:43,965 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14184] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-15 08:17:43,985 [salt.state       :300 ][INFO    ][14184] The service bind9 is already running
2019-04-15 08:17:43,986 [salt.state       :1951][INFO    ][14184] Completed state [bind9] at time 08:17:43.986222 duration_in_ms=67.643
2019-04-15 08:17:43,989 [salt.state       :1780][INFO    ][14184] Running state [apache2] at time 08:17:43.989466
2019-04-15 08:17:43,990 [salt.state       :1813][INFO    ][14184] Executing state service.running for [apache2]
2019-04-15 08:17:43,991 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14184] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-15 08:17:44,012 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14184] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-15 08:17:44,029 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14184] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-15 08:17:44,061 [salt.state       :300 ][INFO    ][14184] The service apache2 is already running
2019-04-15 08:17:44,061 [salt.state       :1951][INFO    ][14184] Completed state [apache2] at time 08:17:44.061718 duration_in_ms=72.251
2019-04-15 08:17:44,063 [salt.state       :1780][INFO    ][14184] Running state [maasng.wait_for_http_code] at time 08:17:44.063583
2019-04-15 08:17:44,064 [salt.state       :1813][INFO    ][14184] Executing state module.run for [maasng.wait_for_http_code]
2019-04-15 08:17:44,064 [salt.utils.decorators:613 ][WARNING ][14184] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 08:17:44,205 [salt.state       :300 ][INFO    ][14184] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-15 08:17:44,206 [salt.state       :1951][INFO    ][14184] Completed state [maasng.wait_for_http_code] at time 08:17:44.205902 duration_in_ms=142.318
2019-04-15 08:17:44,207 [salt.state       :1780][INFO    ][14184] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:17:44.207755
2019-04-15 08:17:44,208 [salt.state       :1813][INFO    ][14184] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-15 08:17:44,208 [salt.state       :300 ][INFO    ][14184] /var/lib/maas/.setup_admin exists
2019-04-15 08:17:44,209 [salt.state       :1951][INFO    ][14184] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:17:44.209317 duration_in_ms=1.562
2019-04-15 08:17:44,210 [salt.state       :1780][INFO    ][14184] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:17:44.210413
2019-04-15 08:17:44,213 [salt.state       :1813][INFO    ][14184] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-15 08:17:44,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14184] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-15 08:17:46,252 [salt.state       :300 ][INFO    ][14184] {'pid': 14237, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-15 08:17:46,252 [salt.state       :1951][INFO    ][14184] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:17:46.252798 duration_in_ms=2042.385
2019-04-15 08:17:46,258 [salt.state       :1780][INFO    ][14184] Running state [maas_region_boot_source_resources_mirror] at time 08:17:46.258390
2019-04-15 08:17:46,258 [salt.state       :1813][INFO    ][14184] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-15 08:17:46,327 [salt.state       :300 ][INFO    ][14184] {'changes': {}}
2019-04-15 08:17:46,328 [salt.state       :1951][INFO    ][14184] Completed state [maas_region_boot_source_resources_mirror] at time 08:17:46.328307 duration_in_ms=69.917
2019-04-15 08:17:46,329 [salt.state       :1780][INFO    ][14184] Running state [maasng.boot_resources_import] at time 08:17:46.329076
2019-04-15 08:17:46,329 [salt.state       :1813][INFO    ][14184] Executing state module.run for [maasng.boot_resources_import]
2019-04-15 08:17:46,329 [salt.utils.decorators:613 ][WARNING ][14184] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 08:17:46,406 [salt.loaded.ext.module.maasng:1600][INFO    ][14184] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-15 08:17:51,455 [salt.loaded.ext.module.maasng:1600][INFO    ][14184] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-15 08:17:53,012 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081752992631
2019-04-15 08:17:53,039 [salt.minion      :1432][INFO    ][14378] Starting a new job with PID 14378
2019-04-15 08:17:53,086 [salt.minion      :1711][INFO    ][14378] Returning information for job: 20190415081752992631
2019-04-15 08:17:56,563 [salt.state       :300 ][INFO    ][14184] {'ret': True}
2019-04-15 08:17:56,564 [salt.state       :1951][INFO    ][14184] Completed state [maasng.boot_resources_import] at time 08:17:56.563972 duration_in_ms=10234.895
2019-04-15 08:17:56,564 [salt.state       :1780][INFO    ][14184] Running state [maas_region_boot_sources_selection_xenial] at time 08:17:56.564869
2019-04-15 08:17:56,565 [salt.state       :1813][INFO    ][14184] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-15 08:17:56,699 [salt.state       :300 ][INFO    ][14184] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-15 08:17:56,700 [salt.state       :1951][INFO    ][14184] Completed state [maas_region_boot_sources_selection_xenial] at time 08:17:56.699925 duration_in_ms=135.056
2019-04-15 08:17:56,700 [salt.state       :1780][INFO    ][14184] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 08:17:56.700809
2019-04-15 08:17:56,701 [salt.state       :1813][INFO    ][14184] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-15 08:17:56,701 [salt.utils.decorators:613 ][WARNING ][14184] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 08:17:56,701 [salt.loaded.ext.module.maasng:1771][INFO    ][14184] boot-sources sync initiated for ALL Rack's
2019-04-15 08:17:57,493 [salt.state       :300 ][INFO    ][14184] {'ret': True}
2019-04-15 08:17:57,494 [salt.state       :1951][INFO    ][14184] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 08:17:57.494120 duration_in_ms=793.31
2019-04-15 08:17:57,496 [salt.state       :1780][INFO    ][14184] Running state [maas.process_maas_config] at time 08:17:57.496012
2019-04-15 08:17:57,496 [salt.state       :1813][INFO    ][14184] Executing state module.run for [maas.process_maas_config]
2019-04-15 08:17:57,496 [salt.utils.decorators:613 ][WARNING ][14184] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 08:17:57,497 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=enable_http_proxy value=True
2019-04-15 08:17:57,534 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=upstream_dns value=8.8.8.8
2019-04-15 08:17:58,774 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=commissioning_distro_series value=xenial
2019-04-15 08:17:58,825 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=default_osystem value=ubuntu
2019-04-15 08:17:58,868 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=active_discovery_interval value=600
2019-04-15 08:17:58,908 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=dnssec_validation value=no
2019-04-15 08:17:58,944 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=maas_name value=mas01
2019-04-15 08:17:58,980 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=network_discovery value=enabled
2019-04-15 08:17:59,053 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=enable_third_party_drivers value=True
2019-04-15 08:17:59,095 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=default_storage_layout value=lvm
2019-04-15 08:17:59,132 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=ntp_external_only value=True
2019-04-15 08:17:59,174 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-15 08:17:59,216 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=default_distro_series value=xenial
2019-04-15 08:17:59,261 [salt.loaded.ext.module.maas:92  ][INFO    ][14184] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-15 08:17:59,374 [salt.state       :300 ][INFO    ][14184] {'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-04-15 08:17:59,375 [salt.state       :1951][INFO    ][14184] Completed state [maas.process_maas_config] at time 08:17:59.375169 duration_in_ms=1879.155
2019-04-15 08:17:59,376 [salt.state       :1780][INFO    ][14184] Running state [pxe_admin] at time 08:17:59.376514
2019-04-15 08:17:59,377 [salt.state       :1813][INFO    ][14184] Executing state maasng.fabric_present for [pxe_admin]
2019-04-15 08:17:59,430 [salt.loaded.ext.module.maasng:945 ][INFO    ][14184] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'e7fy7t', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-15 08:17:59,495 [salt.loaded.ext.module.maasng:1008][WARNING ][14184] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-15 08:17:59,495 [salt.loaded.ext.module.maasng:1011][WARNING ][14184] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-15 08:17:59,544 [salt.state       :300 ][INFO    ][14184] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-15 08:17:59,544 [salt.state       :1951][INFO    ][14184] Completed state [pxe_admin] at time 08:17:59.544889 duration_in_ms=168.374
2019-04-15 08:17:59,545 [salt.state       :1780][INFO    ][14184] Running state [vlan 0] at time 08:17:59.545280
2019-04-15 08:17:59,545 [salt.state       :1813][INFO    ][14184] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-15 08:17:59,597 [salt.loaded.ext.module.maasng:945 ][INFO    ][14184] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'e7fy7t', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-15 08:17:59,674 [salt.loaded.ext.module.maasng:945 ][INFO    ][14184] [{u'name': u'fabric-0', 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'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', 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': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'e7fy7t', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-04-15 08:17:59,870 [salt.loaded.ext.module.maasng:945 ][INFO    ][14184] [{u'id': 0, 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'e7fy7t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-04-15 08:17:59,938 [salt.state       :300 ][INFO    ][14184] {'new': 'Vlan untagged was updated'}
2019-04-15 08:17:59,938 [salt.state       :1951][INFO    ][14184] Completed state [vlan 0] at time 08:17:59.938465 duration_in_ms=393.184
2019-04-15 08:17:59,940 [salt.state       :1780][INFO    ][14184] Running state [192.168.11.0/24] at time 08:17:59.940387
2019-04-15 08:17:59,940 [salt.state       :1813][INFO    ][14184] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-15 08:18:00,090 [salt.loaded.ext.module.maasng:945 ][INFO    ][14184] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'e7fy7t', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-15 08:18:00,090 [salt.loaded.ext.module.maasng:1235][WARNING ][14184] Ignoring parameter vlan:0
2019-04-15 08:18:00,149 [salt.state       :300 ][INFO    ][14184] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-15 08:18:00,149 [salt.state       :1951][INFO    ][14184] Completed state [192.168.11.0/24] at time 08:18:00.149620 duration_in_ms=209.233
2019-04-15 08:18:00,150 [salt.state       :1780][INFO    ][14184] Running state [maas_create_iprange_1] at time 08:18:00.150445
2019-04-15 08:18:00,150 [salt.state       :1813][INFO    ][14184] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-15 08:18:00,202 [salt.state       :300 ][INFO    ][14184] Iprange maas_create_iprange_1 already exist.
2019-04-15 08:18:00,203 [salt.state       :1951][INFO    ][14184] Completed state [maas_create_iprange_1] at time 08:18:00.203823 duration_in_ms=53.377
2019-04-15 08:18:00,204 [salt.state       :1780][INFO    ][14184] Running state [vlan 0] at time 08:18:00.204139
2019-04-15 08:18:00,204 [salt.state       :1813][INFO    ][14184] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-15 08:18:00,254 [salt.loaded.ext.module.maasng:945 ][INFO    ][14184] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'e7fy7t', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-15 08:18:00,337 [salt.loaded.ext.module.maasng:945 ][INFO    ][14184] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'e7fy7t', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-15 08:18:00,550 [salt.loaded.ext.module.maasng:945 ][INFO    ][14184] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'e7fy7t', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-15 08:18:00,630 [salt.state       :300 ][INFO    ][14184] {'new': 'Vlan untagged was updated'}
2019-04-15 08:18:00,631 [salt.state       :1951][INFO    ][14184] Completed state [vlan 0] at time 08:18:00.631438 duration_in_ms=427.298
2019-04-15 08:18:00,632 [salt.state       :1780][INFO    ][14184] Running state [opnfv] at time 08:18:00.632256
2019-04-15 08:18:00,632 [salt.state       :1813][INFO    ][14184] Executing state maasng.sshkey_present for [opnfv]
2019-04-15 08:18:00,679 [salt.loaded.ext.module.maasng:1903][INFO    ][14184] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-04-15 08:18:00,680 [salt.state       :300 ][INFO    ][14184] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-15 08:18:00,680 [salt.state       :1951][INFO    ][14184] Completed state [opnfv] at time 08:18:00.680276 duration_in_ms=48.019
2019-04-15 08:18:00,683 [salt.minion      :1711][INFO    ][14184] Returning information for job: 20190415081737918015
2019-04-15 08:18:01,466 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command state.apply with jid 20190415081801454811
2019-04-15 08:18:01,482 [salt.minion      :1432][INFO    ][14672] Starting a new job with PID 14672
2019-04-15 08:18:09,592 [salt.state       :915 ][INFO    ][14672] Loading fresh modules for state activity
2019-04-15 08:18:09,689 [salt.state       :1780][INFO    ][14672] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:18:09.689399
2019-04-15 08:18:09,689 [salt.state       :1813][INFO    ][14672] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-15 08:18:09,693 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14672] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-15 08:18:11,517 [salt.state       :300 ][INFO    ][14672] {'pid': 14701, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-15 08:18:11,518 [salt.state       :1951][INFO    ][14672] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:18:11.518437 duration_in_ms=1829.038
2019-04-15 08:18:11,522 [salt.state       :1780][INFO    ][14672] Running state [maas.process_machines] at time 08:18:11.522168
2019-04-15 08:18:11,522 [salt.state       :1813][INFO    ][14672] Executing state module.run for [maas.process_machines]
2019-04-15 08:18:11,525 [salt.utils.decorators:613 ][WARNING ][14672] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 08:18:11,994 [salt.loaded.ext.module.maas:412 ][WARNING ][14672] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-15 08:18:11,994 [salt.loaded.ext.module.maas:92  ][INFO    ][14672] 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=64kdb3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-15 08:18:12,973 [salt.loaded.ext.module.maas:412 ][WARNING ][14672] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-15 08:18:12,975 [salt.loaded.ext.module.maas:92  ][INFO    ][14672] 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=cakrqn architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-15 08:18:14,107 [salt.loaded.ext.module.maas:412 ][WARNING ][14672] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-15 08:18:14,108 [salt.loaded.ext.module.maas:92  ][INFO    ][14672] 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=e6tax7 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-15 08:18:15,215 [salt.loaded.ext.module.maas:412 ][WARNING ][14672] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-15 08:18:15,216 [salt.loaded.ext.module.maas:92  ][INFO    ][14672] 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=gm7gc6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-15 08:18:16,255 [salt.loaded.ext.module.maas:412 ][WARNING ][14672] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-15 08:18:16,256 [salt.loaded.ext.module.maas:92  ][INFO    ][14672] 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=nh8pqs architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-15 08:18:16,561 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081816554954
2019-04-15 08:18:16,583 [salt.minion      :1432][INFO    ][14902] Starting a new job with PID 14902
2019-04-15 08:18:16,618 [salt.minion      :1711][INFO    ][14902] Returning information for job: 20190415081816554954
2019-04-15 08:18:17,216 [salt.state       :300 ][INFO    ][14672] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-15 08:18:17,217 [salt.state       :1951][INFO    ][14672] Completed state [maas.process_machines] at time 08:18:17.217275 duration_in_ms=5695.106
2019-04-15 08:18:17,220 [salt.minion      :1711][INFO    ][14672] Returning information for job: 20190415081801454811
2019-04-15 08:18:50,996 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command state.apply with jid 20190415081850985916
2019-04-15 08:18:51,014 [salt.minion      :1432][INFO    ][14971] Starting a new job with PID 14971
2019-04-15 08:18:59,153 [salt.state       :915 ][INFO    ][14971] Loading fresh modules for state activity
2019-04-15 08:18:59,256 [salt.state       :1780][INFO    ][14971] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:18:59.256282
2019-04-15 08:18:59,256 [salt.state       :1813][INFO    ][14971] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-15 08:18:59,258 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14971] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-15 08:19:01,098 [salt.state       :300 ][INFO    ][14971] {'pid': 15005, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-15 08:19:01,099 [salt.state       :1951][INFO    ][14971] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:19:01.099249 duration_in_ms=1842.967
2019-04-15 08:19:01,100 [salt.state       :1780][INFO    ][14971] Running state [maas.wait_for_machine_status] at time 08:19:01.100831
2019-04-15 08:19:01,101 [salt.state       :1813][INFO    ][14971] Executing state module.run for [maas.wait_for_machine_status]
2019-04-15 08:19:01,101 [salt.utils.decorators:613 ][WARNING ][14971] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 08:19:03,652 [salt.state       :300 ][INFO    ][14971] {'ret': True}
2019-04-15 08:19:03,652 [salt.state       :1951][INFO    ][14971] Completed state [maas.wait_for_machine_status] at time 08:19:03.652767 duration_in_ms=2551.935
2019-04-15 08:19:03,655 [salt.minion      :1711][INFO    ][14971] Returning information for job: 20190415081850985916
2019-04-15 08:19:04,329 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command state.apply with jid 20190415081904320782
2019-04-15 08:19:04,343 [salt.minion      :1432][INFO    ][15023] Starting a new job with PID 15023
2019-04-15 08:19:05,457 [salt.state       :915 ][INFO    ][15023] Loading fresh modules for state activity
2019-04-15 08:19:05,594 [salt.state       :1780][INFO    ][15023] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:19:05.594407
2019-04-15 08:19:05,594 [salt.state       :1813][INFO    ][15023] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-15 08:19:05,597 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15023] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-15 08:19:07,427 [salt.state       :300 ][INFO    ][15023] {'pid': 15030, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-15 08:19:07,428 [salt.state       :1951][INFO    ][15023] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:19:07.428676 duration_in_ms=1834.269
2019-04-15 08:19:07,433 [salt.state       :1780][INFO    ][15023] Running state [maas_machines_storage_cmp002_lvm] at time 08:19:07.433051
2019-04-15 08:19:07,433 [salt.state       :1813][INFO    ][15023] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-15 08:19:07,976 [salt.state       :300 ][INFO    ][15023] Machine cmp002 is not in Ready state.
2019-04-15 08:19:07,977 [salt.state       :1951][INFO    ][15023] Completed state [maas_machines_storage_cmp002_lvm] at time 08:19:07.977054 duration_in_ms=544.003
2019-04-15 08:19:07,977 [salt.state       :1780][INFO    ][15023] Running state [maas_machines_storage_cmp001_lvm] at time 08:19:07.977593
2019-04-15 08:19:07,978 [salt.state       :1813][INFO    ][15023] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-15 08:19:08,616 [salt.state       :300 ][INFO    ][15023] Machine cmp001 is not in Ready state.
2019-04-15 08:19:08,617 [salt.state       :1951][INFO    ][15023] Completed state [maas_machines_storage_cmp001_lvm] at time 08:19:08.617485 duration_in_ms=639.89
2019-04-15 08:19:08,622 [salt.minion      :1711][INFO    ][15023] Returning information for job: 20190415081904320782
2019-04-15 08:19:09,320 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command state.apply with jid 20190415081909273986
2019-04-15 08:19:09,337 [salt.minion      :1432][INFO    ][15044] Starting a new job with PID 15044
2019-04-15 08:19:10,442 [salt.state       :915 ][INFO    ][15044] Loading fresh modules for state activity
2019-04-15 08:19:10,540 [salt.state       :1780][INFO    ][15044] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:19:10.540779
2019-04-15 08:19:10,541 [salt.state       :1813][INFO    ][15044] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-15 08:19:10,543 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15044] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-15 08:19:12,345 [salt.state       :300 ][INFO    ][15044] {'pid': 15051, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-15 08:19:12,346 [salt.state       :1951][INFO    ][15044] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:19:12.346520 duration_in_ms=1805.741
2019-04-15 08:19:12,350 [salt.state       :1780][INFO    ][15044] Running state [maas.deploy_machines] at time 08:19:12.350337
2019-04-15 08:19:12,352 [salt.state       :1813][INFO    ][15044] Executing state module.run for [maas.deploy_machines]
2019-04-15 08:19:12,352 [salt.utils.decorators:613 ][WARNING ][15044] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 08:19:12,892 [salt.loaded.ext.module.maas:684 ][INFO    ][15044] deploymachines hwe_kernel=hwe-16.04 system_id=nh8pqs distro_series=xenial
2019-04-15 08:19:15,185 [salt.state       :300 ][INFO    ][15044] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-15 08:19:15,185 [salt.state       :1951][INFO    ][15044] Completed state [maas.deploy_machines] at time 08:19:15.185830 duration_in_ms=2835.493
2019-04-15 08:19:15,188 [salt.minion      :1711][INFO    ][15044] Returning information for job: 20190415081909273986
2019-04-15 08:19:15,884 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command state.apply with jid 20190415081915873995
2019-04-15 08:19:15,898 [salt.minion      :1432][INFO    ][15110] Starting a new job with PID 15110
2019-04-15 08:19:23,998 [salt.state       :915 ][INFO    ][15110] Loading fresh modules for state activity
2019-04-15 08:19:24,098 [salt.state       :1780][INFO    ][15110] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:19:24.098663
2019-04-15 08:19:24,100 [salt.state       :1813][INFO    ][15110] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-15 08:19:24,101 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15110] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-15 08:19:26,122 [salt.state       :300 ][INFO    ][15110] {'pid': 15129, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-15 08:19:26,123 [salt.state       :1951][INFO    ][15110] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:19:26.122983 duration_in_ms=2024.321
2019-04-15 08:19:26,125 [salt.state       :1780][INFO    ][15110] Running state [maas.wait_for_machine_status] at time 08:19:26.125494
2019-04-15 08:19:26,126 [salt.state       :1813][INFO    ][15110] Executing state module.run for [maas.wait_for_machine_status]
2019-04-15 08:19:26,128 [salt.utils.decorators:613 ][WARNING ][15110] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-15 08:19:28,763 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.38154006s left)
2019-04-15 08:19:30,956 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415081930945474
2019-04-15 08:19:30,982 [salt.minion      :1432][INFO    ][15154] Starting a new job with PID 15154
2019-04-15 08:19:31,014 [salt.minion      :1711][INFO    ][15154] Returning information for job: 20190415081930945474
2019-04-15 08:20:01,174 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082001165891
2019-04-15 08:20:01,190 [salt.minion      :1432][INFO    ][15219] Starting a new job with PID 15219
2019-04-15 08:20:01,221 [salt.minion      :1711][INFO    ][15219] Returning information for job: 20190415082001165891
2019-04-15 08:20:01,582 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.5626781s left)
2019-04-15 08:20:31,243 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082031232442
2019-04-15 08:20:31,266 [salt.minion      :1432][INFO    ][15252] Starting a new job with PID 15252
2019-04-15 08:20:31,296 [salt.minion      :1711][INFO    ][15252] Returning information for job: 20190415082031232442
2019-04-15 08:20:34,234 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.91079307s left)
2019-04-15 08:21:01,471 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082101458394
2019-04-15 08:21:01,493 [salt.minion      :1432][INFO    ][15330] Starting a new job with PID 15330
2019-04-15 08:21:01,525 [salt.minion      :1711][INFO    ][15330] Returning information for job: 20190415082101458394
2019-04-15 08:21:06,941 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.2039609s left)
2019-04-15 08:21:31,674 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082131665543
2019-04-15 08:21:31,693 [salt.minion      :1432][INFO    ][15374] Starting a new job with PID 15374
2019-04-15 08:21:31,727 [salt.minion      :1711][INFO    ][15374] Returning information for job: 20190415082131665543
2019-04-15 08:21:39,685 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.45968509s left)
2019-04-15 08:22:01,711 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082201696983
2019-04-15 08:22:01,727 [salt.minion      :1432][INFO    ][15441] Starting a new job with PID 15441
2019-04-15 08:22:01,760 [salt.minion      :1711][INFO    ][15441] Returning information for job: 20190415082201696983
2019-04-15 08:22:12,465 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.67961407s left)
2019-04-15 08:22:31,782 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082231771645
2019-04-15 08:22:31,799 [salt.minion      :1432][INFO    ][15479] Starting a new job with PID 15479
2019-04-15 08:22:31,830 [salt.minion      :1711][INFO    ][15479] Returning information for job: 20190415082231771645
2019-04-15 08:22:45,142 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.00322104s left)
2019-04-15 08:23:01,881 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082301868427
2019-04-15 08:23:01,903 [salt.minion      :1432][INFO    ][15546] Starting a new job with PID 15546
2019-04-15 08:23:01,932 [salt.minion      :1711][INFO    ][15546] Returning information for job: 20190415082301868427
2019-04-15 08:23:17,840 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.30528688s left)
2019-04-15 08:23:31,949 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082331940451
2019-04-15 08:23:31,965 [salt.minion      :1432][INFO    ][15605] Starting a new job with PID 15605
2019-04-15 08:23:31,993 [salt.minion      :1711][INFO    ][15605] Returning information for job: 20190415082331940451
2019-04-15 08:23:50,636 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.50906801s left)
2019-04-15 08:24:02,016 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082402004290
2019-04-15 08:24:02,036 [salt.minion      :1432][INFO    ][15684] Starting a new job with PID 15684
2019-04-15 08:24:02,071 [salt.minion      :1711][INFO    ][15684] Returning information for job: 20190415082402004290
2019-04-15 08:24:23,610 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.53501201s left)
2019-04-15 08:24:32,229 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082432221890
2019-04-15 08:24:32,244 [salt.minion      :1432][INFO    ][15742] Starting a new job with PID 15742
2019-04-15 08:24:32,276 [salt.minion      :1711][INFO    ][15742] Returning information for job: 20190415082432221890
2019-04-15 08:24:56,818 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.3270359s left)
2019-04-15 08:25:02,242 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082502233414
2019-04-15 08:25:02,267 [salt.minion      :1432][INFO    ][15916] Starting a new job with PID 15916
2019-04-15 08:25:02,288 [salt.minion      :1711][INFO    ][15916] Returning information for job: 20190415082502233414
2019-04-15 08:25:29,550 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1886.59502697s left)
2019-04-15 08:25:32,348 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082532336454
2019-04-15 08:25:32,365 [salt.minion      :1432][INFO    ][15995] Starting a new job with PID 15995
2019-04-15 08:25:32,390 [salt.minion      :1711][INFO    ][15995] Returning information for job: 20190415082532336454
2019-04-15 08:26:02,382 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1853.76289201s left)
2019-04-15 08:26:02,433 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082602422592
2019-04-15 08:26:02,455 [salt.minion      :1432][INFO    ][16088] Starting a new job with PID 16088
2019-04-15 08:26:02,479 [salt.minion      :1711][INFO    ][16088] Returning information for job: 20190415082602422592
2019-04-15 08:26:32,529 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082632521739
2019-04-15 08:26:32,544 [salt.minion      :1432][INFO    ][16133] Starting a new job with PID 16133
2019-04-15 08:26:32,567 [salt.minion      :1711][INFO    ][16133] Returning information for job: 20190415082632521739
2019-04-15 08:26:35,301 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1820.84373903s left)
2019-04-15 08:27:02,635 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082702623734
2019-04-15 08:27:02,659 [salt.minion      :1432][INFO    ][16320] Starting a new job with PID 16320
2019-04-15 08:27:02,682 [salt.minion      :1711][INFO    ][16320] Returning information for job: 20190415082702623734
2019-04-15 08:27:08,228 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1787.91673994s left)
2019-04-15 08:27:32,756 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082732742982
2019-04-15 08:27:32,780 [salt.minion      :1432][INFO    ][16398] Starting a new job with PID 16398
2019-04-15 08:27:32,804 [salt.minion      :1711][INFO    ][16398] Returning information for job: 20190415082732742982
2019-04-15 08:27:41,066 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1755.07898903s left)
2019-04-15 08:28:02,874 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082802862827
2019-04-15 08:28:02,897 [salt.minion      :1432][INFO    ][16469] Starting a new job with PID 16469
2019-04-15 08:28:02,923 [salt.minion      :1711][INFO    ][16469] Returning information for job: 20190415082802862827
2019-04-15 08:28:13,963 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1722.18225598s left)
2019-04-15 08:28:33,010 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082833002516
2019-04-15 08:28:33,031 [salt.minion      :1432][INFO    ][16503] Starting a new job with PID 16503
2019-04-15 08:28:33,056 [salt.minion      :1711][INFO    ][16503] Returning information for job: 20190415082833002516
2019-04-15 08:28:46,697 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1689.44747806s left)
2019-04-15 08:29:03,088 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082903074680
2019-04-15 08:29:03,108 [salt.minion      :1432][INFO    ][16572] Starting a new job with PID 16572
2019-04-15 08:29:03,131 [salt.minion      :1711][INFO    ][16572] Returning information for job: 20190415082903074680
2019-04-15 08:29:19,469 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1656.67577696s left)
2019-04-15 08:29:33,114 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415082933099353
2019-04-15 08:29:33,139 [salt.minion      :1432][INFO    ][16606] Starting a new job with PID 16606
2019-04-15 08:29:33,168 [salt.minion      :1711][INFO    ][16606] Returning information for job: 20190415082933099353
2019-04-15 08:29:52,193 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1623.95240498s left)
2019-04-15 08:30:03,210 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415083003197362
2019-04-15 08:30:03,234 [salt.minion      :1432][INFO    ][16675] Starting a new job with PID 16675
2019-04-15 08:30:03,258 [salt.minion      :1711][INFO    ][16675] Returning information for job: 20190415083003197362
2019-04-15 08:30:25,033 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1591.11233902s left)
2019-04-15 08:30:33,372 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415083033357392
2019-04-15 08:30:33,394 [salt.minion      :1432][INFO    ][16709] Starting a new job with PID 16709
2019-04-15 08:30:33,420 [salt.minion      :1711][INFO    ][16709] Returning information for job: 20190415083033357392
2019-04-15 08:30:54,279 [salt.utils.schedule:1377][INFO    ][3387] Running scheduled job: __mine_interval
2019-04-15 08:30:59,071 [salt.loaded.ext.module.maas:1023][INFO    ][15110] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1557.07345605s left)
2019-04-15 08:31:03,545 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command saltutil.find_job with jid 20190415083103537690
2019-04-15 08:31:03,560 [salt.minion      :1432][INFO    ][16969] Starting a new job with PID 16969
2019-04-15 08:31:03,580 [salt.minion      :1711][INFO    ][16969] Returning information for job: 20190415083103537690
2019-04-15 08:31:31,841 [salt.state       :300 ][INFO    ][15110] {'ret': True}
2019-04-15 08:31:31,842 [salt.state       :1951][INFO    ][15110] Completed state [maas.wait_for_machine_status] at time 08:31:31.842113 duration_in_ms=725716.617
2019-04-15 08:31:31,845 [salt.minion      :1711][INFO    ][15110] Returning information for job: 20190415081915873995
2019-04-15 09:30:54,278 [salt.utils.schedule:1377][INFO    ][3387] Running scheduled job: __mine_interval
2019-04-15 10:11:02,024 [salt.minion      :1308][INFO    ][3387] User sudo_ubuntu Executing command cp.push_dir with jid 20190415101102010323
2019-04-15 10:11:02,046 [salt.minion      :1432][INFO    ][27345] Starting a new job with PID 27345
