2019-10-18 07:30:15,161 [salt.utils       :831 ][ERROR   ][1622] DNS lookup or connection check of 'salt' failed.
2019-10-18 07:30:15,161 [salt.minion      :152 ][ERROR   ][1622] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-10-18 07:30:17,228 [salt.utils.parsers:1051][WARNING ][1622] Minion received a SIGTERM. Exiting.
2019-10-18 07:30:58,930 [salt.minion      :870 ][ERROR   ][1839] Error while bringing up minion for multi-master. Is master at 192.168.11.2 responding?
2019-10-18 07:32:02,463 [salt.utils.decorators:613 ][WARNING ][2069] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-18 07:32:18,941 [salt.loaded.int.states.file:2298][WARNING ][2069] 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-10-18 07:32:29,104 [salt.utils.decorators:613 ][WARNING ][2069] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-18 07:32:29,217 [salt.loaded.int.states.file:2298][WARNING ][2069] 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-10-18 07:32:29,218 [salt.loaded.int.states.file:2298][WARNING ][2069] 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-10-18 07:32:29,219 [salt.loaded.int.states.file:2298][WARNING ][2069] 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-10-18 07:32:29,221 [salt.loaded.int.states.file:2298][WARNING ][2069] 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-10-18 07:32:29,222 [salt.loaded.int.states.file:2298][WARNING ][2069] 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-10-18 07:32:29,223 [salt.loaded.int.states.file:2298][WARNING ][2069] 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-10-18 07:32:29,224 [salt.loaded.int.states.file:2298][WARNING ][2069] 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-10-18 07:32:29,225 [salt.loaded.int.states.file:2298][WARNING ][2069] 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-10-18 07:32:35,169 [salt.loaded.int.module.debian_ip:1964][WARNING ][2069] The network state sls is requiring a reboot of the system to properly apply network configuration.
2019-10-18 07:32:35,387 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][2069] Command '['umount', '/dev/shm']' failed with return code: 32
2019-10-18 07:32:35,388 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][2069] 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-10-18 07:32:35,388 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][2069] retcode: 32
2019-10-18 07:32:48,835 [salt.utils.parsers:1051][WARNING ][1839] Minion received a SIGTERM. Exiting.
2019-10-18 07:34:51,037 [salt.utils.decorators:613 ][WARNING ][3910] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-18 07:35:01,997 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5439] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-10-18 07:35:02,028 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5439] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-10-18 07:35:02,051 [salt.utils.parsers:1051][WARNING ][2346] Minion received a SIGTERM. Exiting.
2019-10-18 07:35:03,071 [salt.cli.daemons :293 ][INFO    ][5495] Setting up the Salt Minion "odl01.mcp-odl-noha.local"
2019-10-18 07:35:03,261 [salt.cli.daemons :82  ][INFO    ][5495] Starting up the Salt Minion
2019-10-18 07:35:03,261 [salt.utils.event :1017][INFO    ][5495] Starting pull socket on /var/run/salt/minion/minion_event_0d1d1cf3c0_pull.ipc
2019-10-18 07:35:03,855 [salt.minion      :976 ][INFO    ][5495] Creating minion process manager
2019-10-18 07:35:05,117 [salt.loader.192.168.11.2.int.module.cmdmod:395 ][INFO    ][5495] Executing command ['date', '+%z'] in directory '/root'
2019-10-18 07:35:05,134 [salt.utils.schedule:568 ][INFO    ][5495] Updating job settings for scheduled job: __mine_interval
2019-10-18 07:35:05,156 [salt.minion      :1108][INFO    ][5495] Added mine.update to scheduler
2019-10-18 07:35:05,208 [salt.minion      :1975][INFO    ][5495] Minion is starting as user 'root'
2019-10-18 07:35:05,222 [salt.minion      :2336][INFO    ][5495] Minion is ready to receive requests!
2019-10-18 07:35:39,009 [salt.minion      :1308][INFO    ][5495] User sudo_ubuntu Executing command state.sls with jid 20191018073539000626
2019-10-18 07:35:39,024 [salt.minion      :1432][INFO    ][5584] Starting a new job with PID 5584
2019-10-18 07:35:44,832 [salt.state       :915 ][INFO    ][5584] Loading fresh modules for state activity
2019-10-18 07:35:44,908 [salt.fileclient  :1219][INFO    ][5584] Fetching file from saltenv 'base', ** done ** 'opendaylight/init.sls'
2019-10-18 07:35:44,942 [salt.fileclient  :1219][INFO    ][5584] Fetching file from saltenv 'base', ** done ** 'opendaylight/server.sls'
2019-10-18 07:35:44,975 [salt.fileclient  :1219][INFO    ][5584] Fetching file from saltenv 'base', ** done ** 'opendaylight/map.jinja'
2019-10-18 07:35:45,014 [salt.fileclient  :1219][INFO    ][5584] Fetching file from saltenv 'base', ** done ** 'opendaylight/config.sls'
2019-10-18 07:35:45,084 [salt.fileclient  :1219][INFO    ][5584] Fetching file from saltenv 'base', ** done ** 'opendaylight/repo.sls'
2019-10-18 07:35:45,808 [salt.state       :1780][INFO    ][5584] 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 07:35:45.808338
2019-10-18 07:35:45,808 [salt.state       :1813][INFO    ][5584] 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-10-18 07:35:45,809 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5584] Executing command 'apt-key list | grep -qF '4096R/44C05248 2017-01-26'' in directory '/root'
2019-10-18 07:35:45,933 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5584] 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-10-18 07:35:46,169 [salt.state       :300 ][INFO    ][5584] {'pid': 5646, 'retcode': 0, 'stderr': '', 'stdout': 'OK'}
2019-10-18 07:35:46,170 [salt.state       :1951][INFO    ][5584] 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 07:35:46.170409 duration_in_ms=362.071
2019-10-18 07:35:46,172 [salt.state       :1780][INFO    ][5584] Running state [deb http://ppa.launchpad.net/odl-team/fluorine/ubuntu xenial main] at time 07:35:46.172888
2019-10-18 07:35:46,173 [salt.state       :1813][INFO    ][5584] Executing state pkgrepo.managed for [deb http://ppa.launchpad.net/odl-team/fluorine/ubuntu xenial main]
2019-10-18 07:35:46,273 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5584] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-10-18 07:35:50,471 [salt.state       :300 ][INFO    ][5584] {'repo': 'deb http://ppa.launchpad.net/odl-team/fluorine/ubuntu xenial main'}
2019-10-18 07:35:50,471 [salt.state       :1951][INFO    ][5584] Completed state [deb http://ppa.launchpad.net/odl-team/fluorine/ubuntu xenial main] at time 07:35:50.471877 duration_in_ms=4298.988
2019-10-18 07:35:50,473 [salt.state       :1780][INFO    ][5584] Running state [opendaylight] at time 07:35:50.473424
2019-10-18 07:35:50,474 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5584] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-18 07:35:50,906 [salt.state       :1780][INFO    ][5584] Running state [opendaylight] at time 07:35:50.905981
2019-10-18 07:35:50,906 [salt.state       :1813][INFO    ][5584] Executing state service.masked for [opendaylight]
2019-10-18 07:35:50,907 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5584] Executing command ['systemctl', 'status', 'opendaylight.service', '-n', '0'] in directory '/root'
2019-10-18 07:35:50,945 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5584] Executing command ['systemd-run', '--scope', 'systemctl', 'mask', 'opendaylight.service'] in directory '/root'
2019-10-18 07:35:51,054 [salt.state       :300 ][INFO    ][5584] Made the following changes:
'masked' changed from 'False' to 'True'

