Adding tgen pytest mark to couple of pfc snappi-tests. by kamalsahu0001 · Pull Request #19027 · sonic-net/sonic-mgmt

kamalsahu0001 · 2025-06-16T20:54:11Z

Description of PR

Summary:
Added tgen in addition to multidut-tgen

Type of change

Back port request

Approach

What is the motivation for this PR?

to add missing coverage on tgen

How did you do it?

Added tgen pytest mark

How did you verify/test it?

Ran on a single dut switch

Any platform specific information?

Supported testbed topology if it's a new test case?

Documentation

adding tgen pytest mark to the test case

mssonicbld · 2025-06-16T20:55:15Z

/azp run

azure-pipelines · 2025-06-16T20:55:28Z

Azure Pipelines successfully started running 1 pipeline(s).

kamalsahu0001 · 2025-06-18T06:08:31Z

AzDevOps@e1a1448fa9a4:~/sonic-mgmt/tests$ python3 -m pytest --inventory ../ansible/snappi-sonic --host-pattern sonic-s6100-dut1 --testbed vms-snappi-sonic --testbed_file ../ansible/testbed.yaml --show-capture=stdout --log-cli-level info --showlocals -ra --allow_recover --skip_sanity --disable_loganalyzer --topology tgen snappi_tests/pfc/test_m2o_fluctuating_lossless.py

---------------------------------------------------------------------------------------------- live log sessionstart ----------------------------------------------------------------------------------------------
05:55:22 conftest.pytest_sessionstart L0470 INFO | Invoking /var/AzDevOps/sonic-mgmt/tests/build-gnmi-stubs.sh with base directory: /var/AzDevOps/sonic-mgmt/tests
05:55:23 conftest.pytest_sessionstart L0480 INFO | Output of /var/AzDevOps/sonic-mgmt/tests/build-gnmi-stubs.sh:
Removing existing directory: /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/github.com/openconfig
Creating directory: /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/github.com/openconfig
Removing existing directory: /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/generated
Creating directory: /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/generated
Cloning https://github.com/openconfig/gnmi.git into /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/github.com/openconfig
Generating gRPC stubs...
gRPC stubs generated successfully.
Creating empty init.py files under /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/generated/github/
Moving generated files to correct locations...
Files moved successfully.
Removing /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/generated/github.com directory...
/var/AzDevOps/sonic-mgmt/tests/common/sai_validation/generated/github.com directory removed successfully.

05:55:23 conftest.pytest_sessionstart L0491 INFO | Added /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/generated to sys.path
=============================================================================================== test session starts ===============================================================================================
platform linux -- Python 3.8.10, pytest-7.4.0, pluggy-1.3.0
ansible: 2.13.13
rootdir: /var/AzDevOps/sonic-mgmt/tests
configfile: pytest.ini
plugins: allure-pytest-2.8.22, ansible-4.0.0, forked-1.6.0, html-4.1.0, metadata-3.0.0, repeat-0.9.3, xdist-1.28.0
collecting 1 item ----------------------------------------------------------------------------------------------- live log collection -----------------------------------------------------------------------------------------------
05:55:25 init.pytest_collection_modifyitems L0640 INFO | Available basic facts that can be used in conditional skip:
{
"topo_type": "ptf",
"topo_name": "ptf64",
"testbed": "vms-snappi-sonic",
"asic_subtype": "broadcom",
"asic_type": "broadcom",
"branch": "202411",
"build_date": "Mon May 19 14:06:06 UTC 2025",
"build_number": 854573,
"build_version": "202411.854573-78d363b4a",
"built_by": "azureuser@d3d5b5e6c000000",
"commit_id": "78d363b4a",
"debian_version": "12.9",
"feature_status": {
"bgp": "enabled",
"database": "always_enabled",
"dhcp_relay": "disabled",
"eventd": "enabled",
"gnmi": "enabled",
"iccpd": "enabled",
"lldp": "enabled",
"macsec": "disabled",
"mgmt-framework": "enabled",
"mux": "always_disabled",
"nat": "disabled",
"pmon": "enabled",
"radv": "enabled",
"sflow": "disabled",
"snmp": "enabled",
"stp": "enabled",
"swss": "enabled",
"syncd": "enabled",
"teamd": "enabled",
"telemetry": "enabled"
},
"hwsku": "Accton-AS9716-32D",
"is_chassis": false,
"is_multi_asic": false,
"is_supervisor": false,
"kernel_version": "6.1.0",
"libswsscommon": "1.0.0",
"num_asic": 1,
"platform": "x86_64-accton_as9716_32d-r0",
"release": "202411",
"secure_boot_image": "no",
"sonic_os_version": 12,
"sonic_utilities": 1.2,
"asic_gen": "unknown",
"minigraph_interfaces": [
{
"addr": "21.1.1.1",
"attachto": "Ethernet0",
"mask": "255.255.255.0",
"peer_addr": "21.1.1.2",
"prefixlen": 24,
"subnet": "21.1.1.0/24"
},
{
"addr": "23.1.1.1",
"attachto": "Ethernet16",
"mask": "255.255.255.0",
"peer_addr": "23.1.1.2",
"prefixlen": 24,
"subnet": "23.1.1.0/24"
},
{
"addr": "24.1.1.1",
"attachto": "Ethernet24",
"mask": "255.255.255.0",
"peer_addr": "24.1.1.2",
"prefixlen": 24,
"subnet": "24.1.1.0/24"
},
{
"addr": "22.1.1.1",
"attachto": "Ethernet8",
"mask": "255.255.255.0",
"peer_addr": "22.1.1.2",
"prefixlen": 24,
"subnet": "22.1.1.0/24"
}
],
"minigraph_portchannels": {},
"minigraph_portchannel_interfaces": [],
"minigraph_neighbors": {
"Ethernet0": {
"name": "IXIA-Chassis1",
"namespace": "",
"port": "Port0"
},
"Ethernet16": {
"name": "IXIA-Chassis1",
"namespace": "",
"port": "Port2"
},
"Ethernet24": {
"name": "IXIA-Chassis1",
"namespace": "",
"port": "Port3"
},
"Ethernet8": {
"name": "IXIA-Chassis1",
"namespace": "",
"port": "Port1"
}
},
"VOQ_INBAND_INTERFACE": {},
"BGP_VOQ_CHASSIS_NEIGHBOR": {},
"INTERFACE": {
"Ethernet0": {
"2001::1/64": {},
"21.1.1.1/24": {}
},
"Ethernet16": {
"2003::1/64": {},
"23.1.1.1/24": {}
},
"Ethernet24": {
"2004::1/64": {},
"24.1.1.1/24": {}
},
"Ethernet8": {
"2002::1/64": {},
"22.1.1.1/24": {}
}
},
"switch_type": "",
"switch": {
"ACL_ACTION|PACKET_ACTION": "COPY,DROP,FORWARD",
"ASIC_SDK_HEALTH_EVENT": "false",
"ECMP_HASH_ALGORITHM": "CRC,XOR,CRC_32LO,CRC_32HI,CRC_CCITT,CRC_XOR",
"ECMP_HASH_ALGORITHM_CAPABLE": "true",
"ECMP_HASH_CAPABLE": "false",
"HASH|NATIVE_HASH_FIELD_LIST": "SRC_IP,DST_IP,VLAN_ID,IP_PROTOCOL,ETHERTYPE,L4_SRC_PORT,L4_DST_PORT,SRC_MAC,DST_MAC,IN_PORT",
"LAG_HASH_ALGORITHM": "CRC,XOR,CRC_32LO,CRC_32HI,CRC_CCITT,CRC_XOR",
"LAG_HASH_ALGORITHM_CAPABLE": "true",
"LAG_HASH_CAPABLE": "false",
"LAG_TPID_CAPABLE": "false",
"MAX_NEXTHOP_GROUP_COUNT": "4096",
"MIRROR": "true",
"MIRRORV6": "true",
"ORDERED_ECMP_CAPABLE": "true",
"PATH_TRACING_CAPABLE": "false",
"PFC_DLR_INIT_CAPABLE": "true",
"PORT_EGRESS_SAMPLE_CAPABLE": "false",
"PORT_TPID_CAPABLE": "true",
"REG_FATAL_ASIC_SDK_HEALTH_CATEGORY": "false",
"REG_NOTICE_ASIC_SDK_HEALTH_CATEGORY": "false",
"REG_WARNING_ASIC_SDK_HEALTH_CATEGORY": "false"
},
"macsec_en": false
}
collected 1 item

