2019-06-07 18:39:19,623 [salt.utils       :831 ][ERROR   ][1581] DNS lookup or connection check of 'salt' failed.
2019-06-07 18:39:19,624 [salt.minion      :152 ][ERROR   ][1581] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-06-07 18:39:22,171 [salt.utils.parsers:1051][WARNING ][1581] Minion received a SIGTERM. Exiting.
2019-06-07 18:40:04,095 [salt.minion      :870 ][ERROR   ][1803] Error while bringing up minion for multi-master. Is master at 192.168.11.2 responding?
2019-06-07 18:41:20,147 [salt.utils.decorators:613 ][WARNING ][2022] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-07 18:41:34,160 [salt.loaded.int.states.file:2298][WARNING ][2022] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-07 18:41:44,295 [salt.utils.decorators:613 ][WARNING ][2022] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-07 18:41:44,397 [salt.loaded.int.states.file:2298][WARNING ][2022] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-07 18:41:44,398 [salt.loaded.int.states.file:2298][WARNING ][2022] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-07 18:41:44,399 [salt.loaded.int.states.file:2298][WARNING ][2022] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-07 18:41:44,401 [salt.loaded.int.states.file:2298][WARNING ][2022] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-07 18:41:44,402 [salt.loaded.int.states.file:2298][WARNING ][2022] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-07 18:41:44,403 [salt.loaded.int.states.file:2298][WARNING ][2022] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-07 18:41:44,404 [salt.loaded.int.states.file:2298][WARNING ][2022] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-07 18:41:44,405 [salt.loaded.int.states.file:2298][WARNING ][2022] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-07 18:41:50,291 [salt.loaded.int.module.debian_ip:1964][WARNING ][2022] The network state sls is requiring a reboot of the system to properly apply network configuration.
2019-06-07 18:41:50,467 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][2022] Command '['umount', '/dev/shm']' failed with return code: 32
2019-06-07 18:41:50,467 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][2022] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-06-07 18:41:50,468 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][2022] retcode: 32
2019-06-07 18:42:02,937 [salt.utils.parsers:1051][WARNING ][1803] Minion received a SIGTERM. Exiting.
2019-06-07 18:44:17,287 [salt.utils.decorators:613 ][WARNING ][5362] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-07 18:44:26,351 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6908] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-07 18:44:26,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6908] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-07 18:44:26,388 [salt.utils.parsers:1051][WARNING ][2348] Minion received a SIGTERM. Exiting.
2019-06-07 18:44:27,418 [salt.cli.daemons :293 ][INFO    ][6961] Setting up the Salt Minion "odl01.mcp-odl-noha.local"
2019-06-07 18:44:27,621 [salt.cli.daemons :82  ][INFO    ][6961] Starting up the Salt Minion
2019-06-07 18:44:27,622 [salt.utils.event :1017][INFO    ][6961] Starting pull socket on /var/run/salt/minion/minion_event_0d1d1cf3c0_pull.ipc
2019-06-07 18:44:28,161 [salt.minion      :976 ][INFO    ][6961] Creating minion process manager
2019-06-07 18:44:29,352 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][6961] Executing command ['date', '+%z'] in directory '/root'
2019-06-07 18:44:29,369 [salt.utils.schedule:568 ][INFO    ][6961] Updating job settings for scheduled job: __mine_interval
2019-06-07 18:44:29,372 [salt.minion      :1108][INFO    ][6961] Added mine.update to scheduler
2019-06-07 18:44:29,378 [salt.minion      :1975][INFO    ][6961] Minion is starting as user 'root'
2019-06-07 18:44:29,390 [salt.minion      :2336][INFO    ][6961] Minion is ready to receive requests!
2019-06-07 18:44:58,823 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command state.sls with jid 20190607184458814680
2019-06-07 18:44:58,834 [salt.minion      :1432][INFO    ][7050] Starting a new job with PID 7050
2019-06-07 18:45:04,794 [salt.state       :915 ][INFO    ][7050] Loading fresh modules for state activity
2019-06-07 18:45:04,850 [salt.fileclient  :1219][INFO    ][7050] Fetching file from saltenv 'base', ** done ** 'opendaylight/init.sls'
2019-06-07 18:45:04,879 [salt.fileclient  :1219][INFO    ][7050] Fetching file from saltenv 'base', ** done ** 'opendaylight/server.sls'
2019-06-07 18:45:04,906 [salt.fileclient  :1219][INFO    ][7050] Fetching file from saltenv 'base', ** done ** 'opendaylight/map.jinja'
2019-06-07 18:45:04,939 [salt.fileclient  :1219][INFO    ][7050] Fetching file from saltenv 'base', ** done ** 'opendaylight/config.sls'
2019-06-07 18:45:05,004 [salt.fileclient  :1219][INFO    ][7050] Fetching file from saltenv 'base', ** done ** 'opendaylight/repo.sls'
2019-06-07 18:45:05,687 [salt.state       :1780][INFO    ][7050] Running state [cat <<-EOF | sudo apt-key add -
-----BEGIN PGP PUBLIC KEY BLOCK-----
Version: GnuPG v1

mQINBFiKaBEBEADpCtIj8utf/bUfN6iQ+sxGiOPLnXVYoYyKifHDazD4o1Jevfiu
EpcDIx9EdnhrCpvKTU+jaw2B7K3pkdqbjbzjZY+2CDENSQXfRHuuI/nWDaYI0stx
Tf/evip3cxdutnZNAklzkxppHP+4UZm9HAd7uZsEyff4H9DIsHzZIA4Z++Hx2+lt
w9K0iCKh2k6Pon/VVo8Bir3JuKIIdLRAuHmyniYlHDswQnu+1nQHE0F/oboD0Q9Z
hOvXAr1L7LWu0hkLV7BqmeI0SPcRA3b5MU3dfaTK8MaPAo8anQTpCyYUnoIBqX8h
y324T/dvpFKq2/X3RL+wOSYTA8TLgyhH0fhdIKZg3G8m9kxuAHZYHIHnDtvgJ5yd
72tNY+w8UIX8U2ark/WdkAMZr3O0AuTDlvHcasxO5+puAu8jh0EgtqItqrvKwiF7
dmlHVW41Rt+su2fmsUkk4Z0IhWrn3PdrSWAcH2eL6vjuqx6CccpjsjyiSQ90dUox
EoMpY+viX59aF0kU4BLt76mQO6YZtCpicLxFGCu97v1mNn+FWjhBOIF08pVsbNlq
oMl2j0N8NKZxJvkkmsA/i//ch5FsjzvUy3xajlSzq9ruWS4SlWq2Vzdx/acvF7Oa
ABA11wIjzLc9vmhzQNiRa53fJQwi+w/Or9LtH2msKCbcPVHoZ5OT4t6S8QARAQAB
tBpMYXVuY2hwYWQgUFBBIGZvciBPREwgVGVhbYkCOAQTAQIAIgUCWIpoEQIbAwYL
CQgHAwIGFQgCCQoLBBYCAwECHgECF4AACgkQe4qho0TAUkgAmg//XY/RqU4WcT+p
13oDc3+Dp4aL+rwaNz0o56i0z0cYPxd8GPicCuS8d/di07GnQiBcZ5DZgegnnaYm
OUF+phxk4q+jYO/t2GHQlYSf/QyUv7OimidLOHN1FiahmcGobliwih70o6ZcMT84
ggSu8jBzA/HLFBIkgStKD/staR5zJ2HfK298yVhiffyrPA+I3nPe7pvTaGa2e8AP
BYs5zB5n27upSZIokXFvqlmS4HEKDmPcY061wgmg1cNY1Y+mIuGjxY1Igbi6kAe0
yaLN2AN4c2ImhpwOcuazKTe/q2ZhoPTpYvuzmogwau8LBjRBhVS6fkTpSBPEkcwn
f/QYmmVLygmpMDHuHapyH8iaUoksq7gd64iBRDJQN7giQSjkTVvcGBqoKG8lbUMV
MDT4FGuYYsObWUg7kmHlNq9nIVlAxmxv8ZTg9+8xy3f53aId/51m+gW9LGRAT94T
ZIWrF9cBvsPWoHgHkV1At/fPprOvNXqeQiJ7UzC3ikDNCu2AjPEbA4sb019RNgtj
jUI6g6RZdzbeKVpptxILCtT3yKbfKj8AfrfaRzS0yMhVudgLolIUA4S6g46p0Cgy
gITO49wxxBu6UAOsAG3psDRlsZmmrT4AH09Yt2RzmY0FBWValqpoPagheQqeU+2W
FKnV9Lw1SKMtWZbYMvIlB0rwts3k9lE=
=xkZ9
-----END PGP PUBLIC KEY BLOCK-----
EOF] at time 18:45:05.687100
2019-06-07 18:45:05,688 [salt.state       :1813][INFO    ][7050] Executing state cmd.run for [cat <<-EOF | sudo apt-key add -
-----BEGIN PGP PUBLIC KEY BLOCK-----
Version: GnuPG v1

mQINBFiKaBEBEADpCtIj8utf/bUfN6iQ+sxGiOPLnXVYoYyKifHDazD4o1Jevfiu
EpcDIx9EdnhrCpvKTU+jaw2B7K3pkdqbjbzjZY+2CDENSQXfRHuuI/nWDaYI0stx
Tf/evip3cxdutnZNAklzkxppHP+4UZm9HAd7uZsEyff4H9DIsHzZIA4Z++Hx2+lt
w9K0iCKh2k6Pon/VVo8Bir3JuKIIdLRAuHmyniYlHDswQnu+1nQHE0F/oboD0Q9Z
hOvXAr1L7LWu0hkLV7BqmeI0SPcRA3b5MU3dfaTK8MaPAo8anQTpCyYUnoIBqX8h
y324T/dvpFKq2/X3RL+wOSYTA8TLgyhH0fhdIKZg3G8m9kxuAHZYHIHnDtvgJ5yd
72tNY+w8UIX8U2ark/WdkAMZr3O0AuTDlvHcasxO5+puAu8jh0EgtqItqrvKwiF7
dmlHVW41Rt+su2fmsUkk4Z0IhWrn3PdrSWAcH2eL6vjuqx6CccpjsjyiSQ90dUox
EoMpY+viX59aF0kU4BLt76mQO6YZtCpicLxFGCu97v1mNn+FWjhBOIF08pVsbNlq
oMl2j0N8NKZxJvkkmsA/i//ch5FsjzvUy3xajlSzq9ruWS4SlWq2Vzdx/acvF7Oa
ABA11wIjzLc9vmhzQNiRa53fJQwi+w/Or9LtH2msKCbcPVHoZ5OT4t6S8QARAQAB
tBpMYXVuY2hwYWQgUFBBIGZvciBPREwgVGVhbYkCOAQTAQIAIgUCWIpoEQIbAwYL
CQgHAwIGFQgCCQoLBBYCAwECHgECF4AACgkQe4qho0TAUkgAmg//XY/RqU4WcT+p
13oDc3+Dp4aL+rwaNz0o56i0z0cYPxd8GPicCuS8d/di07GnQiBcZ5DZgegnnaYm
OUF+phxk4q+jYO/t2GHQlYSf/QyUv7OimidLOHN1FiahmcGobliwih70o6ZcMT84
ggSu8jBzA/HLFBIkgStKD/staR5zJ2HfK298yVhiffyrPA+I3nPe7pvTaGa2e8AP
BYs5zB5n27upSZIokXFvqlmS4HEKDmPcY061wgmg1cNY1Y+mIuGjxY1Igbi6kAe0
yaLN2AN4c2ImhpwOcuazKTe/q2ZhoPTpYvuzmogwau8LBjRBhVS6fkTpSBPEkcwn
f/QYmmVLygmpMDHuHapyH8iaUoksq7gd64iBRDJQN7giQSjkTVvcGBqoKG8lbUMV
MDT4FGuYYsObWUg7kmHlNq9nIVlAxmxv8ZTg9+8xy3f53aId/51m+gW9LGRAT94T
ZIWrF9cBvsPWoHgHkV1At/fPprOvNXqeQiJ7UzC3ikDNCu2AjPEbA4sb019RNgtj
jUI6g6RZdzbeKVpptxILCtT3yKbfKj8AfrfaRzS0yMhVudgLolIUA4S6g46p0Cgy
gITO49wxxBu6UAOsAG3psDRlsZmmrT4AH09Yt2RzmY0FBWValqpoPagheQqeU+2W
FKnV9Lw1SKMtWZbYMvIlB0rwts3k9lE=
=xkZ9
-----END PGP PUBLIC KEY BLOCK-----
EOF]
2019-06-07 18:45:05,689 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7050] Executing command 'apt-key list | grep -qF '4096R/44C05248 2017-01-26'' in directory '/root'
2019-06-07 18:45:05,809 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7050] Executing command 'cat <<-EOF | sudo apt-key add -
-----BEGIN PGP PUBLIC KEY BLOCK-----
Version: GnuPG v1

