Using /home/tester/sonic_ansible/kenie/Azure/ansible/ansible.cfg as config file Loaded callback default of type stdout, v2.0 Loaded callback profile_tasks of type aggregate, v2.0 2 plays in test_sonic.yml PLAY *************************************************************************** TASK [setup] ******************************************************************* Tuesday 26 September 2017 14:30:26 +0800 (0:00:00.023) 0:00:00.023 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=mgifdrqkajljbhijpvwxxvkwftxndozd] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-mgifdrqkajljbhijpvwxxvkwftxndozd; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' ok: [switch2] TASK [test : include] ********************************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/main.yml:4 Tuesday 26 September 2017 14:30:27 +0800 (0:00:01.545) 0:00:01.568 ***** included: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/sonic.yml for switch2 TASK [test : Gathering minigraph facts about the device] *********************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/sonic.yml:1 Tuesday 26 September 2017 14:30:27 +0800 (0:00:00.206) 0:00:01.774 ***** ESTABLISH LOCAL CONNECTION FOR USER: root 172.20.192.94 EXEC ( umask 22 && mkdir -p "$( echo $HOME/.ansible/tmp/ansible-tmp-1506407427.84-45348037518439 )" && echo "$( echo $HOME/.ansible/tmp/ansible-tmp-1506407427.84-45348037518439 )" ) 172.20.192.94 PUT /tmp/tmpAUdgm7 TO /root/.ansible/tmp/ansible-tmp-1506407427.84-45348037518439/minigraph_facts 172.20.192.94 EXEC LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/env python /root/.ansible/tmp/ansible-tmp-1506407427.84-45348037518439/minigraph_facts; rm -rf "/root/.ansible/tmp/ansible-tmp-1506407427.84-45348037518439/" > /dev/null 2>&1 ok: [switch2] => {"ansible_facts": {"deployment_id": "1", "dhcp_servers": ["10.0.0.57", "10.0.0.59", "10.0.0.61", "10.0.0.63"], "forced_mgmt_routes": ["10.0.0.100/31", "10.250.0.8", "10.255.0.0/28"], "inventory_hostname": "switch2", "minigraph_acls": {}, "minigraph_as_xml": "minigraph/switch2.xml", "minigraph_bgp": [{"addr": "10.0.0.1", "asn": 65200, "name": "ARISTA01T2", "peer_addr": "10.0.0.0"}, {"addr": "10.0.0.11", "asn": 65200, "name": "ARISTA06T2", "peer_addr": "10.0.0.10"}, {"addr": "10.0.0.13", "asn": 65200, "name": "ARISTA07T2", "peer_addr": "10.0.0.12"}, {"addr": "10.0.0.15", "asn": 65200, "name": "ARISTA08T2", "peer_addr": "10.0.0.14"}, {"addr": "10.0.0.17", "asn": 65200, "name": "ARISTA09T2", "peer_addr": "10.0.0.16"}, {"addr": "10.0.0.19", "asn": 65200, "name": "ARISTA10T2", "peer_addr": "10.0.0.18"}, {"addr": "10.0.0.21", "asn": 65200, "name": "ARISTA11T2", "peer_addr": "10.0.0.20"}, {"addr": "10.0.0.23", "asn": 65200, "name": "ARISTA12T2", "peer_addr": "10.0.0.22"}, {"addr": "10.0.0.25", "asn": 65200, "name": "ARISTA13T2", "peer_addr": "10.0.0.24"}, {"addr": "10.0.0.27", "asn": 65200, "name": "ARISTA14T2", "peer_addr": "10.0.0.26"}, {"addr": "10.0.0.29", "asn": 65200, "name": "ARISTA15T2", "peer_addr": "10.0.0.28"}, {"addr": "10.0.0.3", "asn": 65200, "name": "ARISTA02T2", "peer_addr": "10.0.0.2"}, {"addr": "10.0.0.31", "asn": 65200, "name": "ARISTA16T2", "peer_addr": "10.0.0.30"}, {"addr": "10.0.0.33", "asn": 64001, "name": "ARISTA01T0", "peer_addr": "10.0.0.32"}, {"addr": "10.0.0.35", "asn": 64002, "name": "ARISTA02T0", "peer_addr": "10.0.0.34"}, {"addr": "10.0.0.37", "asn": 64003, "name": "ARISTA03T0", "peer_addr": "10.0.0.36"}, {"addr": "10.0.0.39", "asn": 64004, "name": "ARISTA04T0", "peer_addr": "10.0.0.38"}, {"addr": "10.0.0.41", "asn": 64005, "name": "ARISTA05T0", "peer_addr": "10.0.0.40"}, {"addr": "10.0.0.43", "asn": 64006, "name": "ARISTA06T0", "peer_addr": "10.0.0.42"}, {"addr": "10.0.0.45", "asn": 64007, "name": "ARISTA07T0", "peer_addr": "10.0.0.44"}, {"addr": "10.0.0.47", "asn": 64008, "name": "ARISTA08T0", "peer_addr": "10.0.0.46"}, {"addr": "10.0.0.49", "asn": 64009, "name": "ARISTA09T0", "peer_addr": "10.0.0.48"}, {"addr": "10.0.0.5", "asn": 65200, "name": "ARISTA03T2", "peer_addr": "10.0.0.4"}, {"addr": "10.0.0.51", "asn": 64010, "name": "ARISTA10T0", "peer_addr": "10.0.0.50"}, {"addr": "10.0.0.53", "asn": 64011, "name": "ARISTA11T0", "peer_addr": "10.0.0.52"}, {"addr": "10.0.0.55", "asn": 64012, "name": "ARISTA12T0", "peer_addr": "10.0.0.54"}, {"addr": "10.0.0.57", "asn": 64013, "name": "ARISTA13T0", "peer_addr": "10.0.0.56"}, {"addr": "10.0.0.59", "asn": 64014, "name": "ARISTA14T0", "peer_addr": "10.0.0.58"}, {"addr": "10.0.0.61", "asn": 64015, "name": "ARISTA15T0", "peer_addr": "10.0.0.60"}, {"addr": "10.0.0.63", "asn": 64016, "name": "ARISTA16T0", "peer_addr": "10.0.0.62"}, {"addr": "10.0.0.7", "asn": 65200, "name": "ARISTA04T2", "peer_addr": "10.0.0.6"}, {"addr": "10.0.0.9", "asn": 65200, "name": "ARISTA05T2", "peer_addr": "10.0.0.8"}, {"addr": "FC00::12", "asn": 65200, "name": "ARISTA05T2", "peer_addr": "FC00::11"}, {"addr": "FC00::16", "asn": 65200, "name": "ARISTA06T2", "peer_addr": "FC00::15"}, {"addr": "FC00::1A", "asn": 65200, "name": "ARISTA07T2", "peer_addr": "FC00::19"}, {"addr": "FC00::1E", "asn": 65200, "name": "ARISTA08T2", "peer_addr": "FC00::1D"}, {"addr": "FC00::2", "asn": 65200, "name": "ARISTA01T2", "peer_addr": "FC00::1"}, {"addr": "FC00::22", "asn": 65200, "name": "ARISTA09T2", "peer_addr": "FC00::21"}, {"addr": "FC00::26", "asn": 65200, "name": "ARISTA10T2", "peer_addr": "FC00::25"}, {"addr": "FC00::2A", "asn": 65200, "name": "ARISTA11T2", "peer_addr": "FC00::29"}, {"addr": "FC00::2E", "asn": 65200, "name": "ARISTA12T2", "peer_addr": "FC00::2D"}, {"addr": "FC00::32", "asn": 65200, "name": "ARISTA13T2", "peer_addr": "FC00::31"}, {"addr": "FC00::36", "asn": 65200, "name": "ARISTA14T2", "peer_addr": "FC00::35"}, {"addr": "FC00::3A", "asn": 65200, "name": "ARISTA15T2", "peer_addr": "FC00::39"}, {"addr": "FC00::3E", "asn": 65200, "name": "ARISTA16T2", "peer_addr": "FC00::3D"}, {"addr": "FC00::42", "asn": 64001, "name": "ARISTA01T0", "peer_addr": "FC00::41"}, {"addr": "FC00::46", "asn": 64002, "name": "ARISTA02T0", "peer_addr": "FC00::45"}, {"addr": "FC00::4A", "asn": 64003, "name": "ARISTA03T0", "peer_addr": "FC00::49"}, {"addr": "FC00::4E", "asn": 64004, "name": "ARISTA04T0", "peer_addr": "FC00::4D"}, {"addr": "FC00::52", "asn": 64005, "name": "ARISTA05T0", "peer_addr": "FC00::51"}, {"addr": "FC00::56", "asn": 64006, "name": "ARISTA06T0", "peer_addr": "FC00::55"}, {"addr": "FC00::5A", "asn": 64007, "name": "ARISTA07T0", "peer_addr": "FC00::59"}, {"addr": "FC00::5E", "asn": 64008, "name": "ARISTA08T0", "peer_addr": "FC00::5D"}, {"addr": "FC00::6", "asn": 65200, "name": "ARISTA02T2", "peer_addr": "FC00::5"}, {"addr": "FC00::62", "asn": 64009, "name": "ARISTA09T0", "peer_addr": "FC00::61"}, {"addr": "FC00::66", "asn": 64010, "name": "ARISTA10T0", "peer_addr": "FC00::65"}, {"addr": "FC00::6A", "asn": 64011, "name": "ARISTA11T0", "peer_addr": "FC00::69"}, {"addr": "FC00::6E", "asn": 64012, "name": "ARISTA12T0", "peer_addr": "FC00::6D"}, {"addr": "FC00::72", "asn": 64013, "name": "ARISTA13T0", "peer_addr": "FC00::71"}, {"addr": "FC00::76", "asn": 64014, "name": "ARISTA14T0", "peer_addr": "FC00::75"}, {"addr": "FC00::7A", "asn": 64015, "name": "ARISTA15T0", "peer_addr": "FC00::79"}, {"addr": "FC00::7E", "asn": 64016, "name": "ARISTA16T0", "peer_addr": "FC00::7D"}, {"addr": "FC00::A", "asn": 65200, "name": "ARISTA03T2", "peer_addr": "FC00::9"}, {"addr": "FC00::E", "asn": 65200, "name": "ARISTA04T2", "peer_addr": "FC00::D"}], "minigraph_bgp_asn": 65100, "minigraph_bgp_peers_with_range": [], "minigraph_console": {}, "minigraph_devices": {"\"ARISTA01T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.219", "type": "ToRRouter"}, "\"ARISTA01T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.203", "type": "SpineRouter"}, "\"ARISTA02T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.220", "type": "ToRRouter"}, "\"ARISTA02T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.204", "type": "SpineRouter"}, "\"ARISTA03T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.221", "type": "ToRRouter"}, "\"ARISTA03T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.205", "type": "SpineRouter"}, "\"ARISTA04T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.222", "type": "ToRRouter"}, "\"ARISTA04T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.206", "type": "SpineRouter"}, "\"ARISTA05T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.223", "type": "ToRRouter"}, "\"ARISTA05T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.207", "type": "SpineRouter"}, "\"ARISTA06T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.224", "type": "ToRRouter"}, "\"ARISTA06T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.208", "type": "SpineRouter"}, "\"ARISTA07T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.225", "type": "ToRRouter"}, "\"ARISTA07T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.209", "type": "SpineRouter"}, "\"ARISTA08T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.226", "type": "ToRRouter"}, "\"ARISTA08T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.210", "type": "SpineRouter"}, "\"ARISTA09T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.227", "type": "ToRRouter"}, "\"ARISTA09T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.211", "type": "SpineRouter"}, "\"ARISTA10T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.228", "type": "ToRRouter"}, "\"ARISTA10T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.212", "type": "SpineRouter"}, "\"ARISTA11T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.229", "type": "ToRRouter"}, "\"ARISTA11T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.213", "type": "SpineRouter"}, "\"ARISTA12T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.230", "type": "ToRRouter"}, "\"ARISTA12T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.214", "type": "SpineRouter"}, "\"ARISTA13T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.231", "type": "ToRRouter"}, "\"ARISTA13T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.215", "type": "SpineRouter"}, "\"ARISTA14T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.232", "type": "ToRRouter"}, "\"ARISTA14T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.216", "type": "SpineRouter"}, "\"ARISTA15T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.233", "type": "ToRRouter"}, "\"ARISTA15T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.217", "type": "SpineRouter"}, "\"ARISTA16T0\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.234", "type": "ToRRouter"}, "\"ARISTA16T2\"": {"hwsku": "Arista-VM", "lo_addr": null, "mgmt_addr": "172.20.200.218", "type": "SpineRouter"}, "switch2": {"hwsku": "INGRASYS-S9100-C32", "lo_addr": null, "mgmt_addr": "172.20.192.94", "type": "LeafRouter"}}, "minigraph_hostname": "switch2", "minigraph_hwsku": "INGRASYS-S9100-C32", "minigraph_interfaces": [{"addr": "10.0.0.0", "attachto": "Ethernet0", "mask": "255.255.255.254", "peer_addr": "10.0.0.1", "prefixlen": 31, "subnet": "10.0.0.0/31"}, {"addr": "fc00::1", "attachto": "Ethernet0", "mask": "126", "peer_addr": "fc00::2", "prefixlen": 126, "subnet": "fc00::/126"}, {"addr": "10.0.0.50", "attachto": "Ethernet100", "mask": "255.255.255.254", "peer_addr": "10.0.0.51", "prefixlen": 31, "subnet": "10.0.0.50/31"}, {"addr": "fc00::65", "attachto": "Ethernet100", "mask": "126", "peer_addr": "fc00::66", "prefixlen": 126, "subnet": "fc00::64/126"}, {"addr": "10.0.0.52", "attachto": "Ethernet104", "mask": "255.255.255.254", "peer_addr": "10.0.0.53", "prefixlen": 31, "subnet": "10.0.0.52/31"}, {"addr": "fc00::69", "attachto": "Ethernet104", "mask": "126", "peer_addr": "fc00::6a", "prefixlen": 126, "subnet": "fc00::68/126"}, {"addr": "10.0.0.54", "attachto": "Ethernet108", "mask": "255.255.255.254", "peer_addr": "10.0.0.55", "prefixlen": 31, "subnet": "10.0.0.54/31"}, {"addr": "fc00::6d", "attachto": "Ethernet108", "mask": "126", "peer_addr": "fc00::6e", "prefixlen": 126, "subnet": "fc00::6c/126"}, {"addr": "10.0.0.56", "attachto": "Ethernet112", "mask": "255.255.255.254", "peer_addr": "10.0.0.57", "prefixlen": 31, "subnet": "10.0.0.56/31"}, {"addr": "fc00::71", "attachto": "Ethernet112", "mask": "126", "peer_addr": "fc00::72", "prefixlen": 126, "subnet": "fc00::70/126"}, {"addr": "10.0.0.58", "attachto": "Ethernet116", "mask": "255.255.255.254", "peer_addr": "10.0.0.59", "prefixlen": 31, "subnet": "10.0.0.58/31"}, {"addr": "fc00::75", "attachto": "Ethernet116", "mask": "126", "peer_addr": "fc00::76", "prefixlen": 126, "subnet": "fc00::74/126"}, {"addr": "10.0.0.6", "attachto": "Ethernet12", "mask": "255.255.255.254", "peer_addr": "10.0.0.7", "prefixlen": 31, "subnet": "10.0.0.6/31"}, {"addr": "fc00::d", "attachto": "Ethernet12", "mask": "126", "peer_addr": "fc00::e", "prefixlen": 126, "subnet": "fc00::c/126"}, {"addr": "10.0.0.60", "attachto": "Ethernet120", "mask": "255.255.255.254", "peer_addr": "10.0.0.61", "prefixlen": 31, "subnet": "10.0.0.60/31"}, {"addr": "fc00::79", "attachto": "Ethernet120", "mask": "126", "peer_addr": "fc00::7a", "prefixlen": 126, "subnet": "fc00::78/126"}, {"addr": "10.0.0.62", "attachto": "Ethernet124", "mask": "255.255.255.254", "peer_addr": "10.0.0.63", "prefixlen": 31, "subnet": "10.0.0.62/31"}, {"addr": "fc00::7d", "attachto": "Ethernet124", "mask": "126", "peer_addr": "fc00::7e", "prefixlen": 126, "subnet": "fc00::7c/126"}, {"addr": "10.0.0.8", "attachto": "Ethernet16", "mask": "255.255.255.254", "peer_addr": "10.0.0.9", "prefixlen": 31, "subnet": "10.0.0.8/31"}, {"addr": "fc00::11", "attachto": "Ethernet16", "mask": "126", "peer_addr": "fc00::12", "prefixlen": 126, "subnet": "fc00::10/126"}, {"addr": "10.0.0.10", "attachto": "Ethernet20", "mask": "255.255.255.254", "peer_addr": "10.0.0.11", "prefixlen": 31, "subnet": "10.0.0.10/31"}, {"addr": "fc00::15", "attachto": "Ethernet20", "mask": "126", "peer_addr": "fc00::16", "prefixlen": 126, "subnet": "fc00::14/126"}, {"addr": "10.0.0.12", "attachto": "Ethernet24", "mask": "255.255.255.254", "peer_addr": "10.0.0.13", "prefixlen": 31, "subnet": "10.0.0.12/31"}, {"addr": "fc00::19", "attachto": "Ethernet24", "mask": "126", "peer_addr": "fc00::1a", "prefixlen": 126, "subnet": "fc00::18/126"}, {"addr": "10.0.0.14", "attachto": "Ethernet28", "mask": "255.255.255.254", "peer_addr": "10.0.0.15", "prefixlen": 31, "subnet": "10.0.0.14/31"}, {"addr": "fc00::1d", "attachto": "Ethernet28", "mask": "126", "peer_addr": "fc00::1e", "prefixlen": 126, "subnet": "fc00::1c/126"}, {"addr": "10.0.0.16", "attachto": "Ethernet32", "mask": "255.255.255.254", "peer_addr": "10.0.0.17", "prefixlen": 31, "subnet": "10.0.0.16/31"}, {"addr": "fc00::21", "attachto": "Ethernet32", "mask": "126", "peer_addr": "fc00::22", "prefixlen": 126, "subnet": "fc00::20/126"}, {"addr": "10.0.0.18", "attachto": "Ethernet36", "mask": "255.255.255.254", "peer_addr": "10.0.0.19", "prefixlen": 31, "subnet": "10.0.0.18/31"}, {"addr": "fc00::25", "attachto": "Ethernet36", "mask": "126", "peer_addr": "fc00::26", "prefixlen": 126, "subnet": "fc00::24/126"}, {"addr": "10.0.0.2", "attachto": "Ethernet4", "mask": "255.255.255.254", "peer_addr": "10.0.0.3", "prefixlen": 31, "subnet": "10.0.0.2/31"}, {"addr": "fc00::5", "attachto": "Ethernet4", "mask": "126", "peer_addr": "fc00::6", "prefixlen": 126, "subnet": "fc00::4/126"}, {"addr": "10.0.0.20", "attachto": "Ethernet40", "mask": "255.255.255.254", "peer_addr": "10.0.0.21", "prefixlen": 31, "subnet": "10.0.0.20/31"}, {"addr": "fc00::29", "attachto": "Ethernet40", "mask": "126", "peer_addr": "fc00::2a", "prefixlen": 126, "subnet": "fc00::28/126"}, {"addr": "10.0.0.22", "attachto": "Ethernet44", "mask": "255.255.255.254", "peer_addr": "10.0.0.23", "prefixlen": 31, "subnet": "10.0.0.22/31"}, {"addr": "fc00::2d", "attachto": "Ethernet44", "mask": "126", "peer_addr": "fc00::2e", "prefixlen": 126, "subnet": "fc00::2c/126"}, {"addr": "10.0.0.24", "attachto": "Ethernet48", "mask": "255.255.255.254", "peer_addr": "10.0.0.25", "prefixlen": 31, "subnet": "10.0.0.24/31"}, {"addr": "fc00::31", "attachto": "Ethernet48", "mask": "126", "peer_addr": "fc00::32", "prefixlen": 126, "subnet": "fc00::30/126"}, {"addr": "10.0.0.26", "attachto": "Ethernet52", "mask": "255.255.255.254", "peer_addr": "10.0.0.27", "prefixlen": 31, "subnet": "10.0.0.26/31"}, {"addr": "fc00::35", "attachto": "Ethernet52", "mask": "126", "peer_addr": "fc00::36", "prefixlen": 126, "subnet": "fc00::34/126"}, {"addr": "10.0.0.28", "attachto": "Ethernet56", "mask": "255.255.255.254", "peer_addr": "10.0.0.29", "prefixlen": 31, "subnet": "10.0.0.28/31"}, {"addr": "fc00::39", "attachto": "Ethernet56", "mask": "126", "peer_addr": "fc00::3a", "prefixlen": 126, "subnet": "fc00::38/126"}, {"addr": "10.0.0.30", "attachto": "Ethernet60", "mask": "255.255.255.254", "peer_addr": "10.0.0.31", "prefixlen": 31, "subnet": "10.0.0.30/31"}, {"addr": "fc00::3d", "attachto": "Ethernet60", "mask": "126", "peer_addr": "fc00::3e", "prefixlen": 126, "subnet": "fc00::3c/126"}, {"addr": "10.0.0.32", "attachto": "Ethernet64", "mask": "255.255.255.254", "peer_addr": "10.0.0.33", "prefixlen": 31, "subnet": "10.0.0.32/31"}, {"addr": "fc00::41", "attachto": "Ethernet64", "mask": "126", "peer_addr": "fc00::42", "prefixlen": 126, "subnet": "fc00::40/126"}, {"addr": "10.0.0.34", "attachto": "Ethernet68", "mask": "255.255.255.254", "peer_addr": "10.0.0.35", "prefixlen": 31, "subnet": "10.0.0.34/31"}, {"addr": "fc00::45", "attachto": "Ethernet68", "mask": "126", "peer_addr": "fc00::46", "prefixlen": 126, "subnet": "fc00::44/126"}, {"addr": "10.0.0.36", "attachto": "Ethernet72", "mask": "255.255.255.254", "peer_addr": "10.0.0.37", "prefixlen": 31, "subnet": "10.0.0.36/31"}, {"addr": "fc00::49", "attachto": "Ethernet72", "mask": "126", "peer_addr": "fc00::4a", "prefixlen": 126, "subnet": "fc00::48/126"}, {"addr": "10.0.0.38", "attachto": "Ethernet76", "mask": "255.255.255.254", "peer_addr": "10.0.0.39", "prefixlen": 31, "subnet": "10.0.0.38/31"}, {"addr": "fc00::4d", "attachto": "Ethernet76", "mask": "126", "peer_addr": "fc00::4e", "prefixlen": 126, "subnet": "fc00::4c/126"}, {"addr": "10.0.0.4", "attachto": "Ethernet8", "mask": "255.255.255.254", "peer_addr": "10.0.0.5", "prefixlen": 31, "subnet": "10.0.0.4/31"}, {"addr": "fc00::9", "attachto": "Ethernet8", "mask": "126", "peer_addr": "fc00::a", "prefixlen": 126, "subnet": "fc00::8/126"}, {"addr": "10.0.0.40", "attachto": "Ethernet80", "mask": "255.255.255.254", "peer_addr": "10.0.0.41", "prefixlen": 31, "subnet": "10.0.0.40/31"}, {"addr": "fc00::51", "attachto": "Ethernet80", "mask": "126", "peer_addr": "fc00::52", "prefixlen": 126, "subnet": "fc00::50/126"}, {"addr": "10.0.0.42", "attachto": "Ethernet84", "mask": "255.255.255.254", "peer_addr": "10.0.0.43", "prefixlen": 31, "subnet": "10.0.0.42/31"}, {"addr": "fc00::55", "attachto": "Ethernet84", "mask": "126", "peer_addr": "fc00::56", "prefixlen": 126, "subnet": "fc00::54/126"}, {"addr": "10.0.0.44", "attachto": "Ethernet88", "mask": "255.255.255.254", "peer_addr": "10.0.0.45", "prefixlen": 31, "subnet": "10.0.0.44/31"}, {"addr": "fc00::59", "attachto": "Ethernet88", "mask": "126", "peer_addr": "fc00::5a", "prefixlen": 126, "subnet": "fc00::58/126"}, {"addr": "10.0.0.46", "attachto": "Ethernet92", "mask": "255.255.255.254", "peer_addr": "10.0.0.47", "prefixlen": 31, "subnet": "10.0.0.46/31"}, {"addr": "fc00::5d", "attachto": "Ethernet92", "mask": "126", "peer_addr": "fc00::5e", "prefixlen": 126, "subnet": "fc00::5c/126"}, {"addr": "10.0.0.48", "attachto": "Ethernet96", "mask": "255.255.255.254", "peer_addr": "10.0.0.49", "prefixlen": 31, "subnet": "10.0.0.48/31"}, {"addr": "fc00::61", "attachto": "Ethernet96", "mask": "126", "peer_addr": "fc00::62", "prefixlen": 126, "subnet": "fc00::60/126"}], "minigraph_lo_interfaces": [{"addr": "10.1.0.32", "mask": "255.255.255.255", "name": "Loopback0", "prefixlen": 32}, {"addr": "fc00:1::32", "mask": "128", "name": "Loopback0", "prefixlen": 128}], "minigraph_map_ngs_to_sonic": {"Ethernet0": "Ethernet0", "Ethernet100": "Ethernet100", "Ethernet104": "Ethernet104", "Ethernet108": "Ethernet108", "Ethernet112": "Ethernet112", "Ethernet116": "Ethernet116", "Ethernet12": "Ethernet12", "Ethernet120": "Ethernet120", "Ethernet124": "Ethernet124", "Ethernet16": "Ethernet16", "Ethernet20": "Ethernet20", "Ethernet24": "Ethernet24", "Ethernet28": "Ethernet28", "Ethernet32": "Ethernet32", "Ethernet36": "Ethernet36", "Ethernet4": "Ethernet4", "Ethernet40": "Ethernet40", "Ethernet44": "Ethernet44", "Ethernet48": "Ethernet48", "Ethernet52": "Ethernet52", "Ethernet56": "Ethernet56", "Ethernet60": "Ethernet60", "Ethernet64": "Ethernet64", "Ethernet68": "Ethernet68", "Ethernet72": "Ethernet72", "Ethernet76": "Ethernet76", "Ethernet8": "Ethernet8", "Ethernet80": "Ethernet80", "Ethernet84": "Ethernet84", "Ethernet88": "Ethernet88", "Ethernet92": "Ethernet92", "Ethernet96": "Ethernet96"}, "minigraph_map_sonic_to_ngs": {"Ethernet0": "Ethernet0", "Ethernet100": "Ethernet100", "Ethernet104": "Ethernet104", "Ethernet108": "Ethernet108", "Ethernet112": "Ethernet112", "Ethernet116": "Ethernet116", "Ethernet12": "Ethernet12", "Ethernet120": "Ethernet120", "Ethernet124": "Ethernet124", "Ethernet16": "Ethernet16", "Ethernet20": "Ethernet20", "Ethernet24": "Ethernet24", "Ethernet28": "Ethernet28", "Ethernet32": "Ethernet32", "Ethernet36": "Ethernet36", "Ethernet4": "Ethernet4", "Ethernet40": "Ethernet40", "Ethernet44": "Ethernet44", "Ethernet48": "Ethernet48", "Ethernet52": "Ethernet52", "Ethernet56": "Ethernet56", "Ethernet60": "Ethernet60", "Ethernet64": "Ethernet64", "Ethernet68": "Ethernet68", "Ethernet72": "Ethernet72", "Ethernet76": "Ethernet76", "Ethernet8": "Ethernet8", "Ethernet80": "Ethernet80", "Ethernet84": "Ethernet84", "Ethernet88": "Ethernet88", "Ethernet92": "Ethernet92", "Ethernet96": "Ethernet96"}, "minigraph_mgmt": {}, "minigraph_mgmt_interface": {"addr": "172.20.192.94", "gwaddr": "172.20.192.1", "mask": "255.255.255.0", "prefixlen": "24"}, "minigraph_neighbors": {"Ethernet0": {"name": "ARISTA01T2", "port": "Ethernet1"}, "Ethernet100": {"name": "ARISTA10T0", "port": "Ethernet1"}, "Ethernet104": {"name": "ARISTA11T0", "port": "Ethernet1"}, "Ethernet108": {"name": "ARISTA12T0", "port": "Ethernet1"}, "Ethernet112": {"name": "ARISTA13T0", "port": "Ethernet1"}, "Ethernet116": {"name": "ARISTA14T0", "port": "Ethernet1"}, "Ethernet12": {"name": "ARISTA04T2", "port": "Ethernet1"}, "Ethernet120": {"name": "ARISTA15T0", "port": "Ethernet1"}, "Ethernet124": {"name": "ARISTA16T0", "port": "Ethernet1"}, "Ethernet16": {"name": "ARISTA05T2", "port": "Ethernet1"}, "Ethernet20": {"name": "ARISTA06T2", "port": "Ethernet1"}, "Ethernet24": {"name": "ARISTA07T2", "port": "Ethernet1"}, "Ethernet28": {"name": "ARISTA08T2", "port": "Ethernet1"}, "Ethernet32": {"name": "ARISTA09T2", "port": "Ethernet1"}, "Ethernet36": {"name": "ARISTA10T2", "port": "Ethernet1"}, "Ethernet4": {"name": "ARISTA02T2", "port": "Ethernet1"}, "Ethernet40": {"name": "ARISTA11T2", "port": "Ethernet1"}, "Ethernet44": {"name": "ARISTA12T2", "port": "Ethernet1"}, "Ethernet48": {"name": "ARISTA13T2", "port": "Ethernet1"}, "Ethernet52": {"name": "ARISTA14T2", "port": "Ethernet1"}, "Ethernet56": {"name": "ARISTA15T2", "port": "Ethernet1"}, "Ethernet60": {"name": "ARISTA16T2", "port": "Ethernet1"}, "Ethernet64": {"name": "ARISTA01T0", "port": "Ethernet1"}, "Ethernet68": {"name": "ARISTA02T0", "port": "Ethernet1"}, "Ethernet72": {"name": "ARISTA03T0", "port": "Ethernet1"}, "Ethernet76": {"name": "ARISTA04T0", "port": "Ethernet1"}, "Ethernet8": {"name": "ARISTA03T2", "port": "Ethernet1"}, "Ethernet80": {"name": "ARISTA05T0", "port": "Ethernet1"}, "Ethernet84": {"name": "ARISTA06T0", "port": "Ethernet1"}, "Ethernet88": {"name": "ARISTA07T0", "port": "Ethernet1"}, "Ethernet92": {"name": "ARISTA08T0", "port": "Ethernet1"}, "Ethernet96": {"name": "ARISTA09T0", "port": "Ethernet1"}}, "minigraph_port_indices": {"Ethernet0": 0, "Ethernet100": 25, "Ethernet104": 26, "Ethernet108": 27, "Ethernet112": 28, "Ethernet116": 29, "Ethernet12": 3, "Ethernet120": 30, "Ethernet124": 31, "Ethernet16": 4, "Ethernet20": 5, "Ethernet24": 6, "Ethernet28": 7, "Ethernet32": 8, "Ethernet36": 9, "Ethernet4": 1, "Ethernet40": 10, "Ethernet44": 11, "Ethernet48": 12, "Ethernet52": 13, "Ethernet56": 14, "Ethernet60": 15, "Ethernet64": 16, "Ethernet68": 17, "Ethernet72": 18, "Ethernet76": 19, "Ethernet8": 2, "Ethernet80": 20, "Ethernet84": 21, "Ethernet88": 22, "Ethernet92": 23, "Ethernet96": 24}, "minigraph_portchannel_interfaces": [], "minigraph_portchannels": {}, "minigraph_ports": {"Ethernet0": {"alias": "Ethernet0", "name": "Ethernet0"}, "Ethernet100": {"alias": "Ethernet100", "name": "Ethernet100"}, "Ethernet104": {"alias": "Ethernet104", "name": "Ethernet104"}, "Ethernet108": {"alias": "Ethernet108", "name": "Ethernet108"}, "Ethernet112": {"alias": "Ethernet112", "name": "Ethernet112"}, "Ethernet116": {"alias": "Ethernet116", "name": "Ethernet116"}, "Ethernet12": {"alias": "Ethernet12", "name": "Ethernet12"}, "Ethernet120": {"alias": "Ethernet120", "name": "Ethernet120"}, "Ethernet124": {"alias": "Ethernet124", "name": "Ethernet124"}, "Ethernet16": {"alias": "Ethernet16", "name": "Ethernet16"}, "Ethernet20": {"alias": "Ethernet20", "name": "Ethernet20"}, "Ethernet24": {"alias": "Ethernet24", "name": "Ethernet24"}, "Ethernet28": {"alias": "Ethernet28", "name": "Ethernet28"}, "Ethernet32": {"alias": "Ethernet32", "name": "Ethernet32"}, "Ethernet36": {"alias": "Ethernet36", "name": "Ethernet36"}, "Ethernet4": {"alias": "Ethernet4", "name": "Ethernet4"}, "Ethernet40": {"alias": "Ethernet40", "name": "Ethernet40"}, "Ethernet44": {"alias": "Ethernet44", "name": "Ethernet44"}, "Ethernet48": {"alias": "Ethernet48", "name": "Ethernet48"}, "Ethernet52": {"alias": "Ethernet52", "name": "Ethernet52"}, "Ethernet56": {"alias": "Ethernet56", "name": "Ethernet56"}, "Ethernet60": {"alias": "Ethernet60", "name": "Ethernet60"}, "Ethernet64": {"alias": "Ethernet64", "name": "Ethernet64"}, "Ethernet68": {"alias": "Ethernet68", "name": "Ethernet68"}, "Ethernet72": {"alias": "Ethernet72", "name": "Ethernet72"}, "Ethernet76": {"alias": "Ethernet76", "name": "Ethernet76"}, "Ethernet8": {"alias": "Ethernet8", "name": "Ethernet8"}, "Ethernet80": {"alias": "Ethernet80", "name": "Ethernet80"}, "Ethernet84": {"alias": "Ethernet84", "name": "Ethernet84"}, "Ethernet88": {"alias": "Ethernet88", "name": "Ethernet88"}, "Ethernet92": {"alias": "Ethernet92", "name": "Ethernet92"}, "Ethernet96": {"alias": "Ethernet96", "name": "Ethernet96"}}, "minigraph_underlay_devices": null, "minigraph_underlay_neighbors": null, "minigraph_vlan_interfaces": [], "minigraph_vlans": {}, "ntp_servers": ["watch.stdtime.gov.tw"], "syslog_servers": ["172.20.192.121"]}, "changed": false, "invocation": {"module_args": {"filename": null, "host": "switch2"}, "module_name": "minigraph_facts"}} TASK [test : Set sonic_hwsku fact] ********************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/sonic.yml:15 Tuesday 26 September 2017 14:30:28 +0800 (0:00:00.308) 0:00:02.083 ***** ok: [switch2] => {"ansible_facts": {"sonic_hwsku": "INGRASYS-S9100-C32"}, "changed": false, "invocation": {"module_args": {"sonic_hwsku": "INGRASYS-S9100-C32"}, "module_name": "set_fact"}} TASK [test : Set sonic_asic_type fact] ***************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/sonic.yml:20 Tuesday 26 September 2017 14:30:28 +0800 (0:00:00.069) 0:00:02.152 ***** ok: [switch2] => {"ansible_facts": {"sonic_asic_type": "broadcom"}, "changed": false, "invocation": {"module_args": {"sonic_asic_type": "broadcom"}, "module_name": "set_fact"}} TASK [test : Set sonic_asic_type fact] ***************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/sonic.yml:26 Tuesday 26 September 2017 14:30:28 +0800 (0:00:00.062) 0:00:02.215 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : Verify interfaces are up] ***************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/sonic.yml:32 Tuesday 26 September 2017 14:30:28 +0800 (0:00:00.050) 0:00:02.266 ***** included: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/interface.yml for switch2 TASK [test : Get interface facts] ********************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/interface.yml:1 Tuesday 26 September 2017 14:30:28 +0800 (0:00:00.160) 0:00:02.426 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=avxytasitqcoddvwhlcxqqkewwgmzvoa] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-avxytasitqcoddvwhlcxqqkewwgmzvoa; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' ok: [switch2] => {"ansible_facts": {"ansible_interface_facts": {"Ethernet0": {"active": true, "device": "Ethernet0", "ipv4": {"address": "10.0.0.0", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.0"}, "ipv6": [{"address": "fc00::1", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet100": {"active": true, "device": "Ethernet100", "ipv4": {"address": "10.0.0.50", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.50"}, "ipv6": [{"address": "fc00::65", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet104": {"active": true, "device": "Ethernet104", "ipv4": {"address": "10.0.0.52", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.52"}, "ipv6": [{"address": "fc00::69", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet108": {"active": true, "device": "Ethernet108", "ipv4": {"address": "10.0.0.54", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.54"}, "ipv6": [{"address": "fc00::6d", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet112": {"active": true, "device": "Ethernet112", "ipv4": {"address": "10.0.0.56", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.56"}, "ipv6": [{"address": "fc00::71", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet116": {"active": true, "device": "Ethernet116", "ipv4": {"address": "10.0.0.58", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.58"}, "ipv6": [{"address": "fc00::75", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet12": {"active": true, "device": "Ethernet12", "ipv4": {"address": "10.0.0.6", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.6"}, "ipv6": [{"address": "fc00::d", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet120": {"active": true, "device": "Ethernet120", "ipv4": {"address": "10.0.0.60", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.60"}, "ipv6": [{"address": "fc00::79", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet124": {"active": true, "device": "Ethernet124", "ipv4": {"address": "10.0.0.62", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.62"}, "ipv6": [{"address": "fc00::7d", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet16": {"active": true, "device": "Ethernet16", "ipv4": {"address": "10.0.0.8", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.8"}, "ipv6": [{"address": "fc00::11", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet20": {"active": true, "device": "Ethernet20", "ipv4": {"address": "10.0.0.10", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.10"}, "ipv6": [{"address": "fc00::15", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet24": {"active": true, "device": "Ethernet24", "ipv4": {"address": "10.0.0.12", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.12"}, "ipv6": [{"address": "fc00::19", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet28": {"active": true, "device": "Ethernet28", "ipv4": {"address": "10.0.0.14", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.14"}, "ipv6": [{"address": "fc00::1d", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet32": {"active": true, "device": "Ethernet32", "ipv4": {"address": "10.0.0.16", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.16"}, "ipv6": [{"address": "fc00::21", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet36": {"active": true, "device": "Ethernet36", "ipv4": {"address": "10.0.0.18", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.18"}, "ipv6": [{"address": "fc00::25", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet4": {"active": true, "device": "Ethernet4", "ipv4": {"address": "10.0.0.2", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.2"}, "ipv6": [{"address": "fc00::5", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet40": {"active": true, "device": "Ethernet40", "ipv4": {"address": "10.0.0.20", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.20"}, "ipv6": [{"address": "fc00::29", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet44": {"active": true, "device": "Ethernet44", "ipv4": {"address": "10.0.0.22", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.22"}, "ipv6": [{"address": "fc00::2d", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet48": {"active": true, "device": "Ethernet48", "ipv4": {"address": "10.0.0.24", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.24"}, "ipv6": [{"address": "fc00::31", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet52": {"active": true, "device": "Ethernet52", "ipv4": {"address": "10.0.0.26", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.26"}, "ipv6": [{"address": "fc00::35", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet56": {"active": true, "device": "Ethernet56", "ipv4": {"address": "10.0.0.28", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.28"}, "ipv6": [{"address": "fc00::39", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet60": {"active": true, "device": "Ethernet60", "ipv4": {"address": "10.0.0.30", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.30"}, "ipv6": [{"address": "fc00::3d", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet64": {"active": true, "device": "Ethernet64", "ipv4": {"address": "10.0.0.32", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.32"}, "ipv6": [{"address": "fc00::41", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet68": {"active": true, "device": "Ethernet68", "ipv4": {"address": "10.0.0.34", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.34"}, "ipv6": [{"address": "fc00::45", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet72": {"active": true, "device": "Ethernet72", "ipv4": {"address": "10.0.0.36", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.36"}, "ipv6": [{"address": "fc00::49", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet76": {"active": true, "device": "Ethernet76", "ipv4": {"address": "10.0.0.38", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.38"}, "ipv6": [{"address": "fc00::4d", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet8": {"active": true, "device": "Ethernet8", "ipv4": {"address": "10.0.0.4", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.4"}, "ipv6": [{"address": "fc00::9", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet80": {"active": true, "device": "Ethernet80", "ipv4": {"address": "10.0.0.40", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.40"}, "ipv6": [{"address": "fc00::51", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet84": {"active": true, "device": "Ethernet84", "ipv4": {"address": "10.0.0.42", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.42"}, "ipv6": [{"address": "fc00::55", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet88": {"active": true, "device": "Ethernet88", "ipv4": {"address": "10.0.0.44", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.44"}, "ipv6": [{"address": "fc00::59", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet92": {"active": true, "device": "Ethernet92", "ipv4": {"address": "10.0.0.46", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.46"}, "ipv6": [{"address": "fc00::5d", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "Ethernet96": {"active": true, "device": "Ethernet96", "ipv4": {"address": "10.0.0.48", "broadcast": "255.255.255.255", "netmask": "255.255.255.254", "network": "10.0.0.48"}, "ipv6": [{"address": "fc00::61", "prefix": "126", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "mtu": 9216, "promisc": false, "type": "ether"}, "bcm0": {"active": false, "device": "bcm0", "macaddress": "02:10:18:0f:05:fc", "mtu": 1500, "promisc": false, "type": "ether"}, "docker0": {"active": false, "device": "docker0", "id": "8000.024260e5f2b4", "interfaces": [], "ipv4": {"address": "240.127.1.1", "broadcast": "global", "netmask": "255.255.255.0", "network": "240.127.1.0"}, "link": false, "macaddress": "02:42:60:e5:f2:b4", "mtu": 1500, "promisc": false, "stp": false, "type": "bridge"}, "eth0": {"active": true, "device": "eth0", "ipv4": {"address": "172.20.192.94", "broadcast": "172.20.192.255", "netmask": "255.255.255.0", "network": "172.20.192.0"}, "ipv6": [{"address": "2001:b021:15:2900:205:64ff:fe2f:ebc", "prefix": "64", "scope": "global"}, {"address": "fe80::205:64ff:fe2f:ebc", "prefix": "64", "scope": "link"}], "link": true, "macaddress": "00:05:64:2f:0e:bc", "module": "igb", "mtu": 1500, "pciid": "0000:00:14.0", "promisc": false, "type": "ether"}, "lo": {"active": true, "device": "lo", "ipv4": {"address": "127.0.0.1", "broadcast": "host", "netmask": "255.0.0.0", "network": "127.0.0.0"}, "ipv6": [{"address": "fc00:1::32", "prefix": "128", "scope": "global"}, {"address": "::1", "prefix": "128", "scope": "host"}], "link": true, "mtu": 65536, "promisc": false, "type": "loopback"}}, "ansible_interface_ips": {"all_ipv4_addresses": ["10.0.0.62", "10.0.0.60", "10.0.0.56", "10.0.0.58", "10.0.0.54", "10.0.0.50", "10.0.0.52", "172.20.192.94", "10.1.0.32", "10.0.0.2", "10.0.0.4", "10.0.0.0", "240.127.1.1", "10.0.0.46", "10.0.0.42", "10.0.0.44", "10.0.0.40", "10.0.0.38", "10.0.0.48", "10.0.0.24", "10.0.0.22", "10.0.0.20", "10.0.0.16", "10.0.0.18", "10.0.0.36", "10.0.0.32", "10.0.0.34", "10.0.0.30", "10.0.0.26", "10.0.0.28", "10.0.0.14", "10.0.0.10", "10.0.0.12", "10.0.0.8", "10.0.0.6"], "all_ipv6_addresses": ["fc00::7d", "fe80::205:64ff:fe2f:ebc", "fc00::79", "fe80::205:64ff:fe2f:ebc", "fc00::71", "fe80::205:64ff:fe2f:ebc", "fc00::75", "fe80::205:64ff:fe2f:ebc", "fc00::6d", "fe80::205:64ff:fe2f:ebc", "fc00::65", "fe80::205:64ff:fe2f:ebc", "fc00::69", "fe80::205:64ff:fe2f:ebc", "2001:b021:15:2900:205:64ff:fe2f:ebc", "fe80::205:64ff:fe2f:ebc", "fc00:1::32", "fc00::5", "fe80::205:64ff:fe2f:ebc", "fc00::9", "fe80::205:64ff:fe2f:ebc", "fc00::1", "fe80::205:64ff:fe2f:ebc", "fc00::5d", "fe80::205:64ff:fe2f:ebc", "fc00::55", "fe80::205:64ff:fe2f:ebc", "fc00::59", "fe80::205:64ff:fe2f:ebc", "fc00::51", "fe80::205:64ff:fe2f:ebc", "fc00::4d", "fe80::205:64ff:fe2f:ebc", "fc00::61", "fe80::205:64ff:fe2f:ebc", "fc00::31", "fe80::205:64ff:fe2f:ebc", "fc00::2d", "fe80::205:64ff:fe2f:ebc", "fc00::29", "fe80::205:64ff:fe2f:ebc", "fc00::21", "fe80::205:64ff:fe2f:ebc", "fc00::25", "fe80::205:64ff:fe2f:ebc", "fc00::49", "fe80::205:64ff:fe2f:ebc", "fc00::41", "fe80::205:64ff:fe2f:ebc", "fc00::45", "fe80::205:64ff:fe2f:ebc", "fc00::3d", "fe80::205:64ff:fe2f:ebc", "fc00::35", "fe80::205:64ff:fe2f:ebc", "fc00::39", "fe80::205:64ff:fe2f:ebc", "fc00::1d", "fe80::205:64ff:fe2f:ebc", "fc00::15", "fe80::205:64ff:fe2f:ebc", "fc00::19", "fe80::205:64ff:fe2f:ebc", "fc00::11", "fe80::205:64ff:fe2f:ebc", "fc00::d", "fe80::205:64ff:fe2f:ebc"]}}, "changed": false, "invocation": {"module_args": {"ip_path": "/sbin/ip"}, "module_name": "interface_facts"}} TASK [test : Verify interfaces are up correctly] ******************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/interface.yml:4 Tuesday 26 September 2017 14:30:29 +0800 (0:00:01.277) 0:00:03.704 ***** ok: [switch2] => (item=Ethernet8) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet8", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet44) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet44", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet0) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet0", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet4) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet4", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet108) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet108", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet88) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet88", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet116) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet116", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet100) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet100", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet80) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet80", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet112) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet112", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet104) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet104", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet84) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet84", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet96) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet96", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet124) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet124", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet92) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet92", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet120) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet120", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet52) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet52", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet48) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet48", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet56) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet56", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet40) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet40", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet76) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet76", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet28) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet28", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet68) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet68", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet72) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet72", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet60) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet60", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet64) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet64", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet32) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet32", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet20) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet20", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet16) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet16", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet36) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet36", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet24) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet24", "msg": "all assertions passed"} ok: [switch2] => (item=Ethernet12) => {"changed": false, "invocation": {"module_args": {"that": "'True' == 'True'"}, "module_name": "assert"}, "item": "Ethernet12", "msg": "all assertions passed"} TASK [test : Verify port channel interfaces are up correctly] ****************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/interface.yml:8 Tuesday 26 September 2017 14:30:34 +0800 (0:00:04.653) 0:00:08.357 ***** TASK [test : Verify VLAN interfaces are up correctly] ************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/interface.yml:12 Tuesday 26 September 2017 14:30:34 +0800 (0:00:00.061) 0:00:08.418 ***** TASK [test : Test Everflow] **************************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/sonic.yml:36 Tuesday 26 September 2017 14:30:34 +0800 (0:00:00.059) 0:00:08.478 ***** included: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow.yml for switch2 TASK [test : set_fact] ********************************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow.yml:5 Tuesday 26 September 2017 14:30:34 +0800 (0:00:00.197) 0:00:08.676 ***** ok: [switch2] => {"ansible_facts": {"docker_testdir": "/tmp", "out_dir": "/home/admin/everflow_tests/results", "run_dir": "/home/admin/everflow_tests", "tests_location": "roles/test/tasks/everflow"}, "changed": false, "invocation": {"module_args": {"docker_testdir": "/tmp", "out_dir": "/home/admin/everflow_tests/results", "run_dir": "/home/admin/everflow_tests", "tests_location": "roles/test/tasks/everflow"}, "module_name": "set_fact"}} TASK [test : Create run_dir] *************************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow.yml:11 Tuesday 26 September 2017 14:30:34 +0800 (0:00:00.085) 0:00:08.762 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=kscagnzxydmbzbnoleqfzytytjocgkqn] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-kscagnzxydmbzbnoleqfzytytjocgkqn; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' ok: [switch2] => {"changed": false, "gid": 0, "group": "root", "invocation": {"module_args": {"backup": null, "content": null, "delimiter": null, "diff_peek": null, "directory_mode": null, "follow": false, "force": false, "group": null, "mode": null, "original_basename": null, "owner": null, "path": "/home/admin/everflow_tests", "recurse": false, "regexp": null, "remote_src": null, "selevel": null, "serole": null, "setype": null, "seuser": null, "src": null, "state": "directory", "validate": null}, "module_name": "file"}, "mode": "0755", "owner": "root", "path": "/home/admin/everflow_tests", "size": 4096, "state": "directory", "uid": 0} TASK [test : Test Everflow session activation/deactivation logic.] ************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow.yml:22 Tuesday 26 September 2017 14:30:35 +0800 (0:00:00.249) 0:00:09.011 ***** included: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml for switch2 TASK [test : Define variables for test] **************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:1 Tuesday 26 September 2017 14:30:35 +0800 (0:00:00.374) 0:00:09.385 ***** ok: [switch2] => {"ansible_facts": {"config_files": ["config_valid.json"], "session_ip_prefix_1": "2.2.2.0/24", "session_ip_prefix_2": "2.2.2.0/30", "session_name": "session1", "sw_if": "Ethernet8", "testname": "logic_test"}, "changed": false, "invocation": {"module_args": {"config_files": ["config_valid.json"], "session_ip_prefix_1": "2.2.2.0/24", "session_ip_prefix_2": "2.2.2.0/30", "session_name": "session1", "sw_if": "Ethernet8", "testname": "logic_test"}, "module_name": "set_fact"}} TASK [test : fail] ************************************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:11 Tuesday 26 September 2017 14:30:35 +0800 (0:00:00.116) 0:00:09.501 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : Initialize config test.] ****************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:14 Tuesday 26 September 2017 14:30:35 +0800 (0:00:00.084) 0:00:09.586 ***** included: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/init_config_test.yml for switch2 TASK [test : Create run_dir] *************************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/init_config_test.yml:1 Tuesday 26 September 2017 14:30:35 +0800 (0:00:00.212) 0:00:09.798 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=iiagblekitwjzqlvyeffoeoyveoncgxt] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-iiagblekitwjzqlvyeffoeoyveoncgxt; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' ok: [switch2] => {"changed": false, "gid": 0, "group": "root", "invocation": {"module_args": {"backup": null, "content": null, "delimiter": null, "diff_peek": null, "directory_mode": null, "follow": false, "force": false, "group": null, "mode": null, "original_basename": null, "owner": null, "path": "/home/admin/everflow_tests", "recurse": false, "regexp": null, "remote_src": null, "selevel": null, "serole": null, "setype": null, "seuser": null, "src": null, "state": "directory", "validate": null}, "module_name": "file"}, "mode": "0755", "owner": "root", "path": "/home/admin/everflow_tests", "size": 4096, "state": "directory", "uid": 0} TASK [test : Copy JSON configs onto switch.] *********************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/init_config_test.yml:4 Tuesday 26 September 2017 14:30:36 +0800 (0:00:00.353) 0:00:10.152 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r -tt 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=ltilnrzrhdfezarezxmgppnredyampsk] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-ltilnrzrhdfezarezxmgppnredyampsk; rc=flag; [ -r /home/admin/everflow_tests/config_valid.json ] || rc=2; [ -f /home/admin/everflow_tests/config_valid.json ] || rc=1; [ -d /home/admin/everflow_tests/config_valid.json ] && rc=3; python -V 2>/dev/null || rc=4; [ x"$rc" != "xflag" ] && echo "${rc} "/home/admin/everflow_tests/config_valid.json && exit 0; (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import hashlib; BLOCKSIZE = 65536; hasher = hashlib.sha1(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/config_valid.json'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import sha; BLOCKSIZE = 65536; hasher = sha.sha(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/config_valid.json'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (echo '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'0 '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/config_valid.json)'"'"'"'"'"'"'"'"''"'"'' <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r -tt 172.20.192.94 '( umask 22 && mkdir -p "$( echo $HOME/.ansible/tmp/ansible-tmp-1506407436.55-61199976230943 )" && echo "$( echo $HOME/.ansible/tmp/ansible-tmp-1506407436.55-61199976230943 )" )' <172.20.192.94> PUT /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/config_valid.json TO /home/admin/.ansible/tmp/ansible-tmp-1506407436.55-61199976230943/source <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set sftp_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 sftp -b - -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r '[172.20.192.94]' <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=uukteddultikyariovykowirwwcvommu] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-uukteddultikyariovykowirwwcvommu; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => (item=config_valid.json) => {"changed": true, "checksum": "5232fda2e6cbbb8e526a24ff308037f7fab25513", "dest": "/home/admin/everflow_tests/config_valid.json", "gid": 0, "group": "root", "invocation": {"module_args": {"backup": false, "content": null, "delimiter": null, "dest": "/home/admin/everflow_tests/config_valid.json", "directory_mode": null, "follow": false, "force": true, "group": null, "mode": null, "original_basename": "config_valid.json", "owner": null, "regexp": null, "remote_src": null, "selevel": null, "serole": null, "setype": null, "seuser": null, "src": "/home/admin/.ansible/tmp/ansible-tmp-1506407436.55-61199976230943/source", "validate": null}}, "item": "config_valid.json", "md5sum": "fe8cf9ae3109e517b819b6d09cba507a", "mode": "0644", "owner": "root", "size": 265, "src": "/home/admin/.ansible/tmp/ansible-tmp-1506407436.55-61199976230943/source", "state": "file", "uid": 0} TASK [test : Copy JSON configs from switch into docker filesystem.] ************ task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/init_config_test.yml:9 Tuesday 26 September 2017 14:30:36 +0800 (0:00:00.744) 0:00:10.896 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=mxwsdgyttbkjpycrqgbjwpymcwkfsagk] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-mxwsdgyttbkjpycrqgbjwpymcwkfsagk; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => (item=config_valid.json) => {"changed": true, "cmd": ["docker", "cp", "/home/admin/everflow_tests/config_valid.json", "swss:/tmp/config_valid.json"], "delta": "0:00:00.477508", "end": "2017-09-26 06:30:37.882037", "invocation": {"module_args": {"_raw_params": "docker cp /home/admin/everflow_tests/config_valid.json swss:/tmp/config_valid.json", "_uses_shell": false, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "item": "config_valid.json", "rc": 0, "start": "2017-09-26 06:30:37.404529", "stderr": "", "stdout": "", "stdout_lines": [], "warnings": []} TASK [test : Get IP address of one switch interface] *************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:17 Tuesday 26 September 2017 14:30:38 +0800 (0:00:01.143) 0:00:12.039 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=mlsmxsmkpxjgumjchobvjbkcqjpwbxws] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-mlsmxsmkpxjgumjchobvjbkcqjpwbxws; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": "ifconfig Ethernet8 | grep \"inet addr:\" | awk '{print $2}' | sed \"s/addr://\"", "delta": "0:00:00.013004", "end": "2017-09-26 06:30:38.269389", "invocation": {"module_args": {"_raw_params": "ifconfig Ethernet8 | grep \"inet addr:\" | awk '{print $2}' | sed \"s/addr://\"", "_uses_shell": true, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:30:38.256385", "stderr": "", "stdout": "10.0.0.4", "stdout_lines": ["10.0.0.4"], "warnings": []} TASK [test : Print Ethernet8 IP address] *************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:21 Tuesday 26 September 2017 14:30:38 +0800 (0:00:00.288) 0:00:12.328 ***** ok: [switch2] => { "msg": { "changed": true, "cmd": "ifconfig Ethernet8 | grep \"inet addr:\" | awk '{print $2}' | sed \"s/addr://\"", "delta": "0:00:00.013004", "end": "2017-09-26 06:30:38.269389", "rc": 0, "start": "2017-09-26 06:30:38.256385", "stderr": "", "stdout": "10.0.0.4", "stdout_lines": [ "10.0.0.4" ], "warnings": [] } } TASK [test : Generate neighbor IP and MAC addresses.] ************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:24 Tuesday 26 September 2017 14:30:38 +0800 (0:00:00.048) 0:00:12.376 ***** ok: [switch2] => {"ansible_facts": {"neigh_ip": "10.0.0.5", "neigh_mac_1": "00:11:22:33:44:55", "neigh_mac_2": "00:11:22:33:44:66"}, "changed": false, "invocation": {"module_args": {"neigh_ip": "10.0.0.5", "neigh_mac_1": "00:11:22:33:44:55", "neigh_mac_2": "00:11:22:33:44:66"}, "module_name": "set_fact"}} TASK [test : Print neighbor IP address] *************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:30 Tuesday 26 September 2017 14:30:38 +0800 (0:00:00.048) 0:00:12.425 ***** ok: [switch2] => { "msg": "10.0.0.5" } TASK [test : Create session entry.] ******************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:34 Tuesday 26 September 2017 14:30:38 +0800 (0:00:00.054) 0:00:12.480 ***** included: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_test.yml for switch2 TASK [test : include_vars] ***************************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_test.yml:8 Tuesday 26 September 2017 14:30:39 +0800 (0:00:00.536) 0:00:13.016 ***** ok: [switch2] => {"ansible_facts": {"ignore_file": "loganalyzer_common_ignore.txt", "loganalyzer_analyze": "roles/test/files/tools/loganalyzer/loganalyzer_analyze.yml", "loganalyzer_init": "roles/test/files/tools/loganalyzer/loganalyzer_init.yml", "match_file": "loganalyzer_common_match.txt", "result_file": "result.loganalysis.{{ testname_unique }}.log", "summary_file": "summary.loganalysis.{{ testname_unique }}.log", "test_out_dir": "{{ out_dir }}/{{ testname_unique }}", "testname_unique": "{{ testname }}.{{ ansible_date_time.date }}.{{ ansible_date_time.time }}"}, "changed": false, "invocation": {"module_args": {"_raw_params": "vars/run_config_test_vars.yml"}, "module_name": "include_vars"}} TASK [test : Initialize loganalizer. Put start marker to log file.] ************ task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_test.yml:11 Tuesday 26 September 2017 14:30:39 +0800 (0:00:00.325) 0:00:13.341 ***** included: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml for switch2 TASK [test : set_fact] ********************************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:5 Tuesday 26 September 2017 14:30:39 +0800 (0:00:00.613) 0:00:13.955 ***** ok: [switch2] => {"ansible_facts": {"loganalyzer_location": "roles/test/files/tools/loganalyzer"}, "changed": false, "invocation": {"module_args": {"loganalyzer_location": "roles/test/files/tools/loganalyzer"}, "module_name": "set_fact"}} TASK [test : set_fact] ********************************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:8 Tuesday 26 September 2017 14:30:40 +0800 (0:00:00.267) 0:00:14.222 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : set_fact] ********************************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:12 Tuesday 26 September 2017 14:30:40 +0800 (0:00:00.180) 0:00:14.402 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : set_fact] ********************************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:16 Tuesday 26 September 2017 14:30:40 +0800 (0:00:00.184) 0:00:14.587 ***** ok: [switch2] => {"ansible_facts": {"expect_file": "loganalyzer_common_expect.txt"}, "changed": false, "invocation": {"module_args": {"expect_file": "loganalyzer_common_expect.txt"}, "module_name": "set_fact"}} TASK [test : set_fact] ********************************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:20 Tuesday 26 September 2017 14:30:40 +0800 (0:00:00.229) 0:00:14.816 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : set_fact] ********************************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:24 Tuesday 26 September 2017 14:30:41 +0800 (0:00:00.188) 0:00:15.005 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : Copy loganalyzer common match and ignore files to switch] ********* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:28 Tuesday 26 September 2017 14:30:41 +0800 (0:00:00.200) 0:00:15.206 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r -tt 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=bcziqlzlmmluabuqkevujjxhaqxaykki] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-bcziqlzlmmluabuqkevujjxhaqxaykki; rc=flag; [ -r /home/admin/everflow_tests/loganalyzer_common_match.txt ] || rc=2; [ -f /home/admin/everflow_tests/loganalyzer_common_match.txt ] || rc=1; [ -d /home/admin/everflow_tests/loganalyzer_common_match.txt ] && rc=3; python -V 2>/dev/null || rc=4; [ x"$rc" != "xflag" ] && echo "${rc} "/home/admin/everflow_tests/loganalyzer_common_match.txt && exit 0; (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import hashlib; BLOCKSIZE = 65536; hasher = hashlib.sha1(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/loganalyzer_common_match.txt'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import sha; BLOCKSIZE = 65536; hasher = sha.sha(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/loganalyzer_common_match.txt'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (echo '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'0 '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/loganalyzer_common_match.txt)'"'"'"'"'"'"'"'"''"'"'' <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=yoldrfaibtffiznukxiyeoluowhcvpug] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-yoldrfaibtffiznukxiyeoluowhcvpug; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r -tt 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=ajsxmzwfxfmfwdyslfwftzibqaqcegco] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-ajsxmzwfxfmfwdyslfwftzibqaqcegco; rc=flag; [ -r /home/admin/everflow_tests/loganalyzer_common_ignore.txt ] || rc=2; [ -f /home/admin/everflow_tests/loganalyzer_common_ignore.txt ] || rc=1; [ -d /home/admin/everflow_tests/loganalyzer_common_ignore.txt ] && rc=3; python -V 2>/dev/null || rc=4; [ x"$rc" != "xflag" ] && echo "${rc} "/home/admin/everflow_tests/loganalyzer_common_ignore.txt && exit 0; (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import hashlib; BLOCKSIZE = 65536; hasher = hashlib.sha1(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/loganalyzer_common_ignore.txt'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import sha; BLOCKSIZE = 65536; hasher = sha.sha(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/loganalyzer_common_ignore.txt'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (echo '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'0 '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/loganalyzer_common_ignore.txt)'"'"'"'"'"'"'"'"''"'"'' <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=mcosfarfvgkxulovbctqeudoamztykil] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-mcosfarfvgkxulovbctqeudoamztykil; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r -tt 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=iuedfzhcmshxbosgbqbovbvkzgvhujom] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-iuedfzhcmshxbosgbqbovbvkzgvhujom; rc=flag; [ -r /home/admin/everflow_tests/loganalyzer_common_expect.txt ] || rc=2; [ -f /home/admin/everflow_tests/loganalyzer_common_expect.txt ] || rc=1; [ -d /home/admin/everflow_tests/loganalyzer_common_expect.txt ] && rc=3; python -V 2>/dev/null || rc=4; [ x"$rc" != "xflag" ] && echo "${rc} "/home/admin/everflow_tests/loganalyzer_common_expect.txt && exit 0; (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import hashlib; BLOCKSIZE = 65536; hasher = hashlib.sha1(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/loganalyzer_common_expect.txt'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import sha; BLOCKSIZE = 65536; hasher = sha.sha(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/loganalyzer_common_expect.txt'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (echo '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'0 '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/loganalyzer_common_expect.txt)'"'"'"'"'"'"'"'"''"'"'' <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=yrnjivbcodyfnjnmjrdkmstobvzdotew] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-yrnjivbcodyfnjnmjrdkmstobvzdotew; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' ok: [switch2] => (item=loganalyzer_common_match.txt) => {"changed": false, "checksum": "35c4fd3c4c2af0dac359a66c6199509448473e0b", "dest": "/home/admin/everflow_tests/loganalyzer_common_match.txt", "gid": 0, "group": "root", "invocation": {"module_args": {"backup": null, "content": null, "delimiter": null, "dest": "/home/admin/everflow_tests/loganalyzer_common_match.txt", "diff_peek": null, "directory_mode": null, "follow": false, "force": false, "group": null, "mode": null, "original_basename": "loganalyzer_common_match.txt", "owner": null, "path": "/home/admin/everflow_tests/loganalyzer_common_match.txt", "recurse": false, "regexp": null, "remote_src": null, "selevel": null, "serole": null, "setype": null, "seuser": null, "src": "loganalyzer_common_match.txt", "state": null, "validate": null}}, "item": "loganalyzer_common_match.txt", "mode": "0644", "owner": "root", "path": "/home/admin/everflow_tests/loganalyzer_common_match.txt", "size": 257, "state": "file", "uid": 0} ok: [switch2] => (item=loganalyzer_common_ignore.txt) => {"changed": false, "checksum": "da39a3ee5e6b4b0d3255bfef95601890afd80709", "dest": "/home/admin/everflow_tests/loganalyzer_common_ignore.txt", "gid": 0, "group": "root", "invocation": {"module_args": {"backup": null, "content": null, "delimiter": null, "dest": "/home/admin/everflow_tests/loganalyzer_common_ignore.txt", "diff_peek": null, "directory_mode": null, "follow": false, "force": false, "group": null, "mode": null, "original_basename": "loganalyzer_common_ignore.txt", "owner": null, "path": "/home/admin/everflow_tests/loganalyzer_common_ignore.txt", "recurse": false, "regexp": null, "remote_src": null, "selevel": null, "serole": null, "setype": null, "seuser": null, "src": "loganalyzer_common_ignore.txt", "state": null, "validate": null}}, "item": "loganalyzer_common_ignore.txt", "mode": "0644", "owner": "root", "path": "/home/admin/everflow_tests/loganalyzer_common_ignore.txt", "size": 0, "state": "file", "uid": 0} ok: [switch2] => (item=loganalyzer_common_expect.txt) => {"changed": false, "checksum": "da39a3ee5e6b4b0d3255bfef95601890afd80709", "dest": "/home/admin/everflow_tests/loganalyzer_common_expect.txt", "gid": 0, "group": "root", "invocation": {"module_args": {"backup": null, "content": null, "delimiter": null, "dest": "/home/admin/everflow_tests/loganalyzer_common_expect.txt", "diff_peek": null, "directory_mode": null, "follow": false, "force": false, "group": null, "mode": null, "original_basename": "loganalyzer_common_expect.txt", "owner": null, "path": "/home/admin/everflow_tests/loganalyzer_common_expect.txt", "recurse": false, "regexp": null, "remote_src": null, "selevel": null, "serole": null, "setype": null, "seuser": null, "src": "loganalyzer_common_expect.txt", "state": null, "validate": null}}, "item": "loganalyzer_common_expect.txt", "mode": "0644", "owner": "root", "path": "/home/admin/everflow_tests/loganalyzer_common_expect.txt", "size": 0, "state": "file", "uid": 0} TASK [test : Copy test specific file match-files to switch] ******************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:35 Tuesday 26 September 2017 14:30:44 +0800 (0:00:03.298) 0:00:18.504 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : Copy test specific ignore-files to switch] ************************ task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:39 Tuesday 26 September 2017 14:30:44 +0800 (0:00:00.191) 0:00:18.695 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : Copy test specific expect-files to switch] ************************ task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:43 Tuesday 26 September 2017 14:30:44 +0800 (0:00:00.194) 0:00:18.889 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r -tt 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=mmuqfumzqxpzgrrneowlotxdiyaethtt] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-mmuqfumzqxpzgrrneowlotxdiyaethtt; rc=flag; [ -r /home/admin/everflow_tests/create_session_expect_file ] || rc=2; [ -f /home/admin/everflow_tests/create_session_expect_file ] || rc=1; [ -d /home/admin/everflow_tests/create_session_expect_file ] && rc=3; python -V 2>/dev/null || rc=4; [ x"$rc" != "xflag" ] && echo "${rc} "/home/admin/everflow_tests/create_session_expect_file && exit 0; (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import hashlib; BLOCKSIZE = 65536; hasher = hashlib.sha1(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/create_session_expect_file'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import sha; BLOCKSIZE = 65536; hasher = sha.sha(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/create_session_expect_file'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (echo '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'0 '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/create_session_expect_file)'"'"'"'"'"'"'"'"''"'"'' <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=sqrekcmqenmgzqzohskvlzjbngevreem] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-sqrekcmqenmgzqzohskvlzjbngevreem; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' ok: [switch2] => {"changed": false, "checksum": "da39a3ee5e6b4b0d3255bfef95601890afd80709", "dest": "/home/admin/everflow_tests/create_session_expect_file", "gid": 0, "group": "root", "invocation": {"module_args": {"backup": null, "content": null, "delimiter": null, "dest": "/home/admin/everflow_tests/create_session_expect_file", "diff_peek": null, "directory_mode": null, "follow": false, "force": false, "group": null, "mode": null, "original_basename": "create_session_expect_file", "owner": null, "path": "/home/admin/everflow_tests/create_session_expect_file", "recurse": false, "regexp": null, "remote_src": null, "selevel": null, "serole": null, "setype": null, "seuser": null, "src": "create_session_expect_file", "state": null, "validate": null}}, "mode": "0644", "owner": "root", "path": "/home/admin/everflow_tests/create_session_expect_file", "size": 0, "state": "file", "uid": 0} TASK [test : Copy loganalyzer.py to run directory] ***************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:47 Tuesday 26 September 2017 14:30:45 +0800 (0:00:00.444) 0:00:19.333 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r -tt 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=tjamiubuggslsruobafflayorhabbkeq] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-tjamiubuggslsruobafflayorhabbkeq; rc=flag; [ -r /home/admin/everflow_tests ] || rc=2; [ -f /home/admin/everflow_tests ] || rc=1; [ -d /home/admin/everflow_tests ] && rc=3; python -V 2>/dev/null || rc=4; [ x"$rc" != "xflag" ] && echo "${rc} "/home/admin/everflow_tests && exit 0; (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import hashlib; BLOCKSIZE = 65536; hasher = hashlib.sha1(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import sha; BLOCKSIZE = 65536; hasher = sha.sha(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (echo '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'0 '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests)'"'"'"'"'"'"'"'"''"'"'' <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r -tt 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=yllrygzrbjyuvxczompmnibdtocbpiwa] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-yllrygzrbjyuvxczompmnibdtocbpiwa; rc=flag; [ -r /home/admin/everflow_tests/loganalyzer.py ] || rc=2; [ -f /home/admin/everflow_tests/loganalyzer.py ] || rc=1; [ -d /home/admin/everflow_tests/loganalyzer.py ] && rc=3; python -V 2>/dev/null || rc=4; [ x"$rc" != "xflag" ] && echo "${rc} "/home/admin/everflow_tests/loganalyzer.py && exit 0; (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import hashlib; BLOCKSIZE = 65536; hasher = hashlib.sha1(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/loganalyzer.py'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (python -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'import sha; BLOCKSIZE = 65536; hasher = sha.sha(); afile = open("'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/loganalyzer.py'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'", "rb") buf = afile.read(BLOCKSIZE) while len(buf) > 0: hasher.update(buf) buf = afile.read(BLOCKSIZE) afile.close() print(hasher.hexdigest())'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' 2>/dev/null) || (echo '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'0 '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'/home/admin/everflow_tests/loganalyzer.py)'"'"'"'"'"'"'"'"''"'"'' <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r -tt 172.20.192.94 '( umask 22 && mkdir -p "$( echo $HOME/.ansible/tmp/ansible-tmp-1506407445.53-102515209114794 )" && echo "$( echo $HOME/.ansible/tmp/ansible-tmp-1506407445.53-102515209114794 )" )' <172.20.192.94> PUT /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer.py TO /home/admin/.ansible/tmp/ansible-tmp-1506407445.53-102515209114794/source <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set sftp_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 sftp -b - -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r '[172.20.192.94]' <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=lbkhbrjmydtoxeckdwhwweiiqsojqqpb] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-lbkhbrjmydtoxeckdwhwweiiqsojqqpb; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "checksum": "2c0c529aa79ccbae83878c07fb2b2442dbea295e", "dest": "/home/admin/everflow_tests/loganalyzer.py", "gid": 0, "group": "root", "invocation": {"module_args": {"backup": false, "content": null, "delimiter": null, "dest": "/home/admin/everflow_tests/loganalyzer.py", "directory_mode": null, "follow": false, "force": true, "group": null, "mode": null, "original_basename": "loganalyzer.py", "owner": null, "regexp": null, "remote_src": null, "selevel": null, "serole": null, "setype": null, "seuser": null, "src": "/home/admin/.ansible/tmp/ansible-tmp-1506407445.53-102515209114794/source", "validate": null}}, "md5sum": "57b0651384cf0f661ceed655ff043797", "mode": "0644", "owner": "root", "size": 24355, "src": "/home/admin/.ansible/tmp/ansible-tmp-1506407445.53-102515209114794/source", "state": "file", "uid": 0} TASK [test : create output directory] ****************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:51 Tuesday 26 September 2017 14:30:45 +0800 (0:00:00.560) 0:00:19.894 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=mrndweirlfuekzazrnnzpgifxrdggdmn] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-mrndweirlfuekzazrnnzpgifxrdggdmn; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' ok: [switch2] => {"changed": false, "gid": 0, "group": "root", "invocation": {"module_args": {"backup": null, "content": null, "delimiter": null, "diff_peek": null, "directory_mode": null, "follow": false, "force": false, "group": null, "mode": null, "original_basename": null, "owner": null, "path": "/home/admin/everflow_tests/results", "recurse": false, "regexp": null, "remote_src": null, "selevel": null, "serole": null, "setype": null, "seuser": null, "src": null, "state": "directory", "validate": null}, "module_name": "file"}, "mode": "0755", "owner": "root", "path": "/home/admin/everflow_tests/results", "size": 4096, "state": "directory", "uid": 0} TASK [test : create output directory for current test run] ********************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:55 Tuesday 26 September 2017 14:30:46 +0800 (0:00:00.360) 0:00:20.255 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=jfxlbqqashhjrkeefpnzhmpixvxhuvby] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-jfxlbqqashhjrkeefpnzhmpixvxhuvby; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "gid": 0, "group": "root", "invocation": {"module_args": {"backup": null, "content": null, "delimiter": null, "diff_peek": null, "directory_mode": null, "follow": false, "force": false, "group": null, "mode": null, "original_basename": null, "owner": null, "path": "/home/admin/everflow_tests/results/logic_test.2017-09-26.06:30:27", "recurse": false, "regexp": null, "remote_src": null, "selevel": null, "serole": null, "setype": null, "seuser": null, "src": null, "state": "directory", "validate": null}, "module_name": "file"}, "mode": "0755", "owner": "root", "path": "/home/admin/everflow_tests/results/logic_test.2017-09-26.06:30:27", "size": 4096, "state": "directory", "uid": 0} TASK [test : Force log rotation to have most of log file available] ************ task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:58 Tuesday 26 September 2017 14:30:46 +0800 (0:00:00.377) 0:00:20.633 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=qxcdxlurxgfzyaesxzwhhezxsbvlrlje] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-qxcdxlurxgfzyaesxzwhhezxsbvlrlje; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": ["logrotate", "-f", "/etc/logrotate.conf"], "delta": "0:00:00.317420", "end": "2017-09-26 06:30:47.177735", "invocation": {"module_args": {"_raw_params": "logrotate -f /etc/logrotate.conf", "_uses_shell": false, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:30:46.860315", "stderr": "", "stdout": "", "stdout_lines": [], "warnings": []} TASK [test : debug] ************************************************************ task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:61 Tuesday 26 September 2017 14:30:47 +0800 (0:00:00.681) 0:00:21.314 ***** ok: [switch2] => { "msg": "starting loganalyzer_init.py" } TASK [test : debug] ************************************************************ task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:62 Tuesday 26 September 2017 14:30:47 +0800 (0:00:00.179) 0:00:21.494 ***** ok: [switch2] => { "msg": "python /home/admin/everflow_tests/loganalyzer.py --action init --run_id logic_test.2017-09-26.06:30:27" } TASK [test : Initialize loganalyzer] ******************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:63 Tuesday 26 September 2017 14:30:47 +0800 (0:00:00.200) 0:00:21.695 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=fomemuxevggnsurlecodnvkotmbzmsdc] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-fomemuxevggnsurlecodnvkotmbzmsdc; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": "python /home/admin/everflow_tests/loganalyzer.py --action init --run_id logic_test.2017-09-26.06:30:27", "delta": "0:00:00.066730", "end": "2017-09-26 06:30:47.968432", "invocation": {"module_args": {"_raw_params": "python /home/admin/everflow_tests/loganalyzer.py --action init --run_id logic_test.2017-09-26.06:30:27", "_uses_shell": true, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:30:47.901702", "stderr": "", "stdout": "", "stdout_lines": [], "warnings": []} TASK [test : debug] ************************************************************ task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_init.yml:66 Tuesday 26 September 2017 14:30:48 +0800 (0:00:00.432) 0:00:22.127 ***** ok: [switch2] => { "msg": "Finished calling loganalyzer init phase" } TASK [test : Load JSON config config_valid.json] ******************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_test.yml:14 Tuesday 26 September 2017 14:30:48 +0800 (0:00:00.219) 0:00:22.347 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=onykqtksfqdkbyldbzaeoazebnuxriec] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-onykqtksfqdkbyldbzaeoazebnuxriec; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": ["docker", "exec", "-i", "swss", "swssconfig", "/tmp/config_valid.json"], "delta": "0:00:00.172515", "end": "2017-09-26 06:30:48.700263", "invocation": {"module_args": {"_raw_params": "docker exec -i swss swssconfig /tmp/config_valid.json", "_uses_shell": false, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:30:48.527748", "stderr": "", "stdout": "", "stdout_lines": [], "warnings": []} TASK [test : Use loganalyzer to check for the error messages logic_test / config_valid.json.] *** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_test.yml:17 Tuesday 26 September 2017 14:30:48 +0800 (0:00:00.386) 0:00:22.733 ***** included: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_analyze.yml for switch2 TASK [test : debug] ************************************************************ task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_analyze.yml:5 Tuesday 26 September 2017 14:30:49 +0800 (0:00:00.478) 0:00:23.211 ***** ok: [switch2] => { "msg": "starting loganalyzer analysis phase" } TASK [test : Init variables] *************************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_analyze.yml:7 Tuesday 26 September 2017 14:30:49 +0800 (0:00:00.100) 0:00:23.311 ***** ok: [switch2] => {"ansible_facts": {"expect_file_option": "-e loganalyzer_common_expect.txt", "ignore_file_option": "-i loganalyzer_common_ignore.txt", "match_file_option": "-m loganalyzer_common_match.txt"}, "changed": false, "invocation": {"module_args": {"expect_file_option": "-e loganalyzer_common_expect.txt", "ignore_file_option": "-i loganalyzer_common_ignore.txt", "match_file_option": "-m loganalyzer_common_match.txt"}, "module_name": "set_fact"}} TASK [test : Add test specific match file] ************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_analyze.yml:13 Tuesday 26 September 2017 14:30:49 +0800 (0:00:00.077) 0:00:23.389 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : Add test specific ignore file] ************************************ task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_analyze.yml:18 Tuesday 26 September 2017 14:30:49 +0800 (0:00:00.062) 0:00:23.452 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : Use test specific expect file] ************************************ task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_analyze.yml:23 Tuesday 26 September 2017 14:30:49 +0800 (0:00:00.103) 0:00:23.555 ***** ok: [switch2] => {"ansible_facts": {"expect_file_option": "-e create_session_expect_file"}, "changed": false, "invocation": {"module_args": {"expect_file_option": "-e create_session_expect_file"}, "module_name": "set_fact"}} TASK [test : set_fact] ********************************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_analyze.yml:28 Tuesday 26 September 2017 14:30:49 +0800 (0:00:00.080) 0:00:23.636 ***** ok: [switch2] => {"ansible_facts": {"cmd": "python /home/admin/everflow_tests/loganalyzer.py --action analyze --run_id logic_test.2017-09-26.06:30:27 --out_dir /home/admin/everflow_tests/results/logic_test.2017-09-26.06:30:27 -m loganalyzer_common_match.txt -i loganalyzer_common_ignore.txt -e create_session_expect_file -v"}, "changed": false, "invocation": {"module_args": {"cmd": "python /home/admin/everflow_tests/loganalyzer.py --action analyze --run_id logic_test.2017-09-26.06:30:27 --out_dir /home/admin/everflow_tests/results/logic_test.2017-09-26.06:30:27 -m loganalyzer_common_match.txt -i loganalyzer_common_ignore.txt -e create_session_expect_file -v"}, "module_name": "set_fact"}} TASK [test : debug] ************************************************************ task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_analyze.yml:30 Tuesday 26 September 2017 14:30:49 +0800 (0:00:00.088) 0:00:23.724 ***** ok: [switch2] => { "msg": "python /home/admin/everflow_tests/loganalyzer.py --action analyze --run_id logic_test.2017-09-26.06:30:27 --out_dir /home/admin/everflow_tests/results/logic_test.2017-09-26.06:30:27 -m loganalyzer_common_match.txt -i loganalyzer_common_ignore.txt -e create_session_expect_file -v" } TASK [test : Invoke loganalyzer analyse logic_test] **************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_analyze.yml:32 Tuesday 26 September 2017 14:30:49 +0800 (0:00:00.078) 0:00:23.803 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=szmlhwlmszzeqdiotwrhqtgiltvjydxd] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-szmlhwlmszzeqdiotwrhqtgiltvjydxd; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": "python /home/admin/everflow_tests/loganalyzer.py --action analyze --run_id logic_test.2017-09-26.06:30:27 --out_dir /home/admin/everflow_tests/results/logic_test.2017-09-26.06:30:27 -m loganalyzer_common_match.txt -i loganalyzer_common_ignore.txt -e create_session_expect_file -v", "delta": "0:00:00.070649", "end": "2017-09-26 06:30:50.075621", "invocation": {"module_args": {"_raw_params": "python /home/admin/everflow_tests/loganalyzer.py --action analyze --run_id logic_test.2017-09-26.06:30:27 --out_dir /home/admin/everflow_tests/results/logic_test.2017-09-26.06:30:27 -m loganalyzer_common_match.txt -i loganalyzer_common_ignore.txt -e create_session_expect_file -v", "_uses_shell": true, "chdir": "/home/admin/everflow_tests", "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:30:50.004972", "stderr": "", "stdout": "[LogAnalyzer][diagnostic]:processing match file:loganalyzer_common_match.txt\n[LogAnalyzer][diagnostic]:[diagnostic]:processing row:0\n[LogAnalyzer][diagnostic]:row:['r', '\\\\.ERR', '\\\\.WARN', 'crash']\n[LogAnalyzer][diagnostic]:[diagnostic]:processing row:1\n[LogAnalyzer][diagnostic]:row:['r', 'kernel:.*Oops', 'kernel:.*hung', 'kernel.*oom\\\\s']\n[LogAnalyzer][diagnostic]:[diagnostic]:processing row:2\n[LogAnalyzer][diagnostic]:row:['r', 'kernel:.*scheduling', 'kernel:.*atomic', 'kernel:.*panic']\n[LogAnalyzer][diagnostic]:[diagnostic]:processing row:3\n[LogAnalyzer][diagnostic]:row:['r', 'kernel:.*\\\\serr', 'kernel:.*allocation', 'kernel:.*kill', '']\n[LogAnalyzer][diagnostic]:[diagnostic]:processing row:4\n[LogAnalyzer][diagnostic]:row:['r', 'kernel:.*kmemleak.*', 'kernel:.* Err:']\n[LogAnalyzer][diagnostic]:[diagnostic]:processing row:5\n[LogAnalyzer][diagnostic]:row:['s', 'ERR']\n[LogAnalyzer][diagnostic]:Built error string: ERR\n[LogAnalyzer][diagnostic]:processing match file:loganalyzer_common_ignore.txt\n[LogAnalyzer][diagnostic]:processing match file:create_session_expect_file\n[LogAnalyzer][diagnostic]:analyzing file: /var/log/syslog\n[LogAnalyzer][diagnostic]:found end marker: end-LogAnalyzer-logic_test.2017-09-26.06:30:27\n[LogAnalyzer][diagnostic]:found start marker: start-LogAnalyzer-logic_test.2017-09-26.06:30:27", "stdout_lines": ["[LogAnalyzer][diagnostic]:processing match file:loganalyzer_common_match.txt", "[LogAnalyzer][diagnostic]:[diagnostic]:processing row:0", "[LogAnalyzer][diagnostic]:row:['r', '\\\\.ERR', '\\\\.WARN', 'crash']", "[LogAnalyzer][diagnostic]:[diagnostic]:processing row:1", "[LogAnalyzer][diagnostic]:row:['r', 'kernel:.*Oops', 'kernel:.*hung', 'kernel.*oom\\\\s']", "[LogAnalyzer][diagnostic]:[diagnostic]:processing row:2", "[LogAnalyzer][diagnostic]:row:['r', 'kernel:.*scheduling', 'kernel:.*atomic', 'kernel:.*panic']", "[LogAnalyzer][diagnostic]:[diagnostic]:processing row:3", "[LogAnalyzer][diagnostic]:row:['r', 'kernel:.*\\\\serr', 'kernel:.*allocation', 'kernel:.*kill', '']", "[LogAnalyzer][diagnostic]:[diagnostic]:processing row:4", "[LogAnalyzer][diagnostic]:row:['r', 'kernel:.*kmemleak.*', 'kernel:.* Err:']", "[LogAnalyzer][diagnostic]:[diagnostic]:processing row:5", "[LogAnalyzer][diagnostic]:row:['s', 'ERR']", "[LogAnalyzer][diagnostic]:Built error string: ERR", "[LogAnalyzer][diagnostic]:processing match file:loganalyzer_common_ignore.txt", "[LogAnalyzer][diagnostic]:processing match file:create_session_expect_file", "[LogAnalyzer][diagnostic]:analyzing file: /var/log/syslog", "[LogAnalyzer][diagnostic]:found end marker: end-LogAnalyzer-logic_test.2017-09-26.06:30:27", "[LogAnalyzer][diagnostic]:found start marker: start-LogAnalyzer-logic_test.2017-09-26.06:30:27"], "warnings": []} TASK [test : debug] ************************************************************ task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/files/tools/loganalyzer/loganalyzer_analyze.yml:37 Tuesday 26 September 2017 14:30:50 +0800 (0:00:00.326) 0:00:24.129 ***** ok: [switch2] => { "msg": "Finished calling loganalyzer analyze phase" } TASK [test : Get the total number of expected messages.] *********************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_test.yml:20 Tuesday 26 September 2017 14:30:50 +0800 (0:00:00.080) 0:00:24.210 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : Check that expected error messages are found (negative tests only).] *** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_test.yml:25 Tuesday 26 September 2017 14:30:50 +0800 (0:00:00.039) 0:00:24.249 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : Get the total number of error messages.] ************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_test.yml:29 Tuesday 26 September 2017 14:30:50 +0800 (0:00:00.054) 0:00:24.304 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=wwavuqxrtfvbqjescxeqjtvoweypuilf] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-wwavuqxrtfvbqjescxeqjtvoweypuilf; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": "grep \"TOTAL MATCHES\" \"/home/admin/everflow_tests/results/logic_test.2017-09-26.06:30:27/summary.loganalysis.logic_test.2017-09-26.06:30:27.log\" | sed -n \"s/TOTAL MATCHES:[[:space:]]*//p\"", "delta": "0:00:00.012391", "end": "2017-09-26 06:30:50.498417", "invocation": {"module_args": {"_raw_params": "grep \"TOTAL MATCHES\" \"/home/admin/everflow_tests/results/logic_test.2017-09-26.06:30:27/summary.loganalysis.logic_test.2017-09-26.06:30:27.log\" | sed -n \"s/TOTAL MATCHES:[[:space:]]*//p\"", "_uses_shell": true, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:30:50.486026", "stderr": "", "stdout": "0", "stdout_lines": ["0"], "warnings": []} TASK [test : Check the number of error messages (positive tests only).] ******** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_test.yml:33 Tuesday 26 September 2017 14:30:50 +0800 (0:00:00.227) 0:00:24.531 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : Copy test data to host.] ****************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_test.yml:37 Tuesday 26 September 2017 14:30:50 +0800 (0:00:00.038) 0:00:24.570 ***** skipping: [switch2] => (item=summary.loganalysis.logic_test.2017-09-26.06:30:27.log) => {"changed": false, "item": "summary.loganalysis.logic_test.2017-09-26.06:30:27.log", "skip_reason": "Conditional check failed", "skipped": true} skipping: [switch2] => (item=result.loganalysis.logic_test.2017-09-26.06:30:27.log) => {"changed": false, "item": "result.loganalysis.logic_test.2017-09-26.06:30:27.log", "skip_reason": "Conditional check failed", "skipped": true} TASK [test : Do configuration cleanup after logic_test / config_valid.json] **** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_test.yml:44 Tuesday 26 September 2017 14:30:50 +0800 (0:00:00.351) 0:00:24.921 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : Create route with prefix that matches session DST IP and unresolved next hop.] *** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:42 Tuesday 26 September 2017 14:30:50 +0800 (0:00:00.037) 0:00:24.959 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=zfwmhlfzxsxqzkoirlxlpjihgvgpzjps] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-zfwmhlfzxsxqzkoirlxlpjihgvgpzjps; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": ["ip", "route", "add", "2.2.2.0/24", "via", "10.0.0.5"], "delta": "0:00:00.010213", "end": "2017-09-26 06:30:51.141450", "invocation": {"module_args": {"_raw_params": "ip route add 2.2.2.0/24 via 10.0.0.5", "_uses_shell": false, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:30:51.131237", "stderr": "", "stdout": "", "stdout_lines": [], "warnings": []} TASK [test : Resolve session route.] ******************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:45 Tuesday 26 September 2017 14:30:51 +0800 (0:00:00.206) 0:00:25.165 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=xqmnobyhvqorywicqpzintxufvchgdof] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-xqmnobyhvqorywicqpzintxufvchgdof; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": ["ip", "neigh", "replace", "10.0.0.5", "lladdr", "00:11:22:33:44:55", "nud", "permanent", "dev", "Ethernet8"], "delta": "0:00:00.011621", "end": "2017-09-26 06:30:51.361654", "invocation": {"module_args": {"_raw_params": "ip neigh replace 10.0.0.5 lladdr 00:11:22:33:44:55 nud permanent dev Ethernet8", "_uses_shell": false, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:30:51.350033", "stderr": "", "stdout": "", "stdout_lines": [], "warnings": []} TASK [test : Wait for route initialization.] *********************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:48 Tuesday 26 September 2017 14:30:51 +0800 (0:00:00.223) 0:00:25.388 ***** Pausing for 10 seconds (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort) ok: [switch2] => {"changed": false, "delta": 10, "invocation": {"module_args": {"seconds": 10}, "module_name": "pause"}, "rc": 0, "start": "2017-09-26 14:30:51.440776", "stderr": "", "stdout": "Paused for 10.0 seconds", "stop": "2017-09-26 14:31:01.440905", "user_input": ""} TASK [test : Verify session state is active.] ********************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:52 Tuesday 26 September 2017 14:31:01 +0800 (0:00:10.047) 0:00:35.435 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=vbjcbcfodfmpxixxfxkcxsuznzgyuqpo] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-vbjcbcfodfmpxixxfxkcxsuznzgyuqpo; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": "docker exec -i swss redis-cli HGET MIRROR_SESSION_TABLE:\"session1\" status", "delta": "0:00:00.197972", "end": "2017-09-26 06:31:01.807586", "failed": false, "failed_when_result": false, "invocation": {"module_args": {"_raw_params": "docker exec -i swss redis-cli HGET MIRROR_SESSION_TABLE:\"session1\" status", "_uses_shell": true, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:31:01.609614", "stderr": "", "stdout": "active", "stdout_lines": ["active"], "warnings": []} TASK [test : Create route with longer prefix and resolved next hop.] *********** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:57 Tuesday 26 September 2017 14:31:01 +0800 (0:00:00.411) 0:00:35.847 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=ptcworodaawpaqjdxqivugznhbtcraxa] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-ptcworodaawpaqjdxqivugznhbtcraxa; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": ["ip", "route", "add", "2.2.2.0/30", "via", "10.0.0.5"], "delta": "0:00:00.011605", "end": "2017-09-26 06:31:02.045000", "invocation": {"module_args": {"_raw_params": "ip route add 2.2.2.0/30 via 10.0.0.5", "_uses_shell": false, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:31:02.033395", "stderr": "", "stdout": "", "stdout_lines": [], "warnings": []} TASK [test : Wait for route initialization.] *********************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:60 Tuesday 26 September 2017 14:31:02 +0800 (0:00:00.221) 0:00:36.069 ***** Pausing for 10 seconds (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort) ok: [switch2] => {"changed": false, "delta": 10, "invocation": {"module_args": {"seconds": 10}, "module_name": "pause"}, "rc": 0, "start": "2017-09-26 14:31:02.119140", "stderr": "", "stdout": "Paused for 10.0 seconds", "stop": "2017-09-26 14:31:12.119276", "user_input": ""} TASK [test : Verify session state is active] *********************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:64 Tuesday 26 September 2017 14:31:12 +0800 (0:00:10.042) 0:00:46.111 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=whkvqlwmbbdhmjykvtubimllannmbbqs] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-whkvqlwmbbdhmjykvtubimllannmbbqs; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": "docker exec -i swss redis-cli HGET MIRROR_SESSION_TABLE:\"session1\" status", "delta": "0:00:00.213937", "end": "2017-09-26 06:31:12.497491", "failed": false, "failed_when_result": false, "invocation": {"module_args": {"_raw_params": "docker exec -i swss redis-cli HGET MIRROR_SESSION_TABLE:\"session1\" status", "_uses_shell": true, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:31:12.283554", "stderr": "", "stdout": "active", "stdout_lines": ["active"], "warnings": []} TASK [test : Remove route with longer prefix.] ********************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:69 Tuesday 26 September 2017 14:31:12 +0800 (0:00:00.418) 0:00:46.530 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=nzgjtixbnkdelcbwavvebppeqjgdzhkl] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-nzgjtixbnkdelcbwavvebppeqjgdzhkl; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": ["ip", "route", "del", "2.2.2.0/30"], "delta": "0:00:00.010236", "end": "2017-09-26 06:31:12.735221", "invocation": {"module_args": {"_raw_params": "ip route del 2.2.2.0/30", "_uses_shell": false, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:31:12.724985", "stderr": "", "stdout": "", "stdout_lines": [], "warnings": []} TASK [test : Wait for route initialization.] *********************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:72 Tuesday 26 September 2017 14:31:12 +0800 (0:00:00.229) 0:00:46.759 ***** Pausing for 10 seconds (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort) ok: [switch2] => {"changed": false, "delta": 10, "invocation": {"module_args": {"seconds": 10}, "module_name": "pause"}, "rc": 0, "start": "2017-09-26 14:31:12.811584", "stderr": "", "stdout": "Paused for 10.0 seconds", "stop": "2017-09-26 14:31:22.811754", "user_input": ""} TASK [test : Verify session state is active] *********************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:76 Tuesday 26 September 2017 14:31:22 +0800 (0:00:10.045) 0:00:56.805 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=zmmqsguewnbybmuljsybsicmiiobdmwc] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-zmmqsguewnbybmuljsybsicmiiobdmwc; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": "docker exec -i swss redis-cli HGET MIRROR_SESSION_TABLE:\"session1\" status", "delta": "0:00:00.190755", "end": "2017-09-26 06:31:23.180126", "failed": false, "failed_when_result": false, "invocation": {"module_args": {"_raw_params": "docker exec -i swss redis-cli HGET MIRROR_SESSION_TABLE:\"session1\" status", "_uses_shell": true, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:31:22.989371", "stderr": "", "stdout": "active", "stdout_lines": ["active"], "warnings": []} TASK [test : Change neighbor MAC address.] ************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:81 Tuesday 26 September 2017 14:31:23 +0800 (0:00:00.405) 0:00:57.210 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=jaogwkzjwthgfbfcdoerssimomhoewrn] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-jaogwkzjwthgfbfcdoerssimomhoewrn; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": ["ip", "neigh", "replace", "10.0.0.5", "lladdr", "00:11:22:33:44:66", "nud", "permanent", "dev", "Ethernet8"], "delta": "0:00:00.010898", "end": "2017-09-26 06:31:23.404806", "invocation": {"module_args": {"_raw_params": "ip neigh replace 10.0.0.5 lladdr 00:11:22:33:44:66 nud permanent dev Ethernet8", "_uses_shell": false, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:31:23.393908", "stderr": "", "stdout": "", "stdout_lines": [], "warnings": []} TASK [test : Wait for next hop initialization.] ******************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:84 Tuesday 26 September 2017 14:31:23 +0800 (0:00:00.221) 0:00:57.432 ***** Pausing for 10 seconds (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort) ok: [switch2] => {"changed": false, "delta": 10, "invocation": {"module_args": {"seconds": 10}, "module_name": "pause"}, "rc": 0, "start": "2017-09-26 14:31:23.479340", "stderr": "", "stdout": "Paused for 10.0 seconds", "stop": "2017-09-26 14:31:33.479487", "user_input": ""} TASK [test : Verify session state is active] *********************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:88 Tuesday 26 September 2017 14:31:33 +0800 (0:00:10.040) 0:01:07.472 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=fnjvbevwqznkflyrendpmoejwzjwqorp] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-fnjvbevwqznkflyrendpmoejwzjwqorp; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": "docker exec -i swss redis-cli HGET MIRROR_SESSION_TABLE:\"session1\" status", "delta": "0:00:00.179225", "end": "2017-09-26 06:31:33.827616", "failed": false, "failed_when_result": false, "invocation": {"module_args": {"_raw_params": "docker exec -i swss redis-cli HGET MIRROR_SESSION_TABLE:\"session1\" status", "_uses_shell": true, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:31:33.648391", "stderr": "", "stdout": "active", "stdout_lines": ["active"], "warnings": []} TASK [test : Remove session route] ********************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:93 Tuesday 26 September 2017 14:31:33 +0800 (0:00:00.394) 0:01:07.867 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=zayhczptykvcwsjqgjnlitutgkcommmj] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-zayhczptykvcwsjqgjnlitutgkcommmj; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": ["ip", "route", "del", "2.2.2.0/24"], "delta": "0:00:00.011410", "end": "2017-09-26 06:31:34.065780", "invocation": {"module_args": {"_raw_params": "ip route del 2.2.2.0/24", "_uses_shell": false, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:31:34.054370", "stderr": "", "stdout": "", "stdout_lines": [], "warnings": []} TASK [test : Wait for route initialization.] *********************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:96 Tuesday 26 September 2017 14:31:34 +0800 (0:00:00.223) 0:01:08.090 ***** Pausing for 10 seconds (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort) ok: [switch2] => {"changed": false, "delta": 10, "invocation": {"module_args": {"seconds": 10}, "module_name": "pause"}, "rc": 0, "start": "2017-09-26 14:31:34.140110", "stderr": "", "stdout": "Paused for 10.0 seconds", "stop": "2017-09-26 14:31:44.140247", "user_input": ""} TASK [test : Verify session state is inactive.] ******************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:100 Tuesday 26 September 2017 14:31:44 +0800 (0:00:10.046) 0:01:18.137 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=gnybargumdjefndfqjtscvwmxhkcfbnz] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-gnybargumdjefndfqjtscvwmxhkcfbnz; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' fatal: [switch2]: FAILED! => {"changed": true, "cmd": "docker exec -i swss redis-cli HGET MIRROR_SESSION_TABLE:\"session1\" status", "delta": "0:00:00.229846", "end": "2017-09-26 06:31:44.541825", "failed": true, "failed_when_result": true, "invocation": {"module_args": {"_raw_params": "docker exec -i swss redis-cli HGET MIRROR_SESSION_TABLE:\"session1\" status", "_uses_shell": true, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:31:44.311979", "stderr": "", "stdout": "active", "stdout_lines": ["active"], "warnings": []} TASK [test : Remove session route.] ******************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:106 Tuesday 26 September 2017 14:31:44 +0800 (0:00:00.449) 0:01:18.586 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=tdaubbszhxfqmhxrgggqhryobldhssnz] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-tdaubbszhxfqmhxrgggqhryobldhssnz; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' fatal: [switch2]: FAILED! => {"changed": true, "cmd": ["ip", "route", "del", "2.2.2.0/24"], "delta": "0:00:00.010293", "end": "2017-09-26 06:31:44.777917", "failed": true, "invocation": {"module_args": {"_raw_params": "ip route del 2.2.2.0/24", "_uses_shell": false, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 2, "start": "2017-09-26 06:31:44.767624", "stderr": "RTNETLINK answers: No such process", "stdout": "", "stdout_lines": [], "warnings": []} ...ignoring TASK [test : Remove session route.] ******************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:110 Tuesday 26 September 2017 14:31:44 +0800 (0:00:00.214) 0:01:18.801 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=odszswiestvawphfgtfmfdwhuiehgitt] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-odszswiestvawphfgtfmfdwhuiehgitt; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' fatal: [switch2]: FAILED! => {"changed": true, "cmd": ["ip", "route", "del", "2.2.2.0/30"], "delta": "0:00:00.011009", "end": "2017-09-26 06:31:44.991018", "failed": true, "invocation": {"module_args": {"_raw_params": "ip route del 2.2.2.0/30", "_uses_shell": false, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 2, "start": "2017-09-26 06:31:44.980009", "stderr": "RTNETLINK answers: No such process", "stdout": "", "stdout_lines": [], "warnings": []} ...ignoring TASK [test : Remove neighbor] ************************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:114 Tuesday 26 September 2017 14:31:45 +0800 (0:00:00.229) 0:01:19.031 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=laehcalhuomoklzaubcszrgtdzyiaxyv] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-laehcalhuomoklzaubcszrgtdzyiaxyv; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": ["ip", "neigh", "del", "10.0.0.5", "dev", "Ethernet8"], "delta": "0:00:00.011586", "end": "2017-09-26 06:31:45.236637", "invocation": {"module_args": {"_raw_params": "ip neigh del 10.0.0.5 dev Ethernet8", "_uses_shell": false, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:31:45.225051", "stderr": "", "stdout": "", "stdout_lines": [], "warnings": []} TASK [test : Clear session configuration] ************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:118 Tuesday 26 September 2017 14:31:45 +0800 (0:00:00.231) 0:01:19.263 ***** included: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_cleanup.yml for switch2 TASK [test : Do configuration cleanup.] **************************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_cleanup.yml:2 Tuesday 26 September 2017 14:31:45 +0800 (0:00:00.260) 0:01:19.523 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=zpqxttjtsvvxwhesazrvdlvlvpwztfbz] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-zpqxttjtsvvxwhesazrvdlvlvpwztfbz; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => {"changed": true, "cmd": "service swss restart", "delta": "0:00:07.436043", "end": "2017-09-26 06:31:53.138787", "invocation": {"module_args": {"_raw_params": "service swss restart", "_uses_shell": true, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "rc": 0, "start": "2017-09-26 06:31:45.702744", "stderr": "", "stdout": "", "stdout_lines": [], "warnings": ["Consider using service module rather than running service"]} TASK [test : fail] ************************************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_cleanup.yml:7 Tuesday 26 September 2017 14:31:53 +0800 (0:00:07.664) 0:01:27.187 ***** skipping: [switch2] => {"changed": false, "skip_reason": "Conditional check failed", "skipped": true} TASK [test : Wait for orchagent initialization] ******************************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/run_config_cleanup.yml:10 Tuesday 26 September 2017 14:31:53 +0800 (0:00:00.054) 0:01:27.242 ***** Pausing for 35 seconds (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort) ok: [switch2] => {"changed": false, "delta": 35, "invocation": {"module_args": {"seconds": 35}, "module_name": "pause"}, "rc": 0, "start": "2017-09-26 14:31:53.296198", "stderr": "", "stdout": "Paused for 35.0 seconds", "stop": "2017-09-26 14:32:28.296335", "user_input": ""} TASK [test : Remove all the temporary files created by the test.] ************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow/logic_test/logic_test.yml:121 Tuesday 26 September 2017 14:32:28 +0800 (0:00:35.065) 0:02:02.307 ***** included: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/deinit_config_test.yml for switch2 TASK [test : Remove all the temporary files created by the test.] ************** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/deinit_config_test.yml:1 Tuesday 26 September 2017 14:32:28 +0800 (0:00:00.229) 0:02:02.537 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=uriunzjjwnplhgxjtxtbqvniqbojsref] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-uriunzjjwnplhgxjtxtbqvniqbojsref; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => (item=config_valid.json) => {"changed": true, "invocation": {"module_args": {"backup": null, "content": null, "delimiter": null, "diff_peek": null, "directory_mode": null, "follow": false, "force": false, "group": null, "mode": null, "original_basename": null, "owner": null, "path": "/home/admin/everflow_tests/config_valid.json", "recurse": false, "regexp": null, "remote_src": null, "selevel": null, "serole": null, "setype": null, "seuser": null, "src": null, "state": "absent", "validate": null}, "module_name": "file"}, "item": "config_valid.json", "path": "/home/admin/everflow_tests/config_valid.json", "state": "absent"} TASK [test : Remove all JSON configs from docker container filesystem.] ******** task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/deinit_config_test.yml:6 Tuesday 26 September 2017 14:32:28 +0800 (0:00:00.432) 0:02:02.969 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=mguzeeacafzaqbxvywjgzlfvrjmwvuxn] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-mguzeeacafzaqbxvywjgzlfvrjmwvuxn; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => (item=config_valid.json) => {"changed": true, "cmd": ["docker", "exec", "swss", "rm", "/tmp/config_valid.json"], "delta": "0:00:00.183869", "end": "2017-09-26 06:32:29.508284", "invocation": {"module_args": {"_raw_params": "docker exec swss rm /tmp/config_valid.json", "_uses_shell": false, "chdir": null, "creates": null, "executable": null, "removes": null, "warn": true}, "module_name": "command"}, "item": "config_valid.json", "rc": 0, "start": "2017-09-26 06:32:29.324415", "stderr": "", "stdout": "", "stdout_lines": [], "warnings": []} TASK [test : General cleanup.] ************************************************* task path: /home/tester/sonic_ansible/kenie/Azure/ansible/roles/test/tasks/everflow.yml:26 Tuesday 26 September 2017 14:32:29 +0800 (0:00:00.590) 0:02:03.560 ***** <172.20.192.94> ESTABLISH SSH CONNECTION FOR USER: admin <172.20.192.94> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=120s)(-o)(UserKnownHostsFile=/dev/null) <172.20.192.94> SSH: ANSIBLE_HOST_KEY_CHECKING/host_key_checking disabled: (-o)(StrictHostKeyChecking=no) <172.20.192.94> SSH: ANSIBLE_REMOTE_USER/remote_user/ansible_user/user/-u set: (-o)(User=admin) <172.20.192.94> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10) <172.20.192.94> SSH: PlayContext set ssh_common_args: () <172.20.192.94> SSH: PlayContext set ssh_extra_args: () <172.20.192.94> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r) <172.20.192.94> SSH: EXEC sshpass -d15 ssh -C -vvv -o ControlMaster=auto -o ControlPersist=120s -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o User=admin -o ConnectTimeout=10 -o ControlPath=/root/.ansible/cp/ansible-ssh-%h-%p-%r 172.20.192.94 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=uhrgfdksdnexkdtxsalqdxafgoficvpc] password: " -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-uhrgfdksdnexkdtxsalqdxafgoficvpc; LANG=C LC_ALL=C LC_MESSAGES=C /usr/bin/python'"'"'"'"'"'"'"'"''"'"'' changed: [switch2] => (item=/home/admin/everflow_tests/loganalyzer.py) => {"changed": true, "invocation": {"module_args": {"backup": null, "content": null, "delimiter": null, "diff_peek": null, "directory_mode": null, "follow": false, "force": false, "group": null, "mode": null, "original_basename": null, "owner": null, "path": "/home/admin/everflow_tests/loganalyzer.py", "recurse": false, "regexp": null, "remote_src": null, "selevel": null, "serole": null, "setype": null, "seuser": null, "src": null, "state": "absent", "validate": null}, "module_name": "file"}, "item": "/home/admin/everflow_tests/loganalyzer.py", "path": "/home/admin/everflow_tests/loganalyzer.py", "state": "absent"} PLAY RECAP ********************************************************************* switch2 : ok=71 changed=25 unreachable=0 failed=1 Tuesday 26 September 2017 14:32:29 +0800 (0:00:00.310) 0:02:03.871 ***** =============================================================================== TASK: test : Wait for orchagent initialization ------------------------- 35.07s TASK: test : Wait for route initialization. ---------------------------- 10.05s TASK: test : Wait for route initialization. ---------------------------- 10.05s TASK: test : Wait for route initialization. ---------------------------- 10.05s TASK: test : Wait for route initialization. ---------------------------- 10.04s TASK: test : Wait for next hop initialization. ------------------------- 10.04s TASK: test : Do configuration cleanup. ---------------------------------- 7.66s TASK: test : Verify interfaces are up correctly ------------------------- 4.65s TASK: test : Copy loganalyzer common match and ignore files to switch --- 3.30s TASK: setup ------------------------------------------------------------- 1.55s TASK: test : Get interface facts ---------------------------------------- 1.28s TASK: test : Copy JSON configs from switch into docker filesystem. ------ 1.14s TASK: test : Copy JSON configs onto switch. ----------------------------- 0.74s TASK: test : Force log rotation to have most of log file available ------ 0.68s TASK: test : Initialize loganalizer. Put start marker to log file. ------ 0.61s TASK: test : Remove all JSON configs from docker container filesystem. --- 0.59s TASK: test : Copy loganalyzer.py to run directory ----------------------- 0.56s TASK: test : Create session entry. -------------------------------------- 0.54s TASK: test : Use loganalyzer to check for the error messages {{ testname }} / {{ config_file }}. --- 0.48s TASK: test : Verify session state is inactive. -------------------------- 0.45s