snappi_tests/pfc/test_m2o_fluctuating_lossless.py::test_m2o_fluctuating_lossless[tgen_port_info0]
------------------------------------------------------------------------------------------------- live log setup --------------------------------------------------------------------------------------------------
05:55:25 init.set_default L0053 INFO | Completeness level not set during test execution. Setting to default level: CompletenessLevel.basic
05:55:25 init.check_test_completeness L0151 INFO | Test has no defined levels. Continue without test completeness checks
05:55:25 conftest.enhance_inventory L0313 INFO | Inventory file: ['../ansible/snappi-sonic']
05:55:28 ptfhost_utils.run_icmp_responder_session L0310 INFO | Skip running icmp_responder at session level, it is only for dualtor testbed with active-active mux ports.
05:55:28 init._sanity_check L0431 INFO | Skip sanity check according to command line argument
05:55:28 conftest.collect_before_test L2695 INFO | Dumping Disk and Memory Space information before test on sonic-s6100-dut1
05:55:29 conftest.collect_before_test L2699 INFO | Collecting core dumps before test on sonic-s6100-dut1
05:55:29 conftest.collect_before_test L2708 INFO | Collecting running config before test on sonic-s6100-dut1
05:55:31 conftest.temporarily_disable_route_check L2974 INFO | Skipping temporarily_disable_route_check fixture
05:55:31 conftest.generate_params_dut_hostname L1498 INFO | Using DUTs ['sonic-s6100-dut1'] in testbed 'vms-snappi-sonic'
05:55:31 conftest.set_rand_one_dut_hostname L0647 INFO | Randomly select dut sonic-s6100-dut1 for testing
05:55:31 init._fixture_generator_decorator L0081 INFO | -------------------- fixture enable_packet_aging_after_test setup starts --------------------
05:55:31 init._fixture_generator_decorator L0085 INFO | -------------------- fixture enable_packet_aging_after_test setup ends --------------------
05:55:31 init._fixture_generator_decorator L0081 INFO | -------------------- fixture rand_lossless_prio setup starts --------------------
05:55:31 init._fixture_generator_decorator L0085 INFO | -------------------- fixture rand_lossless_prio setup ends --------------------
05:55:31 init._fixture_generator_decorator L0081 INFO | -------------------- fixture rand_lossy_prio setup starts --------------------
05:55:31 init._fixture_generator_decorator L0085 INFO | -------------------- fixture rand_lossy_prio setup ends --------------------
05:55:31 init._fixture_generator_decorator L0081 INFO | -------------------- fixture start_pfcwd_after_test setup starts --------------------
05:55:31 init._fixture_generator_decorator L0085 INFO | -------------------- fixture start_pfcwd_after_test setup ends --------------------
05:55:31 init._fixture_generator_decorator L0081 INFO | -------------------- fixture disable_voq_watchdog setup starts --------------------
05:55:31 init._fixture_generator_decorator L0085 INFO | -------------------- fixture disable_voq_watchdog setup ends --------------------
05:55:31 init._fixture_generator_decorator L0081 INFO | -------------------- fixture number_of_tx_rx_ports setup starts --------------------
05:55:31 init._fixture_generator_decorator L0085 INFO | -------------------- fixture number_of_tx_rx_ports setup ends --------------------
05:55:31 init._fixture_func_decorator L0069 INFO | -------------------- fixture snappi_api_serv_ip setup starts --------------------
05:55:31 init._fixture_func_decorator L0076 INFO | -------------------- fixture snappi_api_serv_ip setup ends --------------------
05:55:31 init._fixture_func_decorator L0069 INFO | -------------------- fixture snappi_api_serv_port setup starts --------------------
05:55:31 init._fixture_func_decorator L0076 INFO | -------------------- fixture snappi_api_serv_port setup ends --------------------
05:55:31 init._fixture_generator_decorator L0081 INFO | -------------------- fixture snappi_api setup starts --------------------
05:55:31 init._fixture_generator_decorator L0085 INFO | -------------------- fixture snappi_api setup ends --------------------
05:55:32 conftest.rand_one_dut_front_end_hostname L0683 INFO | Randomly select dut sonic-s6100-dut1 for testing
05:55:32 init._fixture_func_decorator L0069 INFO | -------------------- fixture prio_dscp_map setup starts --------------------
05:55:33 init._fixture_func_decorator L0076 INFO | -------------------- fixture prio_dscp_map setup ends --------------------
05:55:33 init._fixture_func_decorator L0069 INFO | -------------------- fixture lossless_prio_list setup starts --------------------
05:55:34 init._fixture_func_decorator L0076 INFO | -------------------- fixture lossless_prio_list setup ends --------------------
05:55:34 init._fixture_func_decorator L0069 INFO | -------------------- fixture get_snappi_ports setup starts --------------------
05:55:35 conftest.generate_port_lists L1590 INFO | Generate dut_port_map: {'sonic-s6100-dut1': ['sonic-s6100-dut1|Ethernet0', 'sonic-s6100-dut1|Ethernet8', 'sonic-s6100-dut1|Ethernet16', 'sonic-s6100-dut1|Ethernet24']}
05:55:35 conftest.generate_port_lists L1613 INFO | Generate port_list: ['sonic-s6100-dut1|Ethernet0', 'sonic-s6100-dut1|Ethernet8', 'sonic-s6100-dut1|Ethernet16', 'sonic-s6100-dut1|Ethernet24']
05:55:35 init._fixture_func_decorator L0069 INFO | -------------------- fixture get_snappi_ports_single_dut setup starts --------------------
05:55:35 init._fixture_func_decorator L0076 INFO | -------------------- fixture get_snappi_ports_single_dut setup ends --------------------
05:55:35 init._fixture_func_decorator L0076 INFO | -------------------- fixture get_snappi_ports setup ends --------------------
05:55:35 init._fixture_func_decorator L0069 INFO | -------------------- fixture snappi_port_selection setup starts --------------------
05:55:35 init._fixture_func_decorator L0076 INFO | -------------------- fixture snappi_port_selection setup ends --------------------
05:55:35 init.loganalyzer L0074 INFO | Log analyzer is disabled
05:55:35 init.memory_utilization L0125 INFO | Hostname: sonic-s6100-dut1, Hwsku: Accton-AS9716-32D, Platform: x86_64-accton_as9716_32d-r0
05:55:35 memory_utilization.register_command L0023 INFO | Registering command: name=monit, cmd=sudo monit status, memory_params={'memory_usage': {'memory_increase_threshold': {'type': 'value', 'value': 10}, 'memory_high_threshold': {'type': 'value', 'value': 70}}}, memory_check=<function parse_monit_status_output at 0x7f700b76d3a0>
05:55:35 memory_utilization.register_command L0023 INFO | Registering command: name=top, cmd=top -b -n 1, memory_params={'bgpd': {'memory_increase_threshold': {'type': 'value', 'value': 128}, 'memory_high_threshold': None}, 'zebra': {'memory_increase_threshold': {'type': 'value', 'value': 64}, 'memory_high_threshold': None}}, memory_check=<function parse_top_output at 0x7f700b762d30>
05:55:35 memory_utilization.register_command L0023 INFO | Registering command: name=free, cmd=free -m, memory_params={'used': {'memory_increase_threshold': {'type': 'percentage', 'value': '20%'}, 'memory_high_threshold': None}}, memory_check=<function parse_free_output at 0x7f700b76d310>
05:55:35 memory_utilization.register_command L0023 INFO | Registering command: name=docker, cmd=docker stats --no-stream, memory_params={'snmp': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 4}}, 'pmon': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 8}}, 'lldp': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 4}}, 'gnmi': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 6}}, 'radv': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 3}}, 'syncd': {'memory_increase_threshold': {'type': 'value', 'value': 5}, 'memory_high_threshold': {'type': 'value', 'value': 18}}, 'bgp': {'memory_increase_threshold': {'type': 'value', 'value': 4}, 'memory_high_threshold': {'type': 'value', 'value': 14}}, 'teamd': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 5}}, 'swss': {'memory_increase_threshold': {'type': 'value', 'value': 3}, 'memory_high_threshold': {'type': 'value', 'value': 8}}, 'database': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 6}}}, memory_check=<function parse_docker_stats_output at 0x7f700b76d430>
05:55:35 memory_utilization.register_command L0023 INFO | Registering command: name=frr_bgp, cmd=vtysh -c "show memory bgp", memory_params={'used': {'memory_increase_threshold': [{'type': 'percentage', 'value': '50%'}, {'type': 'value', 'value': 32}], 'memory_high_threshold': {'type': 'value', 'value': 128}}}, memory_check=<function parse_frr_memory_output at 0x7f700b76d4c0>
05:55:35 memory_utilization.register_command L0023 INFO | Registering command: name=frr_zebra, cmd=vtysh -c "show memory zebra", memory_params={'used': {'memory_increase_threshold': [{'type': 'percentage', 'value': '50%'}, {'type': 'value', 'value': 16}], 'memory_high_threshold': {'type': 'value', 'value': 128}}}, memory_check=<function parse_frr_memory_output at 0x7f700b76d4c0>
05:55:35 init._fixture_generator_decorator L0081 INFO | -------------------- fixture disable_pfcwd setup starts --------------------
05:55:36 init._fixture_generator_decorator L0085 INFO | -------------------- fixture disable_pfcwd setup ends --------------------
05:55:36 init._fixture_func_decorator L0069 INFO | -------------------- fixture tgen_port_info setup starts --------------------
05:55:37 init._fixture_func_decorator L0076 INFO | -------------------- fixture tgen_port_info setup ends --------------------
05:55:43 init.pytest_runtest_setup L0061 INFO | Before test: collected memory_values {'before_test': {'sonic-s6100-dut1': {'monit': {'memory_usage': 21.1}, 'top': {'zebra': 63.046875, 'bgpd': 43.9921875}, 'free': {'used': 3003}, 'docker': {'snmp': '0.42', 'lldp': '0.37', 'radv': '0.24', 'syncd': '3.86', 'teamd': '0.28', 'bgp': '1.03', 'swss': '0.47', 'pmon': '0.77', 'database': '0.93'}, 'frr_bgp': {'used': 7.38671875}, 'frr_zebra': {'used': 4.810546875}}}, 'after_test': {'sonic-s6100-dut1': {}}}
-------------------------------------------------------------------------------------------------- live log call --------------------------------------------------------------------------------------------------
05:55:44 connection._warn L0336 WARNING| Verification of certificates is disabled
05:55:44 connection._info L0333 INFO | Determining the platform and rest_port using the 10.36.79.8 address...
05:55:44 connection._warn L0336 WARNING| Unable to connect to http://10.36.79.8:443.
05:55:44 connection._info L0333 INFO | Connection established to https://10.36.79.8:443 on linux
05:55:59 connection._info L0333 INFO | Using IxNetwork api server version 10.25.2406.6
05:55:59 connection._info L0333 INFO | User info IxNetwork/ixnetworkweb/admin-196-3483
05:56:00 snappi_api.info L1488 INFO | snappi-1.31.1
05:56:00 snappi_api.info L1488 INFO | snappi_ixnetwork-1.31.2
05:56:00 snappi_api.info L1488 INFO | ixnetwork_restpy-1.7.0
05:56:00 snappi_api.info L1488 INFO | Config validation 0.020s
05:56:04 snappi_api.info L1488 INFO | Ports configuration 3.561s
05:56:04 snappi_api.info L1488 INFO | Captures configuration 0.149s
05:56:07 snappi_api.info L1488 INFO | Add location hosts [10.36.79.8] 2.242s
05:56:09 snappi_api.info L1488 INFO | Location hosts ready [10.36.79.8] 2.206s
05:56:10 snappi_api.info L1488 INFO | Speed conversion is not require for (port.name, speed) : [('Port 0', 'aresOneOneByFourHundredGigNonFanOut'), ('Port 1', 'aresOneOneByFourHundredGigNonFanOut'), ('Port 2', 'aresOneOneByFourHundredGigNonFanOut'), ('Port 3', 'aresOneOneByFourHundredGigNonFanOut')]
05:56:10 snappi_api.info L1488 INFO | Aggregation mode speed change 0.289s
05:56:11 snappi_api.info L1488 INFO | Location preemption [10.36.79.8/2, 10.36.79.8/1, 10.36.79.8/3, 10.36.79.8/4] 0.173s
05:56:25 snappi_api.info L1488 INFO | Location connect [Port 0, Port 1, Port 2, Port 3] 14.368s
05:56:25 snappi_api.info L1488 INFO | Location state check [Port 0, Port 1, Port 2, Port 3] 0.235s
05:56:25 snappi_api.info L1488 INFO | Location configuration 20.813s
05:56:32 snappi_api.info L1488 INFO | Layer1 configuration 6.379s
05:56:32 snappi_api.info L1488 INFO | Lag Configuration 0.086s
05:56:32 snappi_api.info L1488 INFO | Convert device config : 0.231s
05:56:32 snappi_api.info L1488 INFO | Create IxNetwork device config : 0.000s
05:56:33 snappi_api.info L1488 INFO | Push IxNetwork device config : 0.789s
05:56:33 snappi_api.info L1488 INFO | Devices configuration 1.089s
05:56:40 snappi_api.info L1488 INFO | Flows configuration 7.007s
05:56:46 snappi_api.info L1488 INFO | Start interfaces 5.904s
05:56:47 snappi_api.info L1488 INFO | IxNet - The Traffic Item was modified. Please perform a Traffic Generate to update the associated traffic Flow Groups
05:56:47 traffic_generation.run_traffic L0433 INFO | Wait for Arp to Resolve ...
05:56:53 traffic_generation.run_traffic L0456 INFO | Starting transmit on all flows ...
05:56:58 snappi_api.info L1488 INFO | Flows generate/apply 3.634s
05:57:14 snappi_api.info L1488 INFO | Flows clear statistics 16.775s
05:57:14 snappi_api.info L1488 INFO | Captures start 0.000s
05:57:16 snappi_api.info L1488 INFO | Flows start 1.651s
05:57:17 traffic_generation.run_traffic L0472 INFO | Polling DUT for traffic statistics for 15 seconds ...
05:57:23 traffic_generation.run_traffic L0491 INFO | Polling TGEN for in-flight traffic statistics...
05:57:24 traffic_generation.run_traffic L0496 INFO | In-flight traffic statistics for flows: ['Test Flow 1 -> 0 Rate:20', 'Test Flow 2 -> 0 Rate:10', '1 Background Flow 1 -> 0 Rate:20', '2 Background Flow 2 -> 0 Rate:20', '3 Background Flow 1 -> 0 Rate:20', '4 Background Flow 2 -> 0 Rate:20']
05:57:24 traffic_generation.run_traffic L0497 INFO | In-flight TX frames: [0, 0, 0, 0, 0, 0]
05:57:24 traffic_generation.run_traffic L0498 INFO | In-flight RX frames: [0, 0, 0, 0, 0, 0]
05:57:28 traffic_generation.run_traffic L0499 INFO | DUT polling complete
05:57:28 traffic_generation.run_traffic L0510 INFO | Checking if all flows have stopped. Attempt #1
05:57:31 traffic_generation.run_traffic L0510 INFO | Checking if all flows have stopped. Attempt #2
05:57:33 traffic_generation.run_traffic L0510 INFO | Checking if all flows have stopped. Attempt #3
05:57:35 traffic_generation.run_traffic L0517 INFO | All test and background traffic flows stopped
05:57:37 traffic_generation.run_traffic L0540 INFO | Dumping per-flow statistics
05:57:38 traffic_generation.run_traffic L0542 INFO | Stopping transmit on all remaining flows