mQINBFiKaBEBEADpCtIj8utf/bUfN6iQ+sxGiOPLnXVYoYyKifHDazD4o1Jevfiu
EpcDIx9EdnhrCpvKTU+jaw2B7K3pkdqbjbzjZY+2CDENSQXfRHuuI/nWDaYI0stx
Tf/evip3cxdutnZNAklzkxppHP+4UZm9HAd7uZsEyff4H9DIsHzZIA4Z++Hx2+lt
w9K0iCKh2k6Pon/VVo8Bir3JuKIIdLRAuHmyniYlHDswQnu+1nQHE0F/oboD0Q9Z
hOvXAr1L7LWu0hkLV7BqmeI0SPcRA3b5MU3dfaTK8MaPAo8anQTpCyYUnoIBqX8h
y324T/dvpFKq2/X3RL+wOSYTA8TLgyhH0fhdIKZg3G8m9kxuAHZYHIHnDtvgJ5yd
72tNY+w8UIX8U2ark/WdkAMZr3O0AuTDlvHcasxO5+puAu8jh0EgtqItqrvKwiF7
dmlHVW41Rt+su2fmsUkk4Z0IhWrn3PdrSWAcH2eL6vjuqx6CccpjsjyiSQ90dUox
EoMpY+viX59aF0kU4BLt76mQO6YZtCpicLxFGCu97v1mNn+FWjhBOIF08pVsbNlq
oMl2j0N8NKZxJvkkmsA/i//ch5FsjzvUy3xajlSzq9ruWS4SlWq2Vzdx/acvF7Oa
ABA11wIjzLc9vmhzQNiRa53fJQwi+w/Or9LtH2msKCbcPVHoZ5OT4t6S8QARAQAB
tBpMYXVuY2hwYWQgUFBBIGZvciBPREwgVGVhbYkCOAQTAQIAIgUCWIpoEQIbAwYL
CQgHAwIGFQgCCQoLBBYCAwECHgECF4AACgkQe4qho0TAUkgAmg//XY/RqU4WcT+p
13oDc3+Dp4aL+rwaNz0o56i0z0cYPxd8GPicCuS8d/di07GnQiBcZ5DZgegnnaYm
OUF+phxk4q+jYO/t2GHQlYSf/QyUv7OimidLOHN1FiahmcGobliwih70o6ZcMT84
ggSu8jBzA/HLFBIkgStKD/staR5zJ2HfK298yVhiffyrPA+I3nPe7pvTaGa2e8AP
BYs5zB5n27upSZIokXFvqlmS4HEKDmPcY061wgmg1cNY1Y+mIuGjxY1Igbi6kAe0
yaLN2AN4c2ImhpwOcuazKTe/q2ZhoPTpYvuzmogwau8LBjRBhVS6fkTpSBPEkcwn
f/QYmmVLygmpMDHuHapyH8iaUoksq7gd64iBRDJQN7giQSjkTVvcGBqoKG8lbUMV
MDT4FGuYYsObWUg7kmHlNq9nIVlAxmxv8ZTg9+8xy3f53aId/51m+gW9LGRAT94T
ZIWrF9cBvsPWoHgHkV1At/fPprOvNXqeQiJ7UzC3ikDNCu2AjPEbA4sb019RNgtj
jUI6g6RZdzbeKVpptxILCtT3yKbfKj8AfrfaRzS0yMhVudgLolIUA4S6g46p0Cgy
gITO49wxxBu6UAOsAG3psDRlsZmmrT4AH09Yt2RzmY0FBWValqpoPagheQqeU+2W
FKnV9Lw1SKMtWZbYMvIlB0rwts3k9lE=
=xkZ9
-----END PGP PUBLIC KEY BLOCK-----
EOF' in directory '/root'
2019-06-07 18:45:06,049 [salt.state       :300 ][INFO    ][7050] {'pid': 7112, 'retcode': 0, 'stderr': '', 'stdout': 'OK'}
2019-06-07 18:45:06,050 [salt.state       :1951][INFO    ][7050] Completed state [cat <<-EOF | sudo apt-key add -
-----BEGIN PGP PUBLIC KEY BLOCK-----
Version: GnuPG v1

mQINBFiKaBEBEADpCtIj8utf/bUfN6iQ+sxGiOPLnXVYoYyKifHDazD4o1Jevfiu
EpcDIx9EdnhrCpvKTU+jaw2B7K3pkdqbjbzjZY+2CDENSQXfRHuuI/nWDaYI0stx
Tf/evip3cxdutnZNAklzkxppHP+4UZm9HAd7uZsEyff4H9DIsHzZIA4Z++Hx2+lt
w9K0iCKh2k6Pon/VVo8Bir3JuKIIdLRAuHmyniYlHDswQnu+1nQHE0F/oboD0Q9Z
hOvXAr1L7LWu0hkLV7BqmeI0SPcRA3b5MU3dfaTK8MaPAo8anQTpCyYUnoIBqX8h
y324T/dvpFKq2/X3RL+wOSYTA8TLgyhH0fhdIKZg3G8m9kxuAHZYHIHnDtvgJ5yd
72tNY+w8UIX8U2ark/WdkAMZr3O0AuTDlvHcasxO5+puAu8jh0EgtqItqrvKwiF7
dmlHVW41Rt+su2fmsUkk4Z0IhWrn3PdrSWAcH2eL6vjuqx6CccpjsjyiSQ90dUox
EoMpY+viX59aF0kU4BLt76mQO6YZtCpicLxFGCu97v1mNn+FWjhBOIF08pVsbNlq
oMl2j0N8NKZxJvkkmsA/i//ch5FsjzvUy3xajlSzq9ruWS4SlWq2Vzdx/acvF7Oa
ABA11wIjzLc9vmhzQNiRa53fJQwi+w/Or9LtH2msKCbcPVHoZ5OT4t6S8QARAQAB
tBpMYXVuY2hwYWQgUFBBIGZvciBPREwgVGVhbYkCOAQTAQIAIgUCWIpoEQIbAwYL
CQgHAwIGFQgCCQoLBBYCAwECHgECF4AACgkQe4qho0TAUkgAmg//XY/RqU4WcT+p
13oDc3+Dp4aL+rwaNz0o56i0z0cYPxd8GPicCuS8d/di07GnQiBcZ5DZgegnnaYm
OUF+phxk4q+jYO/t2GHQlYSf/QyUv7OimidLOHN1FiahmcGobliwih70o6ZcMT84
ggSu8jBzA/HLFBIkgStKD/staR5zJ2HfK298yVhiffyrPA+I3nPe7pvTaGa2e8AP
BYs5zB5n27upSZIokXFvqlmS4HEKDmPcY061wgmg1cNY1Y+mIuGjxY1Igbi6kAe0
yaLN2AN4c2ImhpwOcuazKTe/q2ZhoPTpYvuzmogwau8LBjRBhVS6fkTpSBPEkcwn
f/QYmmVLygmpMDHuHapyH8iaUoksq7gd64iBRDJQN7giQSjkTVvcGBqoKG8lbUMV
MDT4FGuYYsObWUg7kmHlNq9nIVlAxmxv8ZTg9+8xy3f53aId/51m+gW9LGRAT94T
ZIWrF9cBvsPWoHgHkV1At/fPprOvNXqeQiJ7UzC3ikDNCu2AjPEbA4sb019RNgtj
jUI6g6RZdzbeKVpptxILCtT3yKbfKj8AfrfaRzS0yMhVudgLolIUA4S6g46p0Cgy
gITO49wxxBu6UAOsAG3psDRlsZmmrT4AH09Yt2RzmY0FBWValqpoPagheQqeU+2W
FKnV9Lw1SKMtWZbYMvIlB0rwts3k9lE=
=xkZ9
-----END PGP PUBLIC KEY BLOCK-----
EOF] at time 18:45:06.050427 duration_in_ms=363.328
2019-06-07 18:45:06,055 [salt.state       :1780][INFO    ][7050] Running state [deb http://ppa.launchpad.net/odl-team/fluorine/ubuntu xenial main] at time 18:45:06.055260
2019-06-07 18:45:06,055 [salt.state       :1813][INFO    ][7050] Executing state pkgrepo.managed for [deb http://ppa.launchpad.net/odl-team/fluorine/ubuntu xenial main]
2019-06-07 18:45:06,139 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7050] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-06-07 18:45:09,961 [salt.state       :300 ][INFO    ][7050] {'repo': 'deb http://ppa.launchpad.net/odl-team/fluorine/ubuntu xenial main'}
2019-06-07 18:45:09,961 [salt.state       :1951][INFO    ][7050] Completed state [deb http://ppa.launchpad.net/odl-team/fluorine/ubuntu xenial main] at time 18:45:09.961673 duration_in_ms=3906.413
2019-06-07 18:45:09,963 [salt.state       :1780][INFO    ][7050] Running state [opendaylight] at time 18:45:09.963072
2019-06-07 18:45:09,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7050] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-07 18:45:10,324 [salt.state       :1780][INFO    ][7050] Running state [opendaylight] at time 18:45:10.324284
2019-06-07 18:45:10,324 [salt.state       :1813][INFO    ][7050] Executing state service.masked for [opendaylight]
2019-06-07 18:45:10,325 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7050] Executing command ['systemctl', 'status', 'opendaylight.service', '-n', '0'] in directory '/root'
2019-06-07 18:45:10,355 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7050] Executing command ['systemd-run', '--scope', 'systemctl', 'mask', 'opendaylight.service'] in directory '/root'
2019-06-07 18:45:10,464 [salt.state       :300 ][INFO    ][7050] Made the following changes:
'masked' changed from 'False' to 'True'