2019-10-18 07:35:51,054 [salt.state       :1951][INFO    ][5584] Completed state [opendaylight] at time 07:35:51.054858 duration_in_ms=148.877
2019-10-18 07:35:51,055 [salt.state       :1780][INFO    ][5584] Running state [opendaylight] at time 07:35:51.055714
2019-10-18 07:35:51,056 [salt.state       :1813][INFO    ][5584] Executing state pkg.installed for [opendaylight]
2019-10-18 07:35:51,083 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5584] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-10-18 07:35:51,113 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5584] 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-10-18 07:35:54,129 [salt.minion      :1308][INFO    ][5495] User sudo_ubuntu Executing command saltutil.find_job with jid 20191018073554120727
2019-10-18 07:35:54,142 [salt.minion      :1432][INFO    ][6236] Starting a new job with PID 6236
2019-10-18 07:35:54,158 [salt.minion      :1711][INFO    ][6236] Returning information for job: 20191018073554120727
2019-10-18 07:36:24,154 [salt.minion      :1308][INFO    ][5495] User sudo_ubuntu Executing command saltutil.find_job with jid 20191018073624146193
2019-10-18 07:36:24,169 [salt.minion      :1432][INFO    ][6462] Starting a new job with PID 6462
2019-10-18 07:36:24,184 [salt.minion      :1711][INFO    ][6462] Returning information for job: 20191018073624146193
2019-10-18 07:36:33,943 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5584] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-18 07:36:33,984 [salt.state       :300 ][INFO    ][5584] 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 '8u222-b10-1ubuntu1~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.6'
'java8-runtime-headless' changed from 'absent' to '1'
'libcups2' changed from 'absent' to '2.1.3-4ubuntu0.10'
'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.6'
'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-10-18 07:36:34,026 [salt.state       :915 ][INFO    ][5584] Loading fresh modules for state activity
2019-10-18 07:36:34,110 [salt.state       :1951][INFO    ][5584] Completed state [opendaylight] at time 07:36:34.110733 duration_in_ms=43055.018
2019-10-18 07:36:34,114 [salt.state       :1780][INFO    ][5584] Running state [/opt/opendaylight/etc/jetty.xml] at time 07:36:34.114339
2019-10-18 07:36:34,114 [salt.state       :1813][INFO    ][5584] Executing state file.managed for [/opt/opendaylight/etc/jetty.xml]
2019-10-18 07:36:34,143 [salt.fileclient  :1219][INFO    ][5584] Fetching file from saltenv 'base', ** done ** 'opendaylight/files/jetty.xml'
2019-10-18 07:36:34,177 [salt.state       :300 ][INFO    ][5584] 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-10-18 07:36:34,177 [salt.state       :1951][INFO    ][5584] Completed state [/opt/opendaylight/etc/jetty.xml] at time 07:36:34.177923 duration_in_ms=63.584
2019-10-18 07:36:34,178 [salt.state       :1780][INFO    ][5584] Running state [/opt/opendaylight/bin/setenv] at time 07:36:34.178271
2019-10-18 07:36:34,178 [salt.state       :1813][INFO    ][5584] Executing state file.managed for [/opt/opendaylight/bin/setenv]
2019-10-18 07:36:34,196 [salt.fileclient  :1219][INFO    ][5584] Fetching file from saltenv 'base', ** done ** 'opendaylight/files/setenv.shell'
2019-10-18 07:36:34,222 [salt.state       :300 ][INFO    ][5584] 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-10-18 07:36:34,222 [salt.state       :1951][INFO    ][5584] Completed state [/opt/opendaylight/bin/setenv] at time 07:36:34.222248 duration_in_ms=43.977
2019-10-18 07:36:34,227 [salt.state       :1780][INFO    ][5584] Running state [/opt/opendaylight/etc/org.apache.karaf.features.cfg] at time 07:36:34.227647
2019-10-18 07:36:34,227 [salt.state       :1813][INFO    ][5584] Executing state ini.options_present for [/opt/opendaylight/etc/org.apache.karaf.features.cfg]
2019-10-18 07:36:34,251 [salt.state       :300 ][INFO    ][5584] {'featuresBoot': {'after': 'standard,wrap,ssh,odl-restconf-all,odl-aaa-authn,odl-netvirt-openstack', 'before': 'c356856b-baa8-4437-a4b1-6f6fb032f029'}}
2019-10-18 07:36:34,251 [salt.state       :1951][INFO    ][5584] Completed state [/opt/opendaylight/etc/org.apache.karaf.features.cfg] at time 07:36:34.251453 duration_in_ms=23.806
2019-10-18 07:36:34,251 [salt.state       :1780][INFO    ][5584] Running state [/opt/opendaylight/etc/org.ops4j.pax.web.cfg] at time 07:36:34.251874
2019-10-18 07:36:34,252 [salt.state       :1813][INFO    ][5584] Executing state ini.options_present for [/opt/opendaylight/etc/org.ops4j.pax.web.cfg]
2019-10-18 07:36:34,253 [salt.state       :300 ][INFO    ][5584] {'org.osgi.service.http.port': {'after': '8282', 'before': '8181'}, 'org.ops4j.pax.web.listening.addresses': {'after': '172.16.10.40', 'before': None}}
2019-10-18 07:36:34,254 [salt.state       :1951][INFO    ][5584] Completed state [/opt/opendaylight/etc/org.ops4j.pax.web.cfg] at time 07:36:34.253984 duration_in_ms=2.109
2019-10-18 07:36:34,254 [salt.state       :1780][INFO    ][5584] Running state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 07:36:34.254383
2019-10-18 07:36:34,254 [salt.state       :1813][INFO    ][5584] Executing state file.comment for [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg]
2019-10-18 07:36:34,261 [salt.state       :300 ][INFO    ][5584] 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-10-18 07:36:34,261 [salt.state       :1951][INFO    ][5584] Completed state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 07:36:34.261352 duration_in_ms=6.969
2019-10-18 07:36:34,261 [salt.state       :1780][INFO    ][5584] Running state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 07:36:34.261772
2019-10-18 07:36:34,262 [salt.state       :1813][INFO    ][5584] Executing state file.comment for [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg]
2019-10-18 07:36:34,267 [salt.state       :300 ][INFO    ][5584] 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-10-18 07:36:34,267 [salt.state       :1951][INFO    ][5584] Completed state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 07:36:34.267767 duration_in_ms=5.995
2019-10-18 07:36:34,268 [salt.state       :1780][INFO    ][5584] Running state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 07:36:34.268183
2019-10-18 07:36:34,268 [salt.state       :1813][INFO    ][5584] Executing state file.comment for [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg]
2019-10-18 07:36:34,273 [salt.state       :300 ][INFO    ][5584] 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-10-18 07:36:34,273 [salt.state       :1951][INFO    ][5584] Completed state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 07:36:34.273912 duration_in_ms=5.729
2019-10-18 07:36:34,274 [salt.state       :1780][INFO    ][5584] Running state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 07:36:34.274318
2019-10-18 07:36:34,274 [salt.state       :1813][INFO    ][5584] Executing state file.comment for [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg]
2019-10-18 07:36:34,281 [salt.state       :300 ][INFO    ][5584] 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-10-18 07:36:34,282 [salt.state       :1951][INFO    ][5584] Completed state [/opt/opendaylight/etc/org.ops4j.pax.logging.cfg] at time 07:36:34.281962 duration_in_ms=7.644
2019-10-18 07:36:34,282 [salt.state       :1780][INFO    ][5584] Running state [/opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg] at time 07:36:34.282386
2019-10-18 07:36:34,282 [salt.state       :1813][INFO    ][5584] Executing state file.managed for [/opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg]
2019-10-18 07:36:34,283 [salt.loaded.int.states.file:2298][WARNING ][5584] 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-10-18 07:36:34,284 [salt.state       :300 ][INFO    ][5584] {'new': 'file /opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg created', 'group': 'odl', 'user': 'odl'}
2019-10-18 07:36:34,284 [salt.state       :1951][INFO    ][5584] Completed state [/opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg] at time 07:36:34.284330 duration_in_ms=1.944
2019-10-18 07:36:34,284 [salt.state       :1780][INFO    ][5584] Running state [/opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg] at time 07:36:34.284718
2019-10-18 07:36:34,284 [salt.state       :1813][INFO    ][5584] Executing state ini.options_present for [/opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg]
2019-10-18 07:36:34,285 [salt.state       :300 ][INFO    ][5584] {'is-statistics-polling-on': {'after': 'False', 'before': None}}
2019-10-18 07:36:34,285 [salt.state       :1951][INFO    ][5584] Completed state [/opt/opendaylight/etc/org.opendaylight.openflowplugin.cfg] at time 07:36:34.285840 duration_in_ms=1.122
2019-10-18 07:36:34,286 [salt.state       :1780][INFO    ][5584] Running state [/opt/opendaylight/etc/custom.properties] at time 07:36:34.286219
2019-10-18 07:36:34,286 [salt.state       :1813][INFO    ][5584] Executing state ini.options_present for [/opt/opendaylight/etc/custom.properties]
2019-10-18 07:36:34,318 [salt.state       :300 ][INFO    ][5584] {'ovsdb.l3.fwd.enabled': {'after': 'yes', 'before': None}, 'ovsdb.of.version': {'after': '1.3', 'before': None}}
2019-10-18 07:36:34,318 [salt.state       :1951][INFO    ][5584] Completed state [/opt/opendaylight/etc/custom.properties] at time 07:36:34.318512 duration_in_ms=32.292
2019-10-18 07:36:34,318 [salt.state       :1780][INFO    ][5584] Running state [/opt/opendaylight/etc/opendaylight/datastore/initial/config/netvirt-natservice-config.xml] at time 07:36:34.318908
2019-10-18 07:36:34,319 [salt.state       :1813][INFO    ][5584] Executing state file.managed for [/opt/opendaylight/etc/opendaylight/datastore/initial/config/netvirt-natservice-config.xml]
2019-10-18 07:36:34,338 [salt.fileclient  :1219][INFO    ][5584] Fetching file from saltenv 'base', ** done ** 'opendaylight/files/netvirt-natservice-config.xml'
2019-10-18 07:36:34,368 [salt.state       :300 ][INFO    ][5584] File changed:
New file
2019-10-18 07:36:34,368 [salt.state       :1951][INFO    ][5584] Completed state [/opt/opendaylight/etc/opendaylight/datastore/initial/config/netvirt-natservice-config.xml] at time 07:36:34.368458 duration_in_ms=49.55
2019-10-18 07:36:34,840 [salt.state       :1780][INFO    ][5584] Running state [opendaylight] at time 07:36:34.840317
2019-10-18 07:36:34,840 [salt.state       :1813][INFO    ][5584] Executing state service.running for [opendaylight]
2019-10-18 07:36:34,841 [salt.state       :302 ][ERROR   ][5584] The named service opendaylight is not available
2019-10-18 07:36:34,842 [salt.state       :1951][INFO    ][5584] Completed state [opendaylight] at time 07:36:34.842130 duration_in_ms=1.814
2019-10-18 07:36:34,842 [salt.state       :1780][INFO    ][5584] Running state [opendaylight] at time 07:36:34.842346
2019-10-18 07:36:34,842 [salt.state       :1813][INFO    ][5584] Executing state service.mod_watch for [opendaylight]
2019-10-18 07:36:34,843 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5584] Executing command ['systemctl', 'is-active', 'opendaylight.service'] in directory '/root'
2019-10-18 07:36:34,861 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5584] Executing command ['systemd-run', '--scope', 'systemctl', 'stop', 'opendaylight.service'] in directory '/root'
2019-10-18 07:36:34,909 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5584] Executing command ['systemd-run', '--scope', 'systemctl', 'unmask', 'opendaylight.service'] in directory '/root'
2019-10-18 07:36:35,025 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5584] Executing command ['systemd-run', '--scope', 'systemctl', 'start', 'opendaylight.service'] in directory '/root'
2019-10-18 07:36:35,081 [salt.state       :300 ][INFO    ][5584] {'opendaylight': True}
2019-10-18 07:36:35,081 [salt.state       :1951][INFO    ][5584] Completed state [opendaylight] at time 07:36:35.081388 duration_in_ms=239.041
2019-10-18 07:36:35,086 [salt.minion      :1711][INFO    ][5584] Returning information for job: 20191018073539000626
2019-10-18 07:36:35,762 [salt.minion      :1308][INFO    ][5495] User sudo_ubuntu Executing command pillar.get with jid 20191018073635751675
2019-10-18 07:36:35,774 [salt.minion      :1432][INFO    ][9559] Starting a new job with PID 9559
2019-10-18 07:36:35,780 [salt.minion      :1711][INFO    ][9559] Returning information for job: 20191018073635751675
2019-10-18 07:36:36,289 [salt.minion      :1308][INFO    ][5495] User sudo_ubuntu Executing command pillar.get with jid 20191018073636281453
2019-10-18 07:36:36,302 [salt.minion      :1432][INFO    ][9567] Starting a new job with PID 9567
2019-10-18 07:36:36,308 [salt.minion      :1711][INFO    ][9567] Returning information for job: 20191018073636281453
2019-10-18 08:05:49,430 [salt.minion      :1308][INFO    ][5495] User sudo_ubuntu Executing command test.ping with jid 20191018080549418352
2019-10-18 08:05:49,442 [salt.minion      :1432][INFO    ][10164] Starting a new job with PID 10164
2019-10-18 08:05:49,461 [salt.minion      :1711][INFO    ][10164] Returning information for job: 20191018080549418352
2019-10-18 08:05:49,580 [salt.minion      :1308][INFO    ][5495] User sudo_ubuntu Executing command state.sls with jid 20191018080549571631
2019-10-18 08:05:49,592 [salt.minion      :1432][INFO    ][10169] Starting a new job with PID 10169
2019-10-18 08:05:55,442 [salt.state       :915 ][INFO    ][10169] Loading fresh modules for state activity
2019-10-18 08:05:55,495 [salt.fileclient  :1219][INFO    ][10169] Fetching file from saltenv 'base', ** done ** 'quagga/init.sls'
2019-10-18 08:05:55,529 [salt.fileclient  :1219][INFO    ][10169] Fetching file from saltenv 'base', ** done ** 'quagga/server.sls'
2019-10-18 08:05:55,554 [salt.fileclient  :1219][INFO    ][10169] Fetching file from saltenv 'base', ** done ** 'quagga/map.jinja'
2019-10-18 08:05:56,226 [salt.state       :1780][INFO    ][10169] Running state [libglib2.0-0] at time 08:05:56.226357
2019-10-18 08:05:56,227 [salt.state       :1813][INFO    ][10169] Executing state pkg.installed for [libglib2.0-0]
2019-10-18 08:05:56,227 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10169] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-18 08:05:56,610 [salt.state       :300 ][INFO    ][10169] All specified packages are already installed
2019-10-18 08:05:56,611 [salt.state       :1951][INFO    ][10169] Completed state [libglib2.0-0] at time 08:05:56.611275 duration_in_ms=384.918
2019-10-18 08:05:56,614 [salt.state       :1780][INFO    ][10169] Running state [/var/cache/quagga.tar.gz] at time 08:05:56.614425
2019-10-18 08:05:56,614 [salt.state       :1813][INFO    ][10169] Executing state file.managed for [/var/cache/quagga.tar.gz]
2019-10-18 08:06:00,479 [salt.state       :300 ][INFO    ][10169] File changed:
New file
2019-10-18 08:06:00,480 [salt.state       :1951][INFO    ][10169] Completed state [/var/cache/quagga.tar.gz] at time 08:06:00.480024 duration_in_ms=3865.598
2019-10-18 08:06:00,482 [salt.state       :1780][INFO    ][10169] Running state [/tmp] at time 08:06:00.482046
2019-10-18 08:06:00,482 [salt.state       :1813][INFO    ][10169] Executing state archive.extracted for [/tmp]
2019-10-18 08:06:00,749 [salt.state       :300 ][INFO    ][10169] {'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-10-18 08:06:00,749 [salt.state       :1951][INFO    ][10169] Completed state [/tmp] at time 08:06:00.749825 duration_in_ms=267.779
2019-10-18 08:06:00,750 [salt.state       :1780][INFO    ][10169] Running state [dpkg -i $(ls |grep -vE 'debuginfo|devel|contrib')] at time 08:06:00.750827
2019-10-18 08:06:00,751 [salt.state       :1813][INFO    ][10169] Executing state cmd.run for [dpkg -i $(ls |grep -vE 'debuginfo|devel|contrib')]
2019-10-18 08:06:00,751 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10169] Executing command 'dpkg -i $(ls |grep -vE 'debuginfo|devel|contrib')' as user 'root' in directory '/tmp/quagga'
2019-10-18 08:06:03,826 [salt.state       :300 ][INFO    ][10169] {'pid': 10227, 'retcode': 0, 'stderr': '', 'stdout': 'Selecting previously unselected package c-capnproto.\n(Reading database ... 103927 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-10-18 08:06:03,829 [salt.state       :1951][INFO    ][10169] Completed state [dpkg -i $(ls |grep -vE 'debuginfo|devel|contrib')] at time 08:06:03.829510 duration_in_ms=3078.682
2019-10-18 08:06:03,830 [salt.state       :1780][INFO    ][10169] Running state [/opt/quagga/etc/init.d/zrpcd start] at time 08:06:03.830094
2019-10-18 08:06:03,830 [salt.state       :1813][INFO    ][10169] Executing state cmd.run for [/opt/quagga/etc/init.d/zrpcd start]
2019-10-18 08:06:03,831 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10169] Executing command '/opt/quagga/etc/init.d/zrpcd start' as user 'root' in directory '/root'
2019-10-18 08:06:04,674 [salt.minion      :1308][INFO    ][5495] User sudo_ubuntu Executing command saltutil.find_job with jid 20191018080604666497
2019-10-18 08:06:04,687 [salt.minion      :1432][INFO    ][10442] Starting a new job with PID 10442
2019-10-18 08:06:04,702 [salt.minion      :1711][INFO    ][10442] Returning information for job: 20191018080604666497
2019-10-18 08:06:04,950 [salt.state       :300 ][INFO    ][10169] {'pid': 10405, 'retcode': 0, 'stderr': '', 'stdout': ' * Starting Zebra RPC Daemon for Quagga Framework  zrpcd\n   ...done.'}
2019-10-18 08:06:04,950 [salt.state       :1951][INFO    ][10169] Completed state [/opt/quagga/etc/init.d/zrpcd start] at time 08:06:04.950417 duration_in_ms=1120.322
2019-10-18 08:06:04,952 [salt.minion      :1711][INFO    ][10169] Returning information for job: 20191018080549571631
2019-10-18 08:07:46,986 [salt.minion      :1308][INFO    ][5495] User sudo_ubuntu Executing command cp.push_dir with jid 20191018080746978385
2019-10-18 08:07:47,000 [salt.minion      :1432][INFO    ][10508] Starting a new job with PID 10508
2019-10-18 08:07:47,239 [salt.minion      :1711][INFO    ][10508] Returning information for job: 20191018080746978385
2019-10-18 08:35:06,226 [salt.utils.schedule:1377][INFO    ][5495] Running scheduled job: __mine_interval
2019-10-18 09:35:06,237 [salt.utils.schedule:1377][INFO    ][5495] Running scheduled job: __mine_interval
2019-10-18 10:35:06,225 [salt.utils.schedule:1377][INFO    ][5495] Running scheduled job: __mine_interval
2019-10-18 11:35:06,225 [salt.utils.schedule:1377][INFO    ][5495] Running scheduled job: __mine_interval
2019-10-18 12:35:06,225 [salt.utils.schedule:1377][INFO    ][5495] Running scheduled job: __mine_interval
2019-10-18 13:35:06,225 [salt.utils.schedule:1377][INFO    ][5495] Running scheduled job: __mine_interval
2019-10-18 14:35:06,225 [salt.utils.schedule:1377][INFO    ][5495] Running scheduled job: __mine_interval
2019-10-18 15:35:06,225 [salt.utils.schedule:1377][INFO    ][5495] Running scheduled job: __mine_interval
2019-10-18 16:04:40,280 [salt.minion      :1308][INFO    ][5495] User sudo_ubuntu Executing command grains.get with jid 20191018160440261601
2019-10-18 16:04:40,422 [salt.minion      :1432][INFO    ][16496] Starting a new job with PID 16496
2019-10-18 16:04:40,464 [salt.minion      :1711][INFO    ][16496] Returning information for job: 20191018160440261601
2019-10-18 16:35:06,225 [salt.utils.schedule:1377][INFO    ][5495] Running scheduled job: __mine_interval
2019-10-18 17:35:06,226 [salt.utils.schedule:1377][INFO    ][5495] Running scheduled job: __mine_interval
2019-10-18 18:35:06,226 [salt.utils.schedule:1377][INFO    ][5495] Running scheduled job: __mine_interval
2019-10-18 19:03:24,601 [salt.minion      :1308][INFO    ][5495] User sudo_ubuntu Executing command cp.push_dir with jid 20191018190324575474
2019-10-18 19:03:24,623 [salt.minion      :1432][INFO    ][18272] Starting a new job with PID 18272