PDB set_trace (IO-capturing turned off) >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
/var/AzDevOps/sonic-mgmt/tests/snappi_tests/pfc/files/m2o_fluctuating_lossless_helper.py(158)run_m2o_fluctuating_lossless_test()
-> pytest_assert(abs(drop_percentage - 8) < 1, 'FAIL: Drop packets must be around 8 percent')
(Pdb) c

PDB continue (IO-capturing resumed) >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
PASSED [100%]
------------------------------------------------------------------------------------------------ live log teardown ------------------------------------------------------------------------------------------------
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for monit:memory_usage: 10.0
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for top:bgpd: 128.0
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for top:zebra: 64.0
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for free:used: 600.6
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:snmp: 2.0
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:pmon: 2.0
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:lldp: 2.0
06:00:08 memory_utilization.check_memory_threshol L0066 WARNING| Skipping memory check for docker-gnmi due to zero value
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:radv: 2.0
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:syncd: 5.0
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:bgp: 4.0
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:teamd: 2.0
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:swss: 3.0
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:database: 2.0
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for frr_bgp:used: 3.693359375
06:00:08 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for frr_zebra:used: 2.4052734375
06:00:08 init.pytest_runtest_teardown L0106 INFO | After test: collected memory_values {'before_test': {'sonic-s6100-dut1': {'monit': {'memory_usage': 21.1}, 'top': {'zebra': 63.046875, 'bgpd': 43.9921875}, 'free': {'used': 3003}, 'docker': {'snmp': '0.42', 'lldp': '0.37', 'radv': '0.24', 'syncd': '3.86', 'teamd': '0.28', 'bgp': '1.03', 'swss': '0.47', 'pmon': '0.77', 'database': '0.93'}, 'frr_bgp': {'used': 7.38671875}, 'frr_zebra': {'used': 4.810546875}}}, 'after_test': {'sonic-s6100-dut1': {'monit': {'memory_usage': 21.2}, 'top': {'zebra': 63.046875, 'bgpd': 43.9921875}, 'free': {'used': 2978}, 'docker': {'snmp': '0.42', 'lldp': '0.37', 'radv': '0.24', 'syncd': '3.86', 'teamd': '0.28', 'bgp': '1.03', 'swss': '0.47', 'pmon': '0.77', 'database': '0.93'}, 'frr_bgp': {'used': 7.38671875}, 'frr_zebra': {'used': 4.8115234375}}}}
06:00:08 init._fixture_generator_decorator L0093 INFO | -------------------- fixture disable_pfcwd teardown starts --------------------
06:00:10 init._fixture_generator_decorator L0102 INFO | -------------------- fixture disable_pfcwd teardown ends --------------------
06:00:10 init._fixture_generator_decorator L0093 INFO | -------------------- fixture snappi_api teardown starts --------------------
06:00:29 init._fixture_generator_decorator L0102 INFO | -------------------- fixture snappi_api teardown ends --------------------
06:00:29 init._fixture_generator_decorator L0093 INFO | -------------------- fixture number_of_tx_rx_ports teardown starts --------------------
06:00:29 init._fixture_generator_decorator L0102 INFO | -------------------- fixture number_of_tx_rx_ports teardown ends --------------------
06:00:29 init._fixture_generator_decorator L0093 INFO | -------------------- fixture disable_voq_watchdog teardown starts --------------------
06:00:29 init._fixture_generator_decorator L0102 INFO | -------------------- fixture disable_voq_watchdog teardown ends --------------------
06:00:29 init._fixture_generator_decorator L0093 INFO | -------------------- fixture start_pfcwd_after_test teardown starts --------------------
06:00:30 init._fixture_generator_decorator L0102 INFO | -------------------- fixture start_pfcwd_after_test teardown ends --------------------
06:00:30 init._fixture_generator_decorator L0093 INFO | -------------------- fixture rand_lossy_prio teardown starts --------------------
06:00:30 init._fixture_generator_decorator L0102 INFO | -------------------- fixture rand_lossy_prio teardown ends --------------------
06:00:30 init._fixture_generator_decorator L0093 INFO | -------------------- fixture rand_lossless_prio teardown starts --------------------
06:00:30 init._fixture_generator_decorator L0102 INFO | -------------------- fixture rand_lossless_prio teardown ends --------------------
06:00:30 init._fixture_generator_decorator L0093 INFO | -------------------- fixture enable_packet_aging_after_test teardown starts --------------------
06:00:30 init._fixture_generator_decorator L0102 INFO | -------------------- fixture enable_packet_aging_after_test teardown ends --------------------
06:00:30 conftest.temporarily_disable_route_check L2976 INFO | Skipping temporarily_disable_route_check fixture
06:00:30 conftest.collect_after_test L2763 INFO | Dumping Disk and Memory Space information after test on sonic-s6100-dut1
06:00:31 conftest.collect_after_test L2767 INFO | Collecting core dumps after test on sonic-s6100-dut1
06:00:32 conftest.collect_after_test L2778 INFO | Collecting running config after test on sonic-s6100-dut1
06:00:32 conftest.core_dump_and_config_check L2919 WARNING| Core dump or config check failed for test_m2o_fluctuating_lossless.py, results: {"core_dump_check": {"failed": false, "new_core_dumps": {"sonic-s6100-dut1": []}}, "config_db_check": {"failed": true, "pre_only_config": {"sonic-s6100-dut1": {"null": {}}}, "cur_only_config": {"sonic-s6100-dut1": {"null": {}}}, "inconsistent_config": {"sonic-s6100-dut1": {"null": {"PFC_WD": {"pre_value": {"Ethernet0": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "Ethernet16": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "Ethernet24": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "Ethernet8": {"action": "drop", "detection_time": "200", "restoration_time": "200"}}, "cur_value": {"Ethernet0": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "Ethernet16": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "Ethernet24": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "Ethernet8": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "GLOBAL": {"POLL_INTERVAL": "200"}}}}}}}}
06:00:32 conftest.restore_config_db_and_config_re L2597 INFO | dut reload called on sonic-s6100-dut1
06:00:33 config_reload.config_reload L0148 INFO | reloading config_db
06:01:49 processes_utils.wait_critical_processes L0076 INFO | Wait until all critical processes are healthy in 300 sec
06:01:49 processes_utils.all_critical_processes L0045 INFO | Check critical processes status
06:01:58 sonic_asic.check_bgp_session_state L0714 INFO | bgp neighbors that match the state: [] on namespace None
06:01:58 sonic_asic.check_bgp_session_state L0715 INFO | bgp neighbors to be checked on the state: [] on namespace None