2019-06-07 18:45:10,464 [salt.state       :1951][INFO    ][7050] Completed state [opendaylight] at time 18:45:10.464791 duration_in_ms=140.507
2019-06-07 18:45:10,465 [salt.state       :1780][INFO    ][7050] Running state [opendaylight] at time 18:45:10.465702
2019-06-07 18:45:10,466 [salt.state       :1813][INFO    ][7050] Executing state pkg.installed for [opendaylight]
2019-06-07 18:45:10,495 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7050] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-06-07 18:45:10,516 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7050] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'opendaylight'] in directory '/root'
2019-06-07 18:45:13,899 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command saltutil.find_job with jid 20190607184513886067
2019-06-07 18:45:13,920 [salt.minion      :1432][INFO    ][7704] Starting a new job with PID 7704
2019-06-07 18:45:13,938 [salt.minion      :1711][INFO    ][7704] Returning information for job: 20190607184513886067
2019-06-07 18:45:43,961 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command saltutil.find_job with jid 20190607184543947155
2019-06-07 18:45:43,979 [salt.minion      :1432][INFO    ][7709] Starting a new job with PID 7709
2019-06-07 18:45:43,996 [salt.minion      :1711][INFO    ][7709] Returning information for job: 20190607184543947155
2019-06-07 18:46:14,075 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command saltutil.find_job with jid 20190607184614059120
2019-06-07 18:46:14,095 [salt.minion      :1432][INFO    ][7714] Starting a new job with PID 7714
2019-06-07 18:46:14,113 [salt.minion      :1711][INFO    ][7714] Returning information for job: 20190607184614059120
2019-06-07 18:46:44,144 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command saltutil.find_job with jid 20190607184644126261
2019-06-07 18:46:44,164 [salt.minion      :1432][INFO    ][7719] Starting a new job with PID 7719
2019-06-07 18:46:44,185 [salt.minion      :1711][INFO    ][7719] Returning information for job: 20190607184644126261
2019-06-07 18:47:14,216 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command saltutil.find_job with jid 20190607184714200825
2019-06-07 18:47:14,237 [salt.minion      :1432][INFO    ][7724] Starting a new job with PID 7724
2019-06-07 18:47:14,257 [salt.minion      :1711][INFO    ][7724] Returning information for job: 20190607184714200825
2019-06-07 18:47:44,285 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command saltutil.find_job with jid 20190607184744273318
2019-06-07 18:47:44,308 [salt.minion      :1432][INFO    ][7729] Starting a new job with PID 7729
2019-06-07 18:47:44,326 [salt.minion      :1711][INFO    ][7729] Returning information for job: 20190607184744273318
2019-06-07 18:48:14,363 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command saltutil.find_job with jid 20190607184814350476
2019-06-07 18:48:14,383 [salt.minion      :1432][INFO    ][7734] Starting a new job with PID 7734
2019-06-07 18:48:14,401 [salt.minion      :1711][INFO    ][7734] Returning information for job: 20190607184814350476
2019-06-07 18:48:44,444 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command saltutil.find_job with jid 20190607184844429747
2019-06-07 18:48:44,457 [salt.minion      :1432][INFO    ][7739] Starting a new job with PID 7739
2019-06-07 18:48:44,475 [salt.minion      :1711][INFO    ][7739] Returning information for job: 20190607184844429747
2019-06-07 18:49:14,519 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command saltutil.find_job with jid 20190607184914504170
2019-06-07 18:49:14,541 [salt.minion      :1432][INFO    ][7744] Starting a new job with PID 7744
2019-06-07 18:49:14,559 [salt.minion      :1711][INFO    ][7744] Returning information for job: 20190607184914504170
2019-06-07 18:49:44,614 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command saltutil.find_job with jid 20190607184944596908
2019-06-07 18:49:44,636 [salt.minion      :1432][INFO    ][7749] Starting a new job with PID 7749
2019-06-07 18:49:44,655 [salt.minion      :1711][INFO    ][7749] Returning information for job: 20190607184944596908
2019-06-07 18:50:14,719 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command saltutil.find_job with jid 20190607185014703071
2019-06-07 18:50:14,737 [salt.minion      :1432][INFO    ][7754] Starting a new job with PID 7754
2019-06-07 18:50:14,755 [salt.minion      :1711][INFO    ][7754] Returning information for job: 20190607185014703071
2019-06-07 18:50:44,765 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command saltutil.find_job with jid 20190607185044752491
2019-06-07 18:50:44,779 [salt.minion      :1432][INFO    ][7981] Starting a new job with PID 7981
2019-06-07 18:50:44,797 [salt.minion      :1711][INFO    ][7981] Returning information for job: 20190607185044752491
2019-06-07 18:51:02,917 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7050] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-07 18:51:02,958 [salt.state       :300 ][INFO    ][7050] Made the following changes:
'java-common' changed from 'absent' to '0.56ubuntu2'
'libxtst6' changed from 'absent' to '2:1.2.2-1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'openjdk-8-jre-headless' changed from 'absent' to '8u212-b03-0ubuntu1.16.04.1'
'libxrender1' changed from 'absent' to '1:0.9.9-0ubuntu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'libnspr4' changed from 'absent' to '2:4.13.1-0ubuntu0.16.04.1'
'libfontconfig' changed from 'absent' to '1'
'fonts-dejavu-core' changed from 'absent' to '2.35-1'
'libnss3-nssdb' changed from 'absent' to '2:3.28.4-0ubuntu0.16.04.5'
'java8-runtime-headless' changed from 'absent' to '1'
'libcups2' changed from 'absent' to '2.1.3-4ubuntu0.8'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'libnss3' changed from 'absent' to '2:3.28.4-0ubuntu0.16.04.5'
'libpcsclite1' changed from 'absent' to '1.8.14-1ubuntu1.16.04.1'
'java7-runtime-headless' changed from 'absent' to '1'
'ca-certificates-java' changed from 'absent' to '20160321ubuntu1'
'java2-runtime-headless' changed from 'absent' to '1'
'fontconfig-config' changed from 'absent' to '2.11.94-0ubuntu1.1'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'libxi6' changed from 'absent' to '2:1.7.6-1'
'java5-runtime-headless' changed from 'absent' to '1'
'java6-runtime-headless' changed from 'absent' to '1'
'x11-common' changed from 'absent' to '1:7.7+13ubuntu3.1'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'opendaylight' changed from 'absent' to '9.0.0-1ppa2'
'libfontconfig1' changed from 'absent' to '2.11.94-0ubuntu1.1'

2019-06-07 18:51:02,975 [salt.state       :915 ][INFO    ][7050] Loading fresh modules for state activity
2019-06-07 18:51:03,016 [salt.state       :1951][INFO    ][7050] Completed state [opendaylight] at time 18:51:03.016115 duration_in_ms=352550.412
2019-06-07 18:51:03,019 [salt.state       :1780][INFO    ][7050] Running state [/opt/opendaylight/etc/jetty.xml] at time 18:51:03.019691
2019-06-07 18:51:03,019 [salt.state       :1813][INFO    ][7050] Executing state file.managed for [/opt/opendaylight/etc/jetty.xml]
2019-06-07 18:51:03,053 [salt.fileclient  :1219][INFO    ][7050] Fetching file from saltenv 'base', ** done ** 'opendaylight/files/jetty.xml'
2019-06-07 18:51:03,088 [salt.state       :300 ][INFO    ][7050] File changed:
--- 
+++ 
@@ -79,11 +79,11 @@
                         </Item>
                     </Array>
                 </Arg>
-                <Set name="host">
+                <Set name="host">172.16.10.40
                     <Property name="jetty.host"/>
                 </Set>
                 <Set name="port">
-                    <Property name="jetty.port" default="8181"/>
+                    <Property name="jetty.port" default="8282"/>
                 </Set>
                 <Set name="idleTimeout">
                     <Property name="http.timeout" default="300000"/>