================================================================================================ warnings summary =================================================================================================
../../../../usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755
/usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755: PytestAssertRewriteWarning: Module already imported so cannot be rewritten: tests.common.plugins.ptfadapter
self.import_plugin(import_spec)

../../../../usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755
/usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755: PytestAssertRewriteWarning: Module already imported so cannot be rewritten: tests.common.plugins.loganalyzer
self.import_plugin(import_spec)

../../../../usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755
/usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755: PytestAssertRewriteWarning: Module already imported so cannot be rewritten: tests.common.plugins.sanity_check
self.import_plugin(import_spec)

../../../../usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755
/usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755: PytestAssertRewriteWarning: Module already imported so cannot be rewritten: tests.common.plugins.test_completeness
self.import_plugin(import_spec)

../../../../usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755
/usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755: PytestAssertRewriteWarning: Module already imported so cannot be rewritten: tests.common.dualtor
self.import_plugin(import_spec)

../../../../usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755
/usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755: PytestAssertRewriteWarning: Module already imported so cannot be rewritten: tests.common.fixtures.duthost_utils
self.import_plugin(import_spec)

../../../../usr/local/lib/python3.8/dist-packages/paramiko/transport.py:236
/usr/local/lib/python3.8/dist-packages/paramiko/transport.py:236: CryptographyDeprecationWarning: Blowfish has been deprecated
"class": algorithms.Blowfish,