2019-06-07 18:51:03,088 [salt.state       :1951][INFO    ][7050] Completed state [/opt/opendaylight/etc/jetty.xml] at time 18:51:03.088262 duration_in_ms=68.571
2019-06-07 18:51:03,088 [salt.state       :1780][INFO    ][7050] Running state [/opt/opendaylight/bin/setenv] at time 18:51:03.088627
2019-06-07 18:51:03,088 [salt.state       :1813][INFO    ][7050] Executing state file.managed for [/opt/opendaylight/bin/setenv]
2019-06-07 18:51:03,109 [salt.fileclient  :1219][INFO    ][7050] Fetching file from saltenv 'base', ** done ** 'opendaylight/files/setenv.shell'
2019-06-07 18:51:03,137 [salt.state       :300 ][INFO    ][7050] File changed:
--- 
+++ 
@@ -1,55 +1,19 @@
-#!/bin/sh
-#
-#    Licensed to the Apache Software Foundation (ASF) under one or more
-#    contributor license agreements.  See the NOTICE file distributed with
-#    this work for additional information regarding copyright ownership.
-#    The ASF licenses this file to You under the Apache License, Version 2.0
-#    (the "License"); you may not use this file except in compliance with
-#    the License.  You may obtain a copy of the License at
-#
-#       http://www.apache.org/licenses/LICENSE-2.0
-#
-#    Unless required by applicable law or agreed to in writing, software
-#    distributed under the License is distributed on an "AS IS" BASIS,
-#    WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
-#    See the License for the specific language governing permissions and
-#    limitations under the License.
-#
-
-#
-# handle specific scripts; the SCRIPT_NAME is exactly the name of the Karaf
-# script: client, instance, shell, start, status, stop, karaf
-#
-# if [ "${KARAF_SCRIPT}" == "SCRIPT_NAME" ]; then
-#   Actions go here...
-# fi
-
-#
-# general settings which should be applied for all scripts go here; please keep
-# in mind that it is possible that scripts might be executed more than once, e.g.
-# in example of the start script where the start script is executed first and the
-# karaf script afterwards.
-#
-
-#
-# The following section shows the possible configuration options for the default 
-# karaf scripts
-#
-# export JAVA_HOME # Location of Java installation
-# export JAVA_MIN_MEM # Minimum memory for the JVM
-# export JAVA_MAX_MEM # Maximum memory for the JVM
+##############################################################################
+# Copyright (c) 2017 Mirantis Inc. and others.
+# All rights reserved. This program and the accompanying materials
+# are made available under the terms of the Apache License, Version 2.0
+# which accompanies this distribution, and is available at
+# http://www.apache.org/licenses/LICENSE-2.0
+############################################################################### export JAVA_HOME # Location of Java installation
+export JAVA_MIN_MEM=4g # Minimum memory for the JVM
+export JAVA_MAX_MEM=4g # Maximum memory for the JVM
 # export JAVA_PERM_MEM # Minimum perm memory for the JVM
-# export JAVA_MAX_PERM_MEM # Maximum perm memory for the JVM
-# export EXTRA_JAVA_OPTS # Additional JVM options
+export JAVA_MAX_PERM_MEM=512m # Maximum perm memory for the JVM
+export EXTRA_JAVA_OPTS='-Djava.net.preferIPv4Stack=true -XX:+UseG1GC' # Additional JVM options
 # export KARAF_HOME # Karaf home folder
 # export KARAF_DATA # Karaf data folder
 # export KARAF_BASE # Karaf base folder
 # export KARAF_ETC  # Karaf etc  folder
-# export KARAF_SYSTEM_OPTS # First citizen Karaf options
 # export KARAF_OPTS # Additional available Karaf options
 # export KARAF_DEBUG # Enable debug mode
 # export KARAF_REDIRECT # Enable/set the std/err redirection when using bin/start
-# export KARAF_NOROOT # Prevent execution as root if set to true
-if [ "x$JAVA_MAX_MEM" = "x" ]; then
-    export JAVA_MAX_MEM="2048m"
-fi