../../../../usr/local/lib/python3.8/dist-packages/scapy/layers/ipsec.py:471
/usr/local/lib/python3.8/dist-packages/scapy/layers/ipsec.py:471: CryptographyDeprecationWarning: Blowfish has been deprecated
cipher=algorithms.Blowfish,

../../../../usr/local/lib/python3.8/dist-packages/scapy/layers/ipsec.py:485
/usr/local/lib/python3.8/dist-packages/scapy/layers/ipsec.py:485: CryptographyDeprecationWarning: CAST5 has been deprecated
cipher=algorithms.CAST5,

snappi_tests/pfc/test_m2o_fluctuating_lossless.py::test_m2o_fluctuating_lossless[tgen_port_info0]
snappi_tests/pfc/test_m2o_fluctuating_lossless.py::test_m2o_fluctuating_lossless[tgen_port_info0]
snappi_tests/pfc/test_m2o_fluctuating_lossless.py::test_m2o_fluctuating_lossless[tgen_port_info0]
snappi_tests/pfc/test_m2o_fluctuating_lossless.py::test_m2o_fluctuating_lossless[tgen_port_info0]
snappi_tests/pfc/test_m2o_fluctuating_lossless.py::test_m2o_fluctuating_lossless[tgen_port_info0]
snappi_tests/pfc/test_m2o_fluctuating_lossless.py::test_m2o_fluctuating_lossless[tgen_port_info0]
/usr/local/lib/python3.8/dist-packages/pytest_ansible/module_dispatcher/v213.py:100: UserWarning: provided hosts list is empty, only localhost is available
warnings.warn("provided hosts list is empty, only localhost is available")

-- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html
--------------------------------------------------------------------------------------------- live log sessionfinish ----------------------------------------------------------------------------------------------
06:01:58 init.pytest_terminal_summary L0067 INFO | Can not get Allure report URL. Please check logs
=================================================================================== 1 passed, 15 warnings in 394.89s (0:06:34) ====================================================================================
INFO:root:Can not get Allure report URL. Please check logs
AzDevOps@e1a1448fa9a4:~/sonic-mgmt/tests$

---------------------------- live log sessionstart -----------------------------
19:20:29 conftest.pytest_sessionstart L0470 INFO | Invoking /var/AzDevOps/sonic-mgmt/tests/build-gnmi-stubs.sh with base directory: /var/AzDevOps/sonic-mgmt/tests
19:20:30 conftest.pytest_sessionstart L0480 INFO | Output of /var/AzDevOps/sonic-mgmt/tests/build-gnmi-stubs.sh:
Removing existing directory: /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/github.com/openconfig
Creating directory: /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/github.com/openconfig
Removing existing directory: /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/generated
Creating directory: /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/generated
Cloning https://github.com/openconfig/gnmi.git into /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/github.com/openconfig
Generating gRPC stubs...
gRPC stubs generated successfully.
Creating empty init.py files under /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/generated/github/
Moving generated files to correct locations...
Files moved successfully.
Removing /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/generated/github.com directory...
/var/AzDevOps/sonic-mgmt/tests/common/sai_validation/generated/github.com directory removed successfully.

19:20:30 conftest.pytest_sessionstart L0491 INFO | Added /var/AzDevOps/sonic-mgmt/tests/common/sai_validation/generated to sys.path
============================= test session starts ==============================
platform linux -- Python 3.8.10, pytest-7.4.0, pluggy-1.3.0
ansible: 2.13.13
rootdir: /var/AzDevOps/sonic-mgmt/tests
configfile: pytest.ini
plugins: allure-pytest-2.8.22, ansible-4.0.0, forked-1.6.0, html-4.1.0, metadata-3.0.0, repeat-0.9.3, xdist-1.28.0
----------------------------- live log collection ------------------------------
19:20:31 init.pytest_collection_modifyitems L0640 INFO | Available basic facts that can be used in conditional skip:
{
"topo_type": "ptf",
"topo_name": "ptf64",
"testbed": "vms-snappi-sonic",
"asic_subtype": "broadcom",
"asic_type": "broadcom",
"branch": "202411",
"build_date": "Mon May 19 14:06:06 UTC 2025",
"build_number": 854573,
"build_version": "202411.854573-78d363b4a",
"built_by": "azureuser@d3d5b5e6c000000",
"commit_id": "78d363b4a",
"debian_version": "12.9",
"feature_status": {
"bgp": "enabled",
"database": "always_enabled",
"dhcp_relay": "disabled",
"eventd": "enabled",
"gnmi": "enabled",
"iccpd": "enabled",
"lldp": "enabled",
"macsec": "disabled",
"mgmt-framework": "enabled",
"mux": "always_disabled",
"nat": "disabled",
"pmon": "enabled",
"radv": "enabled",
"sflow": "disabled",
"snmp": "enabled",
"stp": "enabled",
"swss": "enabled",
"syncd": "enabled",
"teamd": "enabled",
"telemetry": "enabled"
},
"hwsku": "Accton-AS9716-32D",
"is_chassis": false,
"is_multi_asic": false,
"is_supervisor": false,
"kernel_version": "6.1.0",
"libswsscommon": "1.0.0",
"num_asic": 1,
"platform": "x86_64-accton_as9716_32d-r0",
"release": "202411",
"secure_boot_image": "no",
"sonic_os_version": 12,
"sonic_utilities": 1.2,
"asic_gen": "unknown",
"minigraph_interfaces": [
{
"addr": "21.1.1.1",
"attachto": "Ethernet0",
"mask": "255.255.255.0",
"peer_addr": "21.1.1.2",
"prefixlen": 24,
"subnet": "21.1.1.0/24"
},
{
"addr": "23.1.1.1",
"attachto": "Ethernet16",
"mask": "255.255.255.0",
"peer_addr": "23.1.1.2",
"prefixlen": 24,
"subnet": "23.1.1.0/24"
},
{
"addr": "24.1.1.1",
"attachto": "Ethernet24",
"mask": "255.255.255.0",
"peer_addr": "24.1.1.2",
"prefixlen": 24,
"subnet": "24.1.1.0/24"
},
{
"addr": "22.1.1.1",
"attachto": "Ethernet8",
"mask": "255.255.255.0",
"peer_addr": "22.1.1.2",
"prefixlen": 24,
"subnet": "22.1.1.0/24"
}
],
"minigraph_portchannels": {},
"minigraph_portchannel_interfaces": [],
"minigraph_neighbors": {
"Ethernet0": {
"name": "IXIA-Chassis1",
"namespace": "",
"port": "Port0"
},
"Ethernet16": {
"name": "IXIA-Chassis1",
"namespace": "",
"port": "Port2"
},
"Ethernet24": {
"name": "IXIA-Chassis1",
"namespace": "",
"port": "Port3"
},
"Ethernet8": {
"name": "IXIA-Chassis1",
"namespace": "",
"port": "Port1"
}
},
"VOQ_INBAND_INTERFACE": {},
"BGP_VOQ_CHASSIS_NEIGHBOR": {},
"INTERFACE": {
"Ethernet0": {
"2001::1/64": {},
"21.1.1.1/24": {}
},
"Ethernet16": {
"2003::1/64": {},
"23.1.1.1/24": {}
},
"Ethernet24": {
"2004::1/64": {},
"24.1.1.1/24": {}
},
"Ethernet8": {
"2002::1/64": {},
"22.1.1.1/24": {}
}
},
"switch_type": "",
"switch": {
"ACL_ACTION|PACKET_ACTION": "COPY,DROP,FORWARD",
"ASIC_SDK_HEALTH_EVENT": "false",
"ECMP_HASH_ALGORITHM": "CRC,XOR,CRC_32LO,CRC_32HI,CRC_CCITT,CRC_XOR",
"ECMP_HASH_ALGORITHM_CAPABLE": "true",
"ECMP_HASH_CAPABLE": "false",
"HASH|NATIVE_HASH_FIELD_LIST": "SRC_IP,DST_IP,VLAN_ID,IP_PROTOCOL,ETHERTYPE,L4_SRC_PORT,L4_DST_PORT,SRC_MAC,DST_MAC,IN_PORT",
"LAG_HASH_ALGORITHM": "CRC,XOR,CRC_32LO,CRC_32HI,CRC_CCITT,CRC_XOR",
"LAG_HASH_ALGORITHM_CAPABLE": "true",
"LAG_HASH_CAPABLE": "false",
"LAG_TPID_CAPABLE": "false",
"MAX_NEXTHOP_GROUP_COUNT": "4096",
"MIRROR": "true",
"MIRRORV6": "true",
"ORDERED_ECMP_CAPABLE": "true",
"PATH_TRACING_CAPABLE": "false",
"PFC_DLR_INIT_CAPABLE": "true",
"PORT_EGRESS_SAMPLE_CAPABLE": "false",
"PORT_TPID_CAPABLE": "true",
"REG_FATAL_ASIC_SDK_HEALTH_CATEGORY": "false",
"REG_NOTICE_ASIC_SDK_HEALTH_CATEGORY": "false",
"REG_WARNING_ASIC_SDK_HEALTH_CATEGORY": "false"
},
"macsec_en": false
}
collected 1 item

snappi_tests/pfc/test_m2o_oversubscribe_lossy.py::test_m2o_oversubscribe_lossy[400.0-single_linecard_single_asic]
-------------------------------- live log setup --------------------------------
19:20:31 init.set_default L0053 INFO | Completeness level not set during test execution. Setting to default level: CompletenessLevel.basic
19:20:31 init.check_test_completeness L0151 INFO | Test has no defined levels. Continue without test completeness checks
19:20:31 conftest.enhance_inventory L0313 INFO | Inventory file: ['../ansible/snappi-sonic']
19:20:34 ptfhost_utils.run_icmp_responder_session L0310 INFO | Skip running icmp_responder at session level, it is only for dualtor testbed with active-active mux ports.
19:20:34 init._sanity_check L0431 INFO | Skip sanity check according to command line argument
19:20:34 conftest.collect_before_test L2695 INFO | Dumping Disk and Memory Space information before test on sonic-s6100-dut1
19:20:35 conftest.collect_before_test L2699 INFO | Collecting core dumps before test on sonic-s6100-dut1
19:20:36 conftest.collect_before_test L2708 INFO | Collecting running config before test on sonic-s6100-dut1
19:20:38 conftest.temporarily_disable_route_check L2974 INFO | Skipping temporarily_disable_route_check fixture
19:20:38 conftest.generate_params_dut_hostname L1498 INFO | Using DUTs ['sonic-s6100-dut1'] in testbed 'vms-snappi-sonic'
19:20:38 conftest.set_rand_one_dut_hostname L0647 INFO | Randomly select dut sonic-s6100-dut1 for testing
19:20:38 init._fixture_generator_decorator L0081 INFO | -------------------- fixture enable_packet_aging_after_test setup starts --------------------
19:20:38 init._fixture_generator_decorator L0085 INFO | -------------------- fixture enable_packet_aging_after_test setup ends --------------------
19:20:38 init._fixture_generator_decorator L0081 INFO | -------------------- fixture rand_lossless_prio setup starts --------------------
19:20:38 init._fixture_generator_decorator L0085 INFO | -------------------- fixture rand_lossless_prio setup ends --------------------
19:20:38 init._fixture_generator_decorator L0081 INFO | -------------------- fixture rand_lossy_prio setup starts --------------------
19:20:38 init._fixture_generator_decorator L0085 INFO | -------------------- fixture rand_lossy_prio setup ends --------------------
19:20:38 init._fixture_generator_decorator L0081 INFO | -------------------- fixture start_pfcwd_after_test setup starts --------------------
19:20:38 init._fixture_generator_decorator L0085 INFO | -------------------- fixture start_pfcwd_after_test setup ends --------------------
19:20:38 init._fixture_generator_decorator L0081 INFO | -------------------- fixture disable_voq_watchdog setup starts --------------------
19:20:38 init._fixture_generator_decorator L0085 INFO | -------------------- fixture disable_voq_watchdog setup ends --------------------
19:20:38 init._fixture_generator_decorator L0081 INFO | -------------------- fixture number_of_tx_rx_ports setup starts --------------------
19:20:38 init._fixture_generator_decorator L0085 INFO | -------------------- fixture number_of_tx_rx_ports setup ends --------------------
19:20:38 init._fixture_func_decorator L0069 INFO | -------------------- fixture snappi_api_serv_ip setup starts --------------------
19:20:38 init._fixture_func_decorator L0076 INFO | -------------------- fixture snappi_api_serv_ip setup ends --------------------
19:20:38 init._fixture_func_decorator L0069 INFO | -------------------- fixture snappi_api_serv_port setup starts --------------------
19:20:38 init._fixture_func_decorator L0076 INFO | -------------------- fixture snappi_api_serv_port setup ends --------------------
19:20:38 init._fixture_generator_decorator L0081 INFO | -------------------- fixture snappi_api setup starts --------------------
19:20:38 init._fixture_generator_decorator L0085 INFO | -------------------- fixture snappi_api setup ends --------------------
19:20:39 conftest.rand_one_dut_front_end_hostname L0683 INFO | Randomly select dut sonic-s6100-dut1 for testing
19:20:39 init._fixture_func_decorator L0069 INFO | -------------------- fixture prio_dscp_map setup starts --------------------
19:20:40 init._fixture_func_decorator L0076 INFO | -------------------- fixture prio_dscp_map setup ends --------------------
19:20:40 init._fixture_func_decorator L0069 INFO | -------------------- fixture lossless_prio_list setup starts --------------------
19:20:41 init._fixture_func_decorator L0076 INFO | -------------------- fixture lossless_prio_list setup ends --------------------
19:20:41 init._fixture_func_decorator L0069 INFO | -------------------- fixture get_snappi_ports setup starts --------------------
19:20:41 conftest.generate_port_lists L1590 INFO | Generate dut_port_map: {'sonic-s6100-dut1': ['sonic-s6100-dut1|Ethernet0', 'sonic-s6100-dut1|Ethernet8', 'sonic-s6100-dut1|Ethernet16', 'sonic-s6100-dut1|Ethernet24']}
19:20:41 conftest.generate_port_lists L1613 INFO | Generate port_list: ['sonic-s6100-dut1|Ethernet0', 'sonic-s6100-dut1|Ethernet8', 'sonic-s6100-dut1|Ethernet16', 'sonic-s6100-dut1|Ethernet24']
19:20:41 init._fixture_func_decorator L0069 INFO | -------------------- fixture get_snappi_ports_single_dut setup starts --------------------
19:20:41 init._fixture_func_decorator L0076 INFO | -------------------- fixture get_snappi_ports_single_dut setup ends --------------------
19:20:41 init._fixture_func_decorator L0076 INFO | -------------------- fixture get_snappi_ports setup ends --------------------
19:20:41 init._fixture_func_decorator L0069 INFO | -------------------- fixture snappi_port_selection setup starts --------------------
19:20:41 init._fixture_func_decorator L0076 INFO | -------------------- fixture snappi_port_selection setup ends --------------------
19:20:41 init.loganalyzer L0074 INFO | Log analyzer is disabled
19:20:41 init.memory_utilization L0125 INFO | Hostname: sonic-s6100-dut1, Hwsku: Accton-AS9716-32D, Platform: x86_64-accton_as9716_32d-r0
19:20:41 memory_utilization.register_command L0023 INFO | Registering command: name=monit, cmd=sudo monit status, memory_params={'memory_usage': {'memory_increase_threshold': {'type': 'value', 'value': 10}, 'memory_high_threshold': {'type': 'value', 'value': 70}}}, memory_check=<function parse_monit_status_output at 0x7f15db2940d0>
19:20:41 memory_utilization.register_command L0023 INFO | Registering command: name=top, cmd=top -b -n 1, memory_params={'bgpd': {'memory_increase_threshold': {'type': 'value', 'value': 128}, 'memory_high_threshold': None}, 'zebra': {'memory_increase_threshold': {'type': 'value', 'value': 64}, 'memory_high_threshold': None}}, memory_check=<function parse_top_output at 0x7f15db288a60>
19:20:41 memory_utilization.register_command L0023 INFO | Registering command: name=free, cmd=free -m, memory_params={'used': {'memory_increase_threshold': {'type': 'percentage', 'value': '20%'}, 'memory_high_threshold': None}}, memory_check=<function parse_free_output at 0x7f15db294040>
19:20:41 memory_utilization.register_command L0023 INFO | Registering command: name=docker, cmd=docker stats --no-stream, memory_params={'snmp': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 4}}, 'pmon': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 8}}, 'lldp': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 4}}, 'gnmi': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 6}}, 'radv': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 3}}, 'syncd': {'memory_increase_threshold': {'type': 'value', 'value': 5}, 'memory_high_threshold': {'type': 'value', 'value': 18}}, 'bgp': {'memory_increase_threshold': {'type': 'value', 'value': 4}, 'memory_high_threshold': {'type': 'value', 'value': 14}}, 'teamd': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 5}}, 'swss': {'memory_increase_threshold': {'type': 'value', 'value': 3}, 'memory_high_threshold': {'type': 'value', 'value': 8}}, 'database': {'memory_increase_threshold': {'type': 'value', 'value': 2}, 'memory_high_threshold': {'type': 'value', 'value': 6}}}, memory_check=<function parse_docker_stats_output at 0x7f15db294160>
19:20:41 memory_utilization.register_command L0023 INFO | Registering command: name=frr_bgp, cmd=vtysh -c "show memory bgp", memory_params={'used': {'memory_increase_threshold': [{'type': 'percentage', 'value': '50%'}, {'type': 'value', 'value': 32}], 'memory_high_threshold': {'type': 'value', 'value': 128}}}, memory_check=<function parse_frr_memory_output at 0x7f15db2941f0>
19:20:41 memory_utilization.register_command L0023 INFO | Registering command: name=frr_zebra, cmd=vtysh -c "show memory zebra", memory_params={'used': {'memory_increase_threshold': [{'type': 'percentage', 'value': '50%'}, {'type': 'value', 'value': 16}], 'memory_high_threshold': {'type': 'value', 'value': 128}}}, memory_check=<function parse_frr_memory_output at 0x7f15db2941f0>
19:20:41 init._fixture_generator_decorator L0081 INFO | -------------------- fixture disable_pfcwd setup starts --------------------
19:20:42 init._fixture_generator_decorator L0085 INFO | -------------------- fixture disable_pfcwd setup ends --------------------
19:20:42 init._fixture_func_decorator L0069 INFO | -------------------- fixture tgen_port_info setup starts --------------------
19:20:43 init._fixture_func_decorator L0076 INFO | -------------------- fixture tgen_port_info setup ends --------------------
19:20:49 init.pytest_runtest_setup L0061 INFO | Before test: collected memory_values {'before_test': {'sonic-s6100-dut1': {'monit': {'memory_usage': 19.9}, 'top': {'zebra': 19.96484375, 'bgpd': 21.62109375}, 'free': {'used': 2811}, 'docker': {'snmp': '0.42', 'lldp': '0.38', 'radv': '0.24', 'syncd': '3.88', 'teamd': '0.28', 'bgp': '0.54', 'swss': '0.48', 'pmon': '0.78', 'database': '0.75'}, 'frr_bgp': {'used': 7.3876953125}, 'frr_zebra': {'used': 4.810546875}}}, 'after_test': {'sonic-s6100-dut1': {}}}
-------------------------------- live log call ---------------------------------
19:20:50 connection._warn L0336 WARNING| Verification of certificates is disabled
19:20:50 connection._info L0333 INFO | Determining the platform and rest_port using the 10.36.79.8 address...
19:20:50 connection._warn L0336 WARNING| Unable to connect to http://10.36.79.8:443.
19:20:50 connection._info L0333 INFO | Connection established to https://10.36.79.8:443 on linux
19:21:04 connection._info L0333 INFO | Using IxNetwork api server version 10.25.2406.6
19:21:04 connection._info L0333 INFO | User info IxNetwork/ixnetworkweb/admin-175-20369
19:21:04 snappi_api.info L1488 INFO | snappi-1.31.1
19:21:04 snappi_api.info L1488 INFO | snappi_ixnetwork-1.31.2
19:21:04 snappi_api.info L1488 INFO | ixnetwork_restpy-1.7.0
19:21:06 snappi_api.info L1488 INFO | Config validation 0.017s
19:21:08 snappi_api.info L1488 INFO | Ports configuration 1.507s
19:21:08 snappi_api.info L1488 INFO | Captures configuration 0.157s
19:21:10 snappi_api.info L1488 INFO | Add location hosts [10.36.79.8] 2.217s
19:21:13 snappi_api.info L1488 INFO | Location hosts ready [10.36.79.8] 2.215s
19:21:13 snappi_api.info L1488 INFO | Speed conversion is not require for (port.name, speed) : [('Port 0', 'aresOneOneByFourHundredGigNonFanOut'), ('Port 1', 'aresOneOneByFourHundredGigNonFanOut'), ('Port 2', 'aresOneOneByFourHundredGigNonFanOut')]
19:21:13 snappi_api.info L1488 INFO | Aggregation mode speed change 0.285s
19:21:14 snappi_api.info L1488 INFO | Location preemption [10.36.79.8/1, 10.36.79.8/2, 10.36.79.8/3] 0.137s
19:21:30 snappi_api.info L1488 INFO | Location connect [Port 0, Port 1, Port 2] 16.526s
19:21:30 snappi_api.info L1488 INFO | Location state check [Port 0, Port 1, Port 2] 0.226s
19:21:30 snappi_api.info L1488 INFO | Location configuration 22.583s
19:21:39 snappi_api.info L1488 INFO | Layer1 configuration 8.104s
19:21:39 snappi_api.info L1488 INFO | Lag Configuration 0.075s
19:21:39 snappi_api.info L1488 INFO | Convert device config : 0.196s
19:21:39 snappi_api.info L1488 INFO | Create IxNetwork device config : 0.000s
19:21:40 snappi_api.info L1488 INFO | Push IxNetwork device config : 0.686s
19:21:40 snappi_api.info L1488 INFO | Devices configuration 0.949s
19:21:45 snappi_api.info L1488 INFO | Flows configuration 5.387s
19:21:56 snappi_api.info L1488 INFO | Start interfaces 10.477s
19:21:57 snappi_api.info L1488 INFO | IxNet - The Traffic Item was modified. Please perform a Traffic Generate to update the associated traffic Flow Groups
19:21:57 traffic_generation.run_traffic L0433 INFO | Wait for Arp to Resolve ...
19:22:03 traffic_generation.run_traffic L0456 INFO | Starting transmit on all flows ...
19:22:09 snappi_api.info L1488 INFO | Flows generate/apply 5.382s
19:22:21 snappi_api.info L1488 INFO | Flows clear statistics 12.201s
19:22:21 snappi_api.info L1488 INFO | Captures start 0.000s
19:22:26 snappi_api.info L1488 INFO | Flows start 3.832s
19:22:26 traffic_generation.run_traffic L0472 INFO | Polling DUT for traffic statistics for 15 seconds ...
19:22:32 traffic_generation.run_traffic L0491 INFO | Polling TGEN for in-flight traffic statistics...
19:22:33 traffic_generation.run_traffic L0496 INFO | In-flight traffic statistics for flows: ['Test Flow 1 -> 0', 'Test Flow 2 -> 0', 'Background Flow 1 -> 0', 'Background Flow 2 -> 0']
19:22:33 traffic_generation.run_traffic L0497 INFO | In-flight TX frames: [113264047, 113264046, 94386706, 94386706]
19:22:33 traffic_generation.run_traffic L0498 INFO | In-flight RX frames: [94387304, 94387304, 94386664, 94386668]
19:22:37 traffic_generation.run_traffic L0499 INFO | DUT polling complete
19:22:37 traffic_generation.run_traffic L0510 INFO | Checking if all flows have stopped. Attempt #1
19:22:40 traffic_generation.run_traffic L0510 INFO | Checking if all flows have stopped. Attempt #2
19:22:44 traffic_generation.run_traffic L0517 INFO | All test and background traffic flows stopped
19:22:46 traffic_generation.run_traffic L0540 INFO | Dumping per-flow statistics
19:22:48 traffic_generation.run_traffic L0542 INFO | Stopping transmit on all remaining flows
PASSED [100%]
------------------------------ live log teardown -------------------------------
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for monit:memory_usage: 10.0
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for top:bgpd: 128.0
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for top:zebra: 64.0
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for free:used: 562.2
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:snmp: 2.0
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:pmon: 2.0
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:lldp: 2.0
19:22:56 memory_utilization.check_memory_threshol L0066 WARNING| Skipping memory check for docker-gnmi due to zero value
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:radv: 2.0
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:syncd: 5.0
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:bgp: 4.0
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:teamd: 2.0
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:swss: 3.0
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for docker:database: 2.0
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for frr_bgp:used: 3.69384765625
19:22:56 memory_utilization.check_memory_threshol L0095 INFO | Calculated increase threshold for frr_zebra:used: 2.4052734375
19:22:56 init.pytest_runtest_teardown L0106 INFO | After test: collected memory_values {'before_test': {'sonic-s6100-dut1': {'monit': {'memory_usage': 19.9}, 'top': {'zebra': 19.96484375, 'bgpd': 21.62109375}, 'free': {'used': 2811}, 'docker': {'snmp': '0.42', 'lldp': '0.38', 'radv': '0.24', 'syncd': '3.88', 'teamd': '0.28', 'bgp': '0.54', 'swss': '0.48', 'pmon': '0.78', 'database': '0.75'}, 'frr_bgp': {'used': 7.3876953125}, 'frr_zebra': {'used': 4.810546875}}}, 'after_test': {'sonic-s6100-dut1': {'monit': {'memory_usage': 19.9}, 'top': {'zebra': 19.96484375, 'bgpd': 21.62109375}, 'free': {'used': 2814}, 'docker': {'snmp': '0.42', 'lldp': '0.38', 'radv': '0.24', 'syncd': '3.88', 'teamd': '0.28', 'bgp': '0.54', 'swss': '0.48', 'pmon': '0.78', 'database': '0.75'}, 'frr_bgp': {'used': 7.3876953125}, 'frr_zebra': {'used': 4.810546875}}}}
19:22:56 init._fixture_generator_decorator L0093 INFO | -------------------- fixture disable_pfcwd teardown starts --------------------
19:22:58 init._fixture_generator_decorator L0102 INFO | -------------------- fixture disable_pfcwd teardown ends --------------------
19:22:58 init._fixture_generator_decorator L0093 INFO | -------------------- fixture snappi_api teardown starts --------------------
19:23:10 init._fixture_generator_decorator L0102 INFO | -------------------- fixture snappi_api teardown ends --------------------
19:23:10 init._fixture_generator_decorator L0093 INFO | -------------------- fixture number_of_tx_rx_ports teardown starts --------------------
19:23:10 init._fixture_generator_decorator L0102 INFO | -------------------- fixture number_of_tx_rx_ports teardown ends --------------------
19:23:10 init._fixture_generator_decorator L0093 INFO | -------------------- fixture disable_voq_watchdog teardown starts --------------------
19:23:10 init._fixture_generator_decorator L0102 INFO | -------------------- fixture disable_voq_watchdog teardown ends --------------------
19:23:10 init._fixture_generator_decorator L0093 INFO | -------------------- fixture start_pfcwd_after_test teardown starts --------------------
19:23:11 init._fixture_generator_decorator L0102 INFO | -------------------- fixture start_pfcwd_after_test teardown ends --------------------
19:23:11 init._fixture_generator_decorator L0093 INFO | -------------------- fixture rand_lossy_prio teardown starts --------------------
19:23:11 init._fixture_generator_decorator L0102 INFO | -------------------- fixture rand_lossy_prio teardown ends --------------------
19:23:11 init._fixture_generator_decorator L0093 INFO | -------------------- fixture rand_lossless_prio teardown starts --------------------
19:23:11 init._fixture_generator_decorator L0102 INFO | -------------------- fixture rand_lossless_prio teardown ends --------------------
19:23:11 init._fixture_generator_decorator L0093 INFO | -------------------- fixture enable_packet_aging_after_test teardown starts --------------------
19:23:11 init._fixture_generator_decorator L0102 INFO | -------------------- fixture enable_packet_aging_after_test teardown ends --------------------
19:23:11 conftest.temporarily_disable_route_check L2976 INFO | Skipping temporarily_disable_route_check fixture
19:23:11 conftest.collect_after_test L2763 INFO | Dumping Disk and Memory Space information after test on sonic-s6100-dut1
19:23:12 conftest.collect_after_test L2767 INFO | Collecting core dumps after test on sonic-s6100-dut1
19:23:12 conftest.collect_after_test L2778 INFO | Collecting running config after test on sonic-s6100-dut1
19:23:13 conftest.core_dump_and_config_check L2919 WARNING| Core dump or config check failed for test_m2o_oversubscribe_lossy.py, results: {"core_dump_check": {"failed": false, "new_core_dumps": {"sonic-s6100-dut1": []}}, "config_db_check": {"failed": true, "pre_only_config": {"sonic-s6100-dut1": {"null": {}}}, "cur_only_config": {"sonic-s6100-dut1": {"null": {}}}, "inconsistent_config": {"sonic-s6100-dut1": {"null": {"PFC_WD": {"pre_value": {"Ethernet0": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "Ethernet16": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "Ethernet24": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "Ethernet8": {"action": "drop", "detection_time": "200", "restoration_time": "200"}}, "cur_value": {"Ethernet0": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "Ethernet16": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "Ethernet24": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "Ethernet8": {"action": "drop", "detection_time": "200", "restoration_time": "200"}, "GLOBAL": {"POLL_INTERVAL": "200"}}}}}}}}
19:23:13 conftest.restore_config_db_and_config_re L2597 INFO | dut reload called on sonic-s6100-dut1
19:23:14 config_reload.config_reload L0148 INFO | reloading config_db
19:24:30 processes_utils.wait_critical_processes L0076 INFO | Wait until all critical processes are healthy in 300 sec
19:24:30 processes_utils.all_critical_processes L0045 INFO | Check critical processes status
19:24:40 sonic_asic.check_bgp_session_state L0714 INFO | bgp neighbors that match the state: [] on namespace None
19:24:40 sonic_asic.check_bgp_session_state L0715 INFO | bgp neighbors to be checked on the state: [] on namespace None

=============================== warnings summary ===============================
../../../../usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755
/usr/local/lib/python3.8/dist-packages/_pytest/config/init.py:755: PytestAssertRewriteWarning: Module already imported so cannot be rewritten: tests.common.plugins.ptfadapter
self.import_plugin(import_spec)