2019-06-07 18:51:03,138 [salt.state       :1951][INFO    ][7050] Completed state [/opt/opendaylight/bin/setenv] at time 18:51:03.138029 duration_in_ms=49.402
2019-06-07 18:51:03,141 [salt.state       :1780][INFO    ][7050] Running state [/opt/opendaylight/etc/org.apache.karaf.features.cfg] at time 18:51:03.141819
2019-06-07 18:51:03,142 [salt.state       :1813][INFO    ][7050] Executing state ini.options_present for [/opt/opendaylight/etc/org.apache.karaf.features.cfg]
2019-06-07 18:51:03,165 [salt.state       :300 ][INFO    ][7050] {'featuresBoot': {'after': 'standard,wrap,ssh,odl-restconf-all,odl-aaa-authn,odl-netvirt-openstack', 'before': 'c356856b-baa8-4437-a4b1-6f6fb032f029'}}
2019-06-07 18:51:03,165 [salt.state       :1951][INFO    ][7050] Completed state [/opt/opendaylight/etc/org.apache.karaf.features.cfg] at time 18:51:03.165688 duration_in_ms=23.869
2019-06-07 18:51:03,166 [salt.state       :1780][INFO    ][7050] Running state [/opt/opendaylight/etc/org.ops4j.pax.web.cfg] at time 18:51:03.166148
2019-06-07 18:51:03,166 [salt.state       :1813][INFO    ][7050] Executing state ini.options_present for [/opt/opendaylight/etc/org.ops4j.pax.web.cfg]
2019-06-07 18:51:03,168 [salt.state       :300 ][INFO    ][7050] {'org.osgi.service.http.port': {'after': '8282', 'before': '8181'}, 'org.ops4j.pax.web.listening.addresses': {'after': '172.16.10.40', 'before': None}}
2019-06-07 18:51:03,168 [salt.state       :1951][INFO    ][7050] Completed state [/opt/opendaylight/etc/org.ops4j.pax.web.cfg] at time 18:51:03.168441 duration_in_ms=2.293
2019-06-07 18:51:03,168 [salt.state       :1780][INFO    ][7050] Running state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 18:51:03.168892
2019-06-07 18:51:03,169 [salt.state       :1813][INFO    ][7050] Executing state file.comment for [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg]
2019-06-07 18:51:03,176 [salt.state       :300 ][INFO    ][7050] File changed:
--- 
+++ 
@@ -26,7 +26,7 @@
 #log4j2.rootLogger.type = asyncRoot
 #log4j2.rootLogger.includeLocation = false
 log4j2.rootLogger.appenderRef.RollingFile.ref = RollingFile
-log4j2.rootLogger.appenderRef.PaxOsgi.ref = PaxOsgi
+#log4j2.rootLogger.appenderRef.PaxOsgi.ref = PaxOsgi
 log4j2.rootLogger.appenderRef.Console.ref = Console
 log4j2.rootLogger.appenderRef.Console.filter.threshold.type = ThresholdFilter
 log4j2.rootLogger.appenderRef.Console.filter.threshold.level = ${karaf.log.console:-OFF}

2019-06-07 18:51:03,176 [salt.state       :1951][INFO    ][7050] Completed state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 18:51:03.176527 duration_in_ms=7.634
2019-06-07 18:51:03,177 [salt.state       :1780][INFO    ][7050] Running state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 18:51:03.176971
2019-06-07 18:51:03,177 [salt.state       :1813][INFO    ][7050] Executing state file.comment for [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg]
2019-06-07 18:51:03,183 [salt.state       :300 ][INFO    ][7050] File changed:
--- 
+++ 
@@ -78,7 +78,7 @@
 log4j2.appender.audit.policies.size.size = 8MB
 
 # OSGi appender
-log4j2.appender.osgi.type = PaxOsgi
+#log4j2.appender.osgi.type = PaxOsgi
 log4j2.appender.osgi.name = PaxOsgi
 log4j2.appender.osgi.filter = *
 

2019-06-07 18:51:03,183 [salt.state       :1951][INFO    ][7050] Completed state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 18:51:03.183555 duration_in_ms=6.584
2019-06-07 18:51:03,184 [salt.state       :1780][INFO    ][7050] Running state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 18:51:03.183998
2019-06-07 18:51:03,184 [salt.state       :1813][INFO    ][7050] Executing state file.comment for [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg]
2019-06-07 18:51:03,190 [salt.state       :300 ][INFO    ][7050] File changed:
--- 
+++ 
@@ -79,7 +79,7 @@
 
 # OSGi appender
 #log4j2.appender.osgi.type = PaxOsgi
-log4j2.appender.osgi.name = PaxOsgi
+#log4j2.appender.osgi.name = PaxOsgi
 log4j2.appender.osgi.filter = *
 
 # help with identification of maven-related problems with pax-url-aether

2019-06-07 18:51:03,190 [salt.state       :1951][INFO    ][7050] Completed state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 18:51:03.190282 duration_in_ms=6.284
2019-06-07 18:51:03,190 [salt.state       :1780][INFO    ][7050] Running state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 18:51:03.190732
2019-06-07 18:51:03,191 [salt.state       :1813][INFO    ][7050] Executing state file.comment for [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg]
2019-06-07 18:51:03,196 [salt.state       :300 ][INFO    ][7050] File changed:
--- 
+++ 
@@ -80,7 +80,7 @@
 # OSGi appender
 #log4j2.appender.osgi.type = PaxOsgi
 #log4j2.appender.osgi.name = PaxOsgi
-log4j2.appender.osgi.filter = *
+#log4j2.appender.osgi.filter = *
 
 # help with identification of maven-related problems with pax-url-aether
 #log4j2.logger.aether.name = shaded.org.eclipse.aether

2019-06-07 18:51:03,196 [salt.state       :1951][INFO    ][7050] Completed state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 18:51:03.196282 duration_in_ms=5.55
2019-06-07 18:51:03,196 [salt.state       :1780][INFO    ][7050] Running state [/opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg] at time 18:51:03.196667
2019-06-07 18:51:03,196 [salt.state       :1813][INFO    ][7050] Executing state file.managed for [/opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg]
2019-06-07 18:51:03,197 [salt.loaded.int.states.file:2298][WARNING ][7050] State for file: /opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-07 18:51:03,198 [salt.state       :300 ][INFO    ][7050] {'new': 'file /opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg created', 'group': 'odl', 'user': 'odl'}
2019-06-07 18:51:03,198 [salt.state       :1951][INFO    ][7050] Completed state [/opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg] at time 18:51:03.198494 duration_in_ms=1.827
2019-06-07 18:51:03,198 [salt.state       :1780][INFO    ][7050] Running state [/opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg] at time 18:51:03.198825
2019-06-07 18:51:03,199 [salt.state       :1813][INFO    ][7050] Executing state ini.options_present for [/opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg]
2019-06-07 18:51:03,199 [salt.state       :300 ][INFO    ][7050] {'is-statistics-polling-on': {'after': 'False', 'before': None}}
2019-06-07 18:51:03,199 [salt.state       :1951][INFO    ][7050] Completed state [/opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg] at time 18:51:03.199795 duration_in_ms=0.969
2019-06-07 18:51:03,200 [salt.state       :1780][INFO    ][7050] Running state [/opt/opendaylight/etc/custom.properties] at time 18:51:03.200251
2019-06-07 18:51:03,200 [salt.state       :1813][INFO    ][7050] Executing state ini.options_present for [/opt/opendaylight/etc/custom.properties]
2019-06-07 18:51:03,234 [salt.state       :300 ][INFO    ][7050] {'ovsdb.l3.fwd.enabled': {'after': 'yes', 'before': None}, 'ovsdb.of.version': {'after': '1.3', 'before': None}}
2019-06-07 18:51:03,234 [salt.state       :1951][INFO    ][7050] Completed state [/opt/opendaylight/etc/custom.properties] at time 18:51:03.234659 duration_in_ms=34.408
2019-06-07 18:51:03,235 [salt.state       :1780][INFO    ][7050] Running state [/opt/opendaylight/etc/opendaylight/datastore/initial/config/netvirt-natservice-config.xml] at time 18:51:03.235117
2019-06-07 18:51:03,235 [salt.state       :1813][INFO    ][7050] Executing state file.managed for [/opt/opendaylight/etc/opendaylight/datastore/initial/config/netvirt-natservice-config.xml]
2019-06-07 18:51:03,258 [salt.fileclient  :1219][INFO    ][7050] Fetching file from saltenv 'base', ** done ** 'opendaylight/files/netvirt-natservice-config.xml'
2019-06-07 18:51:03,289 [salt.state       :300 ][INFO    ][7050] File changed:
New file
2019-06-07 18:51:03,289 [salt.state       :1951][INFO    ][7050] Completed state [/opt/opendaylight/etc/opendaylight/datastore/initial/config/netvirt-natservice-config.xml] at time 18:51:03.289541 duration_in_ms=54.424
2019-06-07 18:51:03,774 [salt.state       :1780][INFO    ][7050] Running state [opendaylight] at time 18:51:03.774927
2019-06-07 18:51:03,775 [salt.state       :1813][INFO    ][7050] Executing state service.running for [opendaylight]
2019-06-07 18:51:03,776 [salt.state       :302 ][ERROR   ][7050] The named service opendaylight is not available
2019-06-07 18:51:03,776 [salt.state       :1951][INFO    ][7050] Completed state [opendaylight] at time 18:51:03.776613 duration_in_ms=1.687
2019-06-07 18:51:03,776 [salt.state       :1780][INFO    ][7050] Running state [opendaylight] at time 18:51:03.776844
2019-06-07 18:51:03,777 [salt.state       :1813][INFO    ][7050] Executing state service.mod_watch for [opendaylight]
2019-06-07 18:51:03,777 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7050] Executing command ['systemctl', 'is-active', 'opendaylight.service'] in directory '/root'
2019-06-07 18:51:03,797 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7050] Executing command ['systemd-run', '--scope', 'systemctl', 'stop', 'opendaylight.service'] in directory '/root'
2019-06-07 18:51:03,842 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7050] Executing command ['systemd-run', '--scope', 'systemctl', 'unmask', 'opendaylight.service'] in directory '/root'
2019-06-07 18:51:03,949 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7050] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'opendaylight.service'] in directory '/root'
2019-06-07 18:51:04,003 [salt.state       :300 ][INFO    ][7050] {'opendaylight': True}
2019-06-07 18:51:04,003 [salt.state       :1951][INFO    ][7050] Completed state [opendaylight] at time 18:51:04.003420 duration_in_ms=226.576
2019-06-07 18:51:04,008 [salt.minion      :1711][INFO    ][7050] Returning information for job: 20190607184458814680
2019-06-07 18:51:04,646 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command pillar.get with jid 20190607185104638517
2019-06-07 18:51:04,660 [salt.minion      :1432][INFO    ][11079] Starting a new job with PID 11079
2019-06-07 18:51:04,665 [salt.minion      :1711][INFO    ][11079] Returning information for job: 20190607185104638517
2019-06-07 18:51:05,191 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command pillar.get with jid 20190607185105184054
2019-06-07 18:51:05,201 [salt.minion      :1432][INFO    ][11085] Starting a new job with PID 11085
2019-06-07 18:51:05,207 [salt.minion      :1711][INFO    ][11085] Returning information for job: 20190607185105184054
2019-06-07 19:19:47,347 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command test.ping with jid 20190607191947338804
2019-06-07 19:19:47,365 [salt.minion      :1432][INFO    ][11701] Starting a new job with PID 11701
2019-06-07 19:19:47,855 [salt.minion      :1711][INFO    ][11701] Returning information for job: 20190607191947338804
2019-06-07 19:19:48,047 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command state.sls with jid 20190607191948039589
2019-06-07 19:19:48,058 [salt.minion      :1432][INFO    ][11706] Starting a new job with PID 11706
2019-06-07 19:19:54,162 [salt.state       :915 ][INFO    ][11706] Loading fresh modules for state activity
2019-06-07 19:19:54,250 [salt.fileclient  :1219][INFO    ][11706] Fetching file from saltenv 'base', ** done ** 'quagga/init.sls'
2019-06-07 19:19:54,283 [salt.fileclient  :1219][INFO    ][11706] Fetching file from saltenv 'base', ** done ** 'quagga/server.sls'
2019-06-07 19:19:54,311 [salt.fileclient  :1219][INFO    ][11706] Fetching file from saltenv 'base', ** done ** 'quagga/map.jinja'
2019-06-07 19:19:56,127 [salt.state       :1780][INFO    ][11706] Running state [libglib2.0-0] at time 19:19:56.127814
2019-06-07 19:19:56,128 [salt.state       :1813][INFO    ][11706] Executing state pkg.installed for [libglib2.0-0]
2019-06-07 19:19:56,129 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11706] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-07 19:19:57,130 [salt.state       :300 ][INFO    ][11706] All specified packages are already installed
2019-06-07 19:19:57,130 [salt.state       :1951][INFO    ][11706] Completed state [libglib2.0-0] at time 19:19:57.130582 duration_in_ms=1002.769
2019-06-07 19:19:57,133 [salt.state       :1780][INFO    ][11706] Running state [/var/cache/quagga.tar.gz] at time 19:19:57.133784
2019-06-07 19:19:57,134 [salt.state       :1813][INFO    ][11706] Executing state file.managed for [/var/cache/quagga.tar.gz]
2019-06-07 19:20:01,997 [salt.state       :300 ][INFO    ][11706] File changed:
New file
2019-06-07 19:20:01,998 [salt.state       :1951][INFO    ][11706] Completed state [/var/cache/quagga.tar.gz] at time 19:20:01.998160 duration_in_ms=4864.376
2019-06-07 19:20:02,000 [salt.state       :1780][INFO    ][11706] Running state [/tmp] at time 19:20:02.000907
2019-06-07 19:20:02,001 [salt.state       :1813][INFO    ][11706] Executing state archive.extracted for [/tmp]
2019-06-07 19:20:02,786 [salt.state       :300 ][INFO    ][11706] {'updated ownership': True, 'extracted_files': ['quagga', 'quagga/zrpc_0.2.f3c72f8.thriftv4.Ubuntu16.04_amd64.deb', 'quagga/quagga_1.1.0.9c1fad6.Ubuntu16.04_amd64.deb', 'quagga/c-capnproto_1.0.2.75f7901.Ubuntu16.04_amd64.deb', 'quagga/zmq_4.1.3.56b71af.Ubuntu16.04_amd64.deb', 'quagga/thrift_1.0.0.b2a4d4a.Ubuntu16.04_amd64.deb']}
2019-06-07 19:20:02,786 [salt.state       :1951][INFO    ][11706] Completed state [/tmp] at time 19:20:02.786504 duration_in_ms=785.597
2019-06-07 19:20:02,787 [salt.state       :1780][INFO    ][11706] Running state [dpkg -i $(ls |grep -vE 'debuginfo|devel|contrib')] at time 19:20:02.787610
2019-06-07 19:20:02,787 [salt.state       :1813][INFO    ][11706] Executing state cmd.run for [dpkg -i $(ls |grep -vE 'debuginfo|devel|contrib')]
2019-06-07 19:20:02,788 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11706] Executing command 'dpkg -i $(ls |grep -vE 'debuginfo|devel|contrib')' as user 'root' in directory '/tmp/quagga'
2019-06-07 19:20:03,155 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command saltutil.find_job with jid 20190607192003147776
2019-06-07 19:20:03,188 [salt.minion      :1432][INFO    ][11797] Starting a new job with PID 11797
2019-06-07 19:20:03,495 [salt.minion      :1711][INFO    ][11797] Returning information for job: 20190607192003147776
2019-06-07 19:20:07,751 [salt.state       :300 ][INFO    ][11706] {'pid': 11757, 'retcode': 0, 'stderr': '', 'stdout': 'Selecting previously unselected package c-capnproto.\n(Reading database ... 104028 files and directories currently installed.)\nPreparing to unpack c-capnproto_1.0.2.75f7901.Ubuntu16.04_amd64.deb ...\nUnpacking c-capnproto (1.0.2.75f7901.Ubuntu16.04) ...\nSelecting previously unselected package quagga.\nPreparing to unpack quagga_1.1.0.9c1fad6.Ubuntu16.04_amd64.deb ...\nUnpacking quagga (1.1.0.9c1fad6.Ubuntu16.04) ...\nSelecting previously unselected package thrift.\nPreparing to unpack thrift_1.0.0.b2a4d4a.Ubuntu16.04_amd64.deb ...\nUnpacking thrift (1.0.0.b2a4d4a.Ubuntu16.04) ...\nSelecting previously unselected package zmq.\nPreparing to unpack zmq_4.1.3.56b71af.Ubuntu16.04_amd64.deb ...\nUnpacking zmq (4.1.3.56b71af.Ubuntu16.04) ...\nSelecting previously unselected package zrpc.\nPreparing to unpack zrpc_0.2.f3c72f8.thriftv4.Ubuntu16.04_amd64.deb ...\nUnpacking zrpc (0.2.f3c72f8.thriftv4.Ubuntu16.04) ...\nSetting up c-capnproto (1.0.2.75f7901.Ubuntu16.04) ...\nSetting up thrift (1.0.0.b2a4d4a.Ubuntu16.04) ...\nSetting up zmq (4.1.3.56b71af.Ubuntu16.04) ...\nSetting up quagga (1.1.0.9c1fad6.Ubuntu16.04) ...\nSetting up zrpc (0.2.f3c72f8.thriftv4.Ubuntu16.04) ...'}
2019-06-07 19:20:07,751 [salt.state       :1951][INFO    ][11706] Completed state [dpkg -i $(ls |grep -vE 'debuginfo|devel|contrib')] at time 19:20:07.751584 duration_in_ms=4963.974
2019-06-07 19:20:07,751 [salt.state       :1780][INFO    ][11706] Running state [/opt/quagga/etc/init.d/zrpcd start] at time 19:20:07.751949
2019-06-07 19:20:07,752 [salt.state       :1813][INFO    ][11706] Executing state cmd.run for [/opt/quagga/etc/init.d/zrpcd start]
2019-06-07 19:20:07,753 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11706] Executing command '/opt/quagga/etc/init.d/zrpcd start' as user 'root' in directory '/root'
2019-06-07 19:20:08,850 [salt.state       :300 ][INFO    ][11706] {'pid': 11930, 'retcode': 0, 'stderr': '', 'stdout': ' * Starting Zebra RPC Daemon for Quagga Framework  zrpcd\n   ...done.'}
2019-06-07 19:20:08,850 [salt.state       :1951][INFO    ][11706] Completed state [/opt/quagga/etc/init.d/zrpcd start] at time 19:20:08.850758 duration_in_ms=1098.809
2019-06-07 19:20:08,853 [salt.minion      :1711][INFO    ][11706] Returning information for job: 20190607191948039589
2019-06-07 19:21:46,386 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command cp.push_dir with jid 20190607192146377685
2019-06-07 19:21:46,408 [salt.minion      :1432][INFO    ][12015] Starting a new job with PID 12015
2019-06-07 19:21:46,736 [salt.minion      :1711][INFO    ][12015] Returning information for job: 20190607192146377685
2019-06-07 19:44:30,394 [salt.utils.schedule:1377][INFO    ][6961] Running scheduled job: __mine_interval
2019-06-07 20:44:30,396 [salt.utils.schedule:1377][INFO    ][6961] Running scheduled job: __mine_interval
2019-06-07 21:44:30,394 [salt.utils.schedule:1377][INFO    ][6961] Running scheduled job: __mine_interval
2019-06-07 22:44:30,393 [salt.utils.schedule:1377][INFO    ][6961] Running scheduled job: __mine_interval
2019-06-07 23:44:30,394 [salt.utils.schedule:1377][INFO    ][6961] Running scheduled job: __mine_interval
2019-06-08 00:44:30,394 [salt.utils.schedule:1377][INFO    ][6961] Running scheduled job: __mine_interval
2019-06-08 01:44:30,393 [salt.utils.schedule:1377][INFO    ][6961] Running scheduled job: __mine_interval
2019-06-08 02:44:30,393 [salt.utils.schedule:1377][INFO    ][6961] Running scheduled job: __mine_interval
2019-06-08 03:44:30,394 [salt.utils.schedule:1377][INFO    ][6961] Running scheduled job: __mine_interval
2019-06-08 04:43:42,523 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command grains.get with jid 20190608044342482640
2019-06-08 04:43:42,716 [salt.minion      :1432][INFO    ][19038] Starting a new job with PID 19038
2019-06-08 04:43:42,773 [salt.minion      :1711][INFO    ][19038] Returning information for job: 20190608044342482640
2019-06-08 04:44:30,392 [salt.utils.schedule:1377][INFO    ][6961] Running scheduled job: __mine_interval
2019-06-08 05:04:59,293 [salt.minion      :1308][INFO    ][6961] User sudo_ubuntu Executing command cp.push_dir with jid 20190608050459281549
2019-06-08 05:04:59,402 [salt.minion      :1432][INFO    ][19304] Starting a new job with PID 19304
