<?xml version="1.0" encoding="UTF-8"?>
<robot generator="Robot 7.5 (Python 3.11.10 on linux)" generated="2026-09-29T01:04:16.932368" rpa="false" schemaversion="5">
<suite id="s1" name="openflowplugin-cbench.txt" source="/w/workspace/openflowplugin-csit-1node-cbench-only-titanium/test/csit/suites/openflowplugin/Performance/010_Cbench.robot">
<kw name="Cbench Suite Setup" type="SETUP">
<kw name="Wait Until Keyword Succeeds" owner="BuiltIn">
<kw name="Issue_Command_On_Karaf_Console" owner="KarafKeywords">
<if>
<branch type="IF" condition="'${IS_KARAF_APPL}' == 'False'">
<return>
<value>Not A Karaf App</value>
<status status="NOT RUN" start="2026-09-29T01:04:17.404890" elapsed="0.000023"/>
</return>
<status status="NOT RUN" start="2026-09-29T01:04:17.404775" elapsed="0.000186"/>
</branch>
<status status="PASS" start="2026-09-29T01:04:17.404746" elapsed="0.000241"/>
</if>
<kw name="Open Connection" owner="SSHLibrary">
<arg>${controller}</arg>
<arg>port=${karaf_port}</arg>
<arg>prompt=${KARAF_PROMPT_LOGIN}</arg>
<arg>timeout=${timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-09-29T01:04:17.405153" elapsed="0.000436"/>
</kw>
<kw name="Login" owner="SSHLibrary">
<msg time="2026-09-29T01:04:17.406040" level="INFO">Logging into '10.30.170.255:8101' as 'karaf'.</msg>
<msg time="2026-09-29T01:04:18.406868" level="INFO">Read output: [33m                                                                                           
[33m    ________                       ________                .__  .__       .__     __       
[33m    \_____  \ ______   ____   ____ \______ \ _____  ___.__.|  | |__| ____ |  |___/  |_     
[33m     /   |   \\____ \_/ __ \ /    \ |    |  \\__  \&lt;   |  ||  | |  |/ ___\|  |  \   __\    
[33m    /    |    \  |_&gt; &gt;  ___/|   |  \|    `   \/ __ \\___  ||  |_|  / /_/  &gt;   Y  \  |      
[33m    \_______  /   __/ \___  &gt;___|  /_______  (____  / ____||____/__\___  /|___|  /__|      
[33m            \/|__|        \/     \/        \/     \/\/            /_____/      \/          
[33m                                                                                           

Hit '[1m&lt;tab&gt;[0m' for a list of available commands
and '[1m[cmd] --help[0m' for help on a specific command.
Hit '[1m&lt;ctrl-d&gt;[0m' or type '[1msystem:shutdown[0m' or '[1mlogout[0m' to shutdown OpenDaylight.

[?1h=[90m~[0m                                                                                [?2004hopendaylight-user</msg>
<arg>${KARAF_USER}</arg>
<arg>${KARAF_PASSWORD}</arg>
<arg>loglevel=${loglevel}</arg>
<doc>Logs into the SSH server with the given ``username`` and ``password``.</doc>
<status status="PASS" start="2026-09-29T01:04:17.405802" elapsed="1.001235"/>
</kw>
<kw name="Write" owner="SSHLibrary">
<msg time="2026-09-29T01:04:18.457452" level="INFO">@root&gt;log:set ERROR</msg>
<arg>${cmd}</arg>
<doc>Writes the given ``text`` on the remote machine and appends a newline.</doc>
<status status="PASS" start="2026-09-29T01:04:18.407327" elapsed="0.050191"/>
</kw>
<kw name="Read Until Regexp" owner="SSHLibrary">
<msg time="2026-09-29T01:04:18.459157" level="INFO">[?1l&gt;[?2004l[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<msg time="2026-09-29T01:04:18.459284" level="INFO">${output} = [?1l&gt;[?2004l[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<var>${output}</var>
<arg>${KARAF_PROMPT}</arg>
<doc>Consumes and returns the server output until a match to ``regexp`` is found.</doc>
<status status="PASS" start="2026-09-29T01:04:18.457705" elapsed="0.001608"/>
</kw>
<kw name="Write Bare" owner="SSHLibrary">
<arg>logout\n</arg>
<doc>Writes the given ``text`` on the remote machine without appending a newline.</doc>
<status status="PASS" start="2026-09-29T01:04:18.459470" elapsed="0.000193"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-29T01:04:18.459827" elapsed="0.000188"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:04:18.460561" level="INFO">[?1l&gt;[?2004l[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:04:18.460206" elapsed="0.000413"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-09-29T01:04:18.460670" elapsed="0.000070"/>
</return>
<arg>log:set ${log_level}</arg>
<doc>Will execute the given ${cmd} by ssh'ing to the karaf console running on 10.30.170.255
Note that this keyword will open&amp;close new SSH connection, without switching back to previously current session.</doc>
<status status="PASS" start="2026-09-29T01:04:17.404450" elapsed="1.056399"/>
</kw>
<arg>3x</arg>
<arg>1s</arg>
<arg>KarafKeywords.Issue Command On Karaf Console</arg>
<arg>log:set ${log_level}</arg>
<doc>Runs the specified keyword and retries if it fails.</doc>
<status status="PASS" start="2026-09-29T01:04:17.402815" elapsed="1.058094"/>
</kw>
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-09-29T01:04:18.461460" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-titanium/latency.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-titanium/latency.csv&lt;/a&gt;'.</msg>
<arg>${latency_results_file}</arg>
<arg>LATENCY_MIN,LATENCY_MAX,LATENCY_AVERAGE\n</arg>
<doc>Appends the given content to the specified file.</doc>
<status status="PASS" start="2026-09-29T01:04:18.461101" elapsed="0.000412"/>
</kw>
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-09-29T01:04:18.461895" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-titanium/throughput.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-titanium/throughput.csv&lt;/a&gt;'.</msg>
<arg>${throughput_results_file}</arg>
<arg>THROUGHPUT_MIN,THROUGHPUT_MAX,THROUGHPUT_AVERAGE\n</arg>
<doc>Appends the given content to the specified file.</doc>
<status status="PASS" start="2026-09-29T01:04:18.461665" elapsed="0.000282"/>
</kw>
<kw name="Evaluate" owner="BuiltIn">
<msg time="2026-09-29T01:04:18.462614" level="INFO">${duration_in_ms} = 60000</msg>
<var>${duration_in_ms}</var>
<arg>${duration_in_secs} * 1000</arg>
<doc>Evaluates the given expression in Python and returns the result.</doc>
<status status="PASS" start="2026-09-29T01:04:18.462129" elapsed="0.000511"/>
</kw>
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-09-29T01:04:18.463162" level="INFO">${duration_in_ms} = 60000</msg>
<arg>${duration_in_ms}</arg>
<doc>Makes the variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-09-29T01:04:18.462850" elapsed="0.000352"/>
</kw>
<kw name="Evaluate" owner="BuiltIn">
<msg time="2026-09-29T01:04:18.463741" level="INFO">${test_timeout} = 900.0</msg>
<var>${test_timeout}</var>
<arg>(${loops} * ${duration_in_secs}) * 1.5</arg>
<doc>Evaluates the given expression in Python and returns the result.</doc>
<status status="PASS" start="2026-09-29T01:04:18.463398" elapsed="0.000368"/>
</kw>
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-09-29T01:04:18.464249" level="INFO">${test_timeout} = 900.0</msg>
<arg>${test_timeout}</arg>
<doc>Makes the variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-09-29T01:04:18.463954" elapsed="0.000336"/>
</kw>
<if>
<branch type="IF" condition="&quot;${cbench_system}&quot; == &quot;localhost&quot;">
<kw name="File Should Exist" owner="OperatingSystem">
<arg>${cbench_executable}</arg>
<doc>Fails unless the given `path` points to an existing file.</doc>
<status status="NOT RUN" start="2026-09-29T01:04:18.464573" elapsed="0.000019"/>
</kw>
<status status="NOT RUN" start="2026-09-29T01:04:18.464375" elapsed="0.000247"/>
</branch>
<branch type="ELSE">
<kw name="Verify File Exists On Remote System" owner="Utils">
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-09-29T01:04:18.466882" level="INFO">${conn_id} = 2</msg>
<var>${conn_id}</var>
<arg>${system}</arg>
<arg>prompt=${prompt}</arg>
<arg>timeout=${prompt_timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-09-29T01:04:18.466508" elapsed="0.000399"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-29T01:04:18.468041" level="INFO">Length is 0.</msg>
<msg time="2026-09-29T01:04:18.468119" level="INFO">${pwd_length} = 0</msg>
<var>${pwd_length}</var>
<arg>${password}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-09-29T01:04:18.467422" elapsed="0.000720"/>
</kw>
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${pwd_length} &gt; 0</arg>
<arg>SSHLibrary.Login</arg>
<arg>${user}</arg>
<arg>${password}</arg>
<arg>delay=${delay}</arg>
<doc>Conditionally runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-29T01:04:18.468298" elapsed="0.000317"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-09-29T01:04:18.469399" level="INFO">Logging into '10.30.171.66:22' as 'jenkins'.</msg>
<msg time="2026-09-29T01:04:18.932742" level="INFO">Read output: Welcome to Ubuntu 22.04.5 LTS (GNU/Linux 5.15.0-187-generic x86_64)

 * Documentation:  https://help.ubuntu.com
 * Management:     https://landscape.canonical.com
 * Support:        https://ubuntu.com/pro

 System information as of Tue Sep 29 01:03:32 UTC 2026

  System load:  0.15               Processes:             111
  Usage of /:   19.8% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.171.66
  Swap usage:   0%


Expanded Security Maintenance for Applications is not enabled.

5 updates can be applied immediately.
To see these additional updates run: apt list --upgradable

14 additional security updates can be applied with ESM Apps.
Learn more about enabling ESM Apps service at https://ubuntu.com/esm


The list of available updates is more than a week old.
To check for new updates run: sudo apt update
New release '24.04.5 LTS' available.
Run 'do-release-upgrade' to upgrade to it.


[?2004h[jenkins@releng-48317-851-1-mininet-ovs-217-0 ~]&gt;</msg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Logs into the SSH server using key-based authentication.</doc>
<status status="PASS" start="2026-09-29T01:04:18.469141" elapsed="0.463692"/>
</kw>
<msg time="2026-09-29T01:04:18.932897" level="INFO">Returning from the enclosing user keyword.</msg>
<arg>SSHLibrary.Login With Public Key</arg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-29T01:04:18.468780" elapsed="0.464189"/>
</kw>
<arg>${user}</arg>
<arg>${password}</arg>
<doc>On active SSH session: if given non-empty password, do Login, else do Login With Public Key.</doc>
<status status="PASS" start="2026-09-29T01:04:18.467092" elapsed="0.465965"/>
</kw>
<kw name="File Should Exist" owner="SSHLibrary">
<msg time="2026-09-29T01:04:18.942547" level="INFO">[chan 1] Opened sftp connection (server version 3)</msg>
<arg>${file}</arg>
<doc>Fails if the given ``path`` does NOT point to an existing file.</doc>
<status status="PASS" start="2026-09-29T01:04:18.933219" elapsed="0.011145"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-29T01:04:18.944590" elapsed="0.000342"/>
</kw>
<arg>${cbench_system}</arg>
<arg>${cbench_executable}</arg>
<doc>Will create connection with public key and will PASS if the given ${file} exists,
otherwise will FAIL</doc>
<status status="PASS" start="2026-09-29T01:04:18.466022" elapsed="0.478996"/>
</kw>
<status status="PASS" start="2026-09-29T01:04:18.464714" elapsed="0.480350"/>
</branch>
<status status="PASS" start="2026-09-29T01:04:18.464348" elapsed="0.480752"/>
</if>
<kw name="Should Be True" owner="BuiltIn">
<arg>${loops} &gt;= 2</arg>
<arg>If number of loops is less than 2, cbench will not run</arg>
<doc>Fails if the given condition is not true.</doc>
<status status="PASS" start="2026-09-29T01:04:18.945346" elapsed="0.000543"/>
</kw>
<kw name="Verify_Feature_Is_Installed" owner="KarafKeywords">
<if>
<branch type="IF" condition="'${IS_KARAF_APPL}' == 'False'">
<return>
<value>Not A Karaf App</value>
<status status="NOT RUN" start="2026-09-29T01:04:18.948426" elapsed="0.000024"/>
</return>
<status status="NOT RUN" start="2026-09-29T01:04:18.948289" elapsed="0.000206"/>
</branch>
<status status="PASS" start="2026-09-29T01:04:18.948258" elapsed="0.000268"/>
</if>
<kw name="Issue_Command_On_Karaf_Console" owner="KarafKeywords">
<if>
<branch type="IF" condition="'${IS_KARAF_APPL}' == 'False'">
<return>
<value>Not A Karaf App</value>
<status status="NOT RUN" start="2026-09-29T01:04:18.950571" elapsed="0.000020"/>
</return>
<status status="NOT RUN" start="2026-09-29T01:04:18.950444" elapsed="0.000188"/>
</branch>
<status status="PASS" start="2026-09-29T01:04:18.950419" elapsed="0.000242"/>
</if>
<kw name="Open Connection" owner="SSHLibrary">
<arg>${controller}</arg>
<arg>port=${karaf_port}</arg>
<arg>prompt=${KARAF_PROMPT_LOGIN}</arg>
<arg>timeout=${timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-09-29T01:04:18.950893" elapsed="0.000502"/>
</kw>
<kw name="Login" owner="SSHLibrary">
<msg time="2026-09-29T01:04:18.951932" level="INFO">Logging into '10.30.170.255:8101' as 'karaf'.</msg>
<msg time="2026-09-29T01:04:19.210711" level="INFO">Read output: [33m                                                                                           
[33m    ________                       ________                .__  .__       .__     __       
[33m    \_____  \ ______   ____   ____ \______ \ _____  ___.__.|  | |__| ____ |  |___/  |_     
[33m     /   |   \\____ \_/ __ \ /    \ |    |  \\__  \&lt;   |  ||  | |  |/ ___\|  |  \   __\    
[33m    /    |    \  |_&gt; &gt;  ___/|   |  \|    `   \/ __ \\___  ||  |_|  / /_/  &gt;   Y  \  |      
[33m    \_______  /   __/ \___  &gt;___|  /_______  (____  / ____||____/__\___  /|___|  /__|      
[33m            \/|__|        \/     \/        \/     \/\/            /_____/      \/          
[33m                                                                                           

Hit '[1m&lt;tab&gt;[0m' for a list of available commands
and '[1m[cmd] --help[0m' for help on a specific command.
Hit '[1m&lt;ctrl-d&gt;[0m' or type '[1msystem:shutdown[0m' or '[1mlogout[0m' to shutdown OpenDaylight.

[?1h=[90m~[0m                                                                                [?2004hopendaylight-user</msg>
<arg>${KARAF_USER}</arg>
<arg>${KARAF_PASSWORD}</arg>
<arg>loglevel=${loglevel}</arg>
<doc>Logs into the SSH server with the given ``username`` and ``password``.</doc>
<status status="PASS" start="2026-09-29T01:04:18.951635" elapsed="0.259171"/>
</kw>
<kw name="Write" owner="SSHLibrary">
<msg time="2026-09-29T01:04:19.315426" level="INFO">@root&gt;feature:list -i | 0[C 0g[Krep odl-openflowplugin-drop-test</msg>
<arg>${cmd}</arg>
<doc>Writes the given ``text`` on the remote machine and appends a newline.</doc>
<status status="PASS" start="2026-09-29T01:04:19.210982" elapsed="0.104502"/>
</kw>
<kw name="Read Until Regexp" owner="SSHLibrary">
<msg time="2026-09-29T01:04:19.319149" level="INFO">[?1l&gt;[?2004l[31;1modl-openflowplugin-drop-test[0m                                    x 0.20.4           x x        x Started x [31;1modl-openflowplugin-drop-test[0m                                    x OpenDaylight :: Openflow Plugin :: Drop Test
[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<msg time="2026-09-29T01:04:19.319325" level="INFO">${output} = [?1l&gt;[?2004l[31;1modl-openflowplugin-drop-test[0m                                    x 0.20.4           x x        x Started x [31;1modl-openflowplugin-drop-test[0m                     ...</msg>
<var>${output}</var>
<arg>${KARAF_PROMPT}</arg>
<doc>Consumes and returns the server output until a match to ``regexp`` is found.</doc>
<status status="PASS" start="2026-09-29T01:04:19.315638" elapsed="0.003717"/>
</kw>
<kw name="Write Bare" owner="SSHLibrary">
<arg>logout\n</arg>
<doc>Writes the given ``text`` on the remote machine without appending a newline.</doc>
<status status="PASS" start="2026-09-29T01:04:19.319491" elapsed="0.000163"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-29T01:04:19.319808" elapsed="0.000146"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:04:19.320417" level="INFO">[?1l&gt;[?2004l[31;1modl-openflowplugin-drop-test[0m                                    x 0.20.4           x x        x Started x [31;1modl-openflowplugin-drop-test[0m                                    x OpenDaylight :: Openflow Plugin :: Drop Test
[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:04:19.320136" elapsed="0.000333"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-09-29T01:04:19.320512" elapsed="0.000041"/>
</return>
<msg time="2026-09-29T01:04:19.320667" level="INFO">${output} = [?1l&gt;[?2004l[31;1modl-openflowplugin-drop-test[0m                                    x 0.20.4           x x        x Started x [31;1modl-openflowplugin-drop-test[0m                     ...</msg>
<var>${output}</var>
<arg>feature:list -i | grep ${feature_name}</arg>
<arg>${controller}</arg>
<arg>${karaf_port}</arg>
<doc>Will execute the given ${cmd} by ssh'ing to the karaf console running on 10.30.170.255
Note that this keyword will open&amp;close new SSH connection, without switching back to previously current session.</doc>
<status status="PASS" start="2026-09-29T01:04:18.950027" elapsed="0.370686"/>
</kw>
<kw name="Should Contain" owner="BuiltIn">
<arg>${output}</arg>
<arg>${feature_name}</arg>
<doc>Fails if the `container` does not contain the `item` one or more times.</doc>
<status status="PASS" start="2026-09-29T01:04:19.320872" elapsed="0.000498"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-09-29T01:04:19.321417" elapsed="0.000030"/>
</return>
<arg>odl-openflowplugin-drop-test</arg>
<doc>Will Succeed if the given ${feature_name} is found in the output of "feature:list -i"</doc>
<status status="PASS" start="2026-09-29T01:04:18.947940" elapsed="0.373585"/>
</kw>
<kw name="Issue_Command_On_Karaf_Console" owner="KarafKeywords">
<if>
<branch type="IF" condition="'${IS_KARAF_APPL}' == 'False'">
<return>
<value>Not A Karaf App</value>
<status status="NOT RUN" start="2026-09-29T01:04:19.323213" elapsed="0.000031"/>
</return>
<status status="NOT RUN" start="2026-09-29T01:04:19.323116" elapsed="0.000159"/>
</branch>
<status status="PASS" start="2026-09-29T01:04:19.323096" elapsed="0.000201"/>
</if>
<kw name="Open Connection" owner="SSHLibrary">
<arg>${controller}</arg>
<arg>port=${karaf_port}</arg>
<arg>prompt=${KARAF_PROMPT_LOGIN}</arg>
<arg>timeout=${timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-09-29T01:04:19.323455" elapsed="0.000354"/>
</kw>
<kw name="Login" owner="SSHLibrary">
<msg time="2026-09-29T01:04:19.324176" level="INFO">Logging into '10.30.170.255:8101' as 'karaf'.</msg>
<msg time="2026-09-29T01:04:19.585358" level="INFO">Read output: [33m                                                                                           
[33m    ________                       ________                .__  .__       .__     __       
[33m    \_____  \ ______   ____   ____ \______ \ _____  ___.__.|  | |__| ____ |  |___/  |_     
[33m     /   |   \\____ \_/ __ \ /    \ |    |  \\__  \&lt;   |  ||  | |  |/ ___\|  |  \   __\    
[33m    /    |    \  |_&gt; &gt;  ___/|   |  \|    `   \/ __ \\___  ||  |_|  / /_/  &gt;   Y  \  |      
[33m    \_______  /   __/ \___  &gt;___|  /_______  (____  / ____||____/__\___  /|___|  /__|      
[33m            \/|__|        \/     \/        \/     \/\/            /_____/      \/          
[33m                                                                                           

Hit '[1m&lt;tab&gt;[0m' for a list of available commands
and '[1m[cmd] --help[0m' for help on a specific command.
Hit '[1m&lt;ctrl-d&gt;[0m' or type '[1msystem:shutdown[0m' or '[1mlogout[0m' to shutdown OpenDaylight.

[?1h=[90m~[0m                                                                                [?2004hopendaylight-user</msg>
<arg>${KARAF_USER}</arg>
<arg>${KARAF_PASSWORD}</arg>
<arg>loglevel=${loglevel}</arg>
<doc>Logs into the SSH server with the given ``username`` and ``password``.</doc>
<status status="PASS" start="2026-09-29T01:04:19.323980" elapsed="0.261459"/>
</kw>
<kw name="Write" owner="SSHLibrary">
<msg time="2026-09-29T01:04:19.605074" level="INFO">@root&gt;dropallpacketsrpc on</msg>
<arg>${cmd}</arg>
<doc>Writes the given ``text`` on the remote machine and appends a newline.</doc>
<status status="PASS" start="2026-09-29T01:04:19.585597" elapsed="0.019531"/>
</kw>
<kw name="Read Until Regexp" owner="SSHLibrary">
<msg time="2026-09-29T01:04:19.606990" level="INFO">[?1l&gt;[?2004lDropAllFlows transitions to on
[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<msg time="2026-09-29T01:04:19.607083" level="INFO">${output} = [?1l&gt;[?2004lDropAllFlows transitions to on
[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<var>${output}</var>
<arg>${KARAF_PROMPT}</arg>
<doc>Consumes and returns the server output until a match to ``regexp`` is found.</doc>
<status status="PASS" start="2026-09-29T01:04:19.605293" elapsed="0.001817"/>
</kw>
<kw name="Write Bare" owner="SSHLibrary">
<arg>logout\n</arg>
<doc>Writes the given ``text`` on the remote machine without appending a newline.</doc>
<status status="PASS" start="2026-09-29T01:04:19.607257" elapsed="0.000164"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-29T01:04:19.607563" elapsed="0.000154"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:04:19.608129" level="INFO">[?1l&gt;[?2004lDropAllFlows transitions to on
[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:04:19.607890" elapsed="0.000284"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-09-29T01:04:19.608215" elapsed="0.000057"/>
</return>
<arg>dropallpacketsrpc on</arg>
<doc>Will execute the given ${cmd} by ssh'ing to the karaf console running on 10.30.170.255
Note that this keyword will open&amp;close new SSH connection, without switching back to previously current session.</doc>
<status status="PASS" start="2026-09-29T01:04:19.322849" elapsed="0.285504"/>
</kw>
<status status="PASS" start="2026-09-29T01:04:17.401073" elapsed="2.207337"/>
</kw>
<test id="s1-t1" name="Cbench Latency Test" line="37">
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:04:19.610154" level="INFO">Cbench tests using 10 iterations of 60 second tests. Switch Count: 16. Unique MACS to cycle: 100</msg>
<arg>Cbench tests using ${loops} iterations of ${duration_in_secs} second tests. Switch Count: ${switch_count}. Unique MACS to cycle: ${num_of_unique_macs}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:04:19.609245" elapsed="0.000971"/>
</kw>
<kw name="Run Cbench And Log Results">
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-09-29T01:04:19.611506" level="INFO">${output_filename} = latency.csv</msg>
<arg>${output_filename}</arg>
<doc>Makes the variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-09-29T01:04:19.611140" elapsed="0.000406"/>
</kw>
<if>
<branch type="IF" condition="&quot;${cbench_system}&quot; == &quot;localhost&quot;">
<kw name="Run" owner="OperatingSystem">
<var>${output}</var>
<arg>${cbench_executable} -c ${ODL_SYSTEM_IP} ${cbench_args}</arg>
<doc>*This keyword is considered deprecated. Use the [Process] library instead.*</doc>
<status status="NOT RUN" start="2026-09-29T01:04:19.611838" elapsed="0.000020"/>
</kw>
<status status="NOT RUN" start="2026-09-29T01:04:19.611613" elapsed="0.000279"/>
</branch>
<branch type="ELSE">
<kw name="Run Command On Remote System" owner="Utils">
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot; and &quot;${return_stderr}&quot;!=&quot;True&quot;">
<kw name="Fail" owner="BuiltIn">
<arg>At least one of {return_stdout} or {return_stderr} args should be set to True</arg>
<doc>Fails the test or task with the given message and optionally alters its tags.</doc>
<status status="NOT RUN" start="2026-09-29T01:04:19.612966" elapsed="0.000020"/>
</kw>
<status status="NOT RUN" start="2026-09-29T01:04:19.612747" elapsed="0.000270"/>
</branch>
<status status="PASS" start="2026-09-29T01:04:19.612727" elapsed="0.000314"/>
</if>
<kw name="Get Connection" owner="SSHLibrary">
<msg time="2026-09-29T01:04:19.613497" level="INFO">index=None
host=None
alias=None
port=22
timeout=3 seconds
newline=

prompt=None
term_type=vt100
width=80
height=24
path_separator=/
encoding=utf8
escape_ansi=False
encoding_errors=strict</msg>
<msg time="2026-09-29T01:04:19.613608" level="INFO">${current_ssh_connection} = index=None
host=None
alias=None
port=22
timeout=3 seconds
newline=

prompt=None
term_type=vt100
width=80
height=24
path_separator=/
encoding=utf8
escape_ansi=False
encoding_errors=strict</msg>
<var>${current_ssh_connection}</var>
<doc>Returns information about the connection.</doc>
<status status="PASS" start="2026-09-29T01:04:19.613222" elapsed="0.000410"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:04:19.614149" level="INFO">Attempting to execute command "/usr/local/bin/cbench -c 10.30.170.255 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633" on remote system "10.30.171.66" by user "jenkins" with keyfile pass "any" and prompt "&gt;" and password ""</msg>
<arg>Attempting to execute command "${cmd}" on remote system "${system}" by user "${user}" with keyfile pass "${keyfile_pass}" and prompt "${prompt}" and password "${password}"</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:04:19.613824" elapsed="0.000380"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-09-29T01:04:19.614784" level="INFO">${conn_id} = 5</msg>
<var>${conn_id}</var>
<arg>${system}</arg>
<arg>prompt=${prompt}</arg>
<arg>timeout=${prompt_timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-09-29T01:04:19.614384" elapsed="0.000424"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-29T01:04:19.615629" level="INFO">Length is 0.</msg>
<msg time="2026-09-29T01:04:19.615721" level="INFO">${pwd_length} = 0</msg>
<var>${pwd_length}</var>
<arg>${password}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-09-29T01:04:19.615345" elapsed="0.000398"/>
</kw>
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${pwd_length} &gt; 0</arg>
<arg>SSHLibrary.Login</arg>
<arg>${user}</arg>
<arg>${password}</arg>
<arg>delay=${delay}</arg>
<doc>Conditionally runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-29T01:04:19.615877" elapsed="0.000295"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-09-29T01:04:19.616986" level="INFO">Logging into '10.30.171.66:22' as 'jenkins'.</msg>
<msg time="2026-09-29T01:04:19.970608" level="INFO">Read output: Welcome to Ubuntu 22.04.5 LTS (GNU/Linux 5.15.0-187-generic x86_64)

 * Documentation:  https://help.ubuntu.com
 * Management:     https://landscape.canonical.com
 * Support:        https://ubuntu.com/pro

 System information as of Tue Sep 29 01:03:32 UTC 2026

  System load:  0.15               Processes:             111
  Usage of /:   19.8% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.171.66
  Swap usage:   0%


Expanded Security Maintenance for Applications is not enabled.

5 updates can be applied immediately.
To see these additional updates run: apt list --upgradable

14 additional security updates can be applied with ESM Apps.
Learn more about enabling ESM Apps service at https://ubuntu.com/esm


The list of available updates is more than a week old.
To check for new updates run: sudo apt update
New release '24.04.5 LTS' available.
Run 'do-release-upgrade' to upgrade to it.


Last login: Tue Sep 29 01:04:18 2026 from 10.30.170.36
[?2004h[jenkins@releng-48317-851-1-mininet-ovs-217-0 ~]&gt;</msg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Logs into the SSH server using key-based authentication.</doc>
<status status="PASS" start="2026-09-29T01:04:19.616713" elapsed="0.354022"/>
</kw>
<msg time="2026-09-29T01:04:19.970797" level="INFO">Returning from the enclosing user keyword.</msg>
<arg>SSHLibrary.Login With Public Key</arg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-29T01:04:19.616338" elapsed="0.354528"/>
</kw>
<arg>${user}</arg>
<arg>${password}</arg>
<doc>On active SSH session: if given non-empty password, do Login, else do Login With Public Key.</doc>
<status status="PASS" start="2026-09-29T01:04:19.614988" elapsed="0.355963"/>
</kw>
<kw name="Execute Command" owner="SSHLibrary">
<msg time="2026-09-29T01:04:19.971366" level="INFO">Executing command '/usr/local/bin/cbench -c 10.30.170.255 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633'.</msg>
<msg time="2026-09-29T01:14:26.112965" level="INFO">Command exited with return code 0.</msg>
<msg time="2026-09-29T01:14:26.113604" level="INFO">${stdout} = </msg>
<msg time="2026-09-29T01:14:26.113704" level="INFO">${stderr} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.170.255:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 10...</msg>
<var>${stdout}</var>
<var>${stderr}</var>
<arg>${cmd}</arg>
<arg>return_stderr=True</arg>
<doc>Executes ``command`` on the remote machine and returns its outputs.</doc>
<status status="PASS" start="2026-09-29T01:04:19.971147" elapsed="606.142626"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-29T01:14:26.114264" elapsed="0.000706"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.116213" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.170.255:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100 unique source MACs per switch
   learning destination mac addresses before the test
   starting test with 5000 ms delay after features_reply
   ignoring first 1 "warmup" and last 0 "cooldown" loops
   debugging info is off
Master Thread: Creating thread-0! 
Thread-0: All switches initialized. Running at node 10.30.170.255. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
01:05:25.204 16  switches: flows:  88651 88864 86465 86506 86562 87935 87029 88286 85903 86740 86340 85104 85192 86381 86758 85669  total = 23.139750 per ms 
01:06:25.304 16  switches: flows:  90713 88900 86651 87353 89447 89321 88032 89842 87105 86672 87823 87169 86007 86899 86280 86320  total = 23.369889 per ms 
01:07:25.404 16  switches: flows:  98228 96267 94810 93723 95442 94471 97096 95966 95115 93854 96627 95967 92224 95070 93249 94362  total = 25.332245 per ms 
01:08:25.505 16  switches: flows:  91413 89849 89260 88281 91255 89142 92074 92551 90837 91430 92650 93212 89363 89760 88066 88821  total = 24.092470 per ms 
01:09:25.605 16  switches: flows:  92622 91124 89428 89670 89907 89944 91856 91621 90991 91108 90209 91266 88866 90777 87462 88684  total = 24.052113 per ms 
01:10:25.705 16  switches: flows:  94674 92431 94000 90404 91544 88695 93528 92628 92656 92680 91342 92360 90754 91420 89612 89209  total = 24.424859 per ms 
01:11:25.805 16  switches: flows:  93611 93270 93638 90787 92961 91067 93192 91118 92086 91573 91941 89873 91025 91320 91243 89812  total = 24.434516 per ms 
01:12:25.905 16  switches: flows:  95728 91402 92714 91330 93329 91479 93107 90992 91116 91235 91743 90578 91915 93469 88624 91430  total = 24.462355 per ms 
01:13:26.005 16  switches: flows:  96198 93198 92810 91527 94473 91213 94562 91138 91473 91379 92725 91549 96913 93442 92523 92108  total = 24.745870 per ms 
01:14:26.106 16  switches: flows:  95302 92484 92966 92436 93014 91925 92710 90950 90853 91642 93689 90537 94151 93106 90036 90747  total = 24.568135 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 23369.89/25332.25/24386.94/8144.67 responses/s</msg>
<arg>${stderr}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:14:26.115480" elapsed="0.001059"/>
</kw>
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot;">
<return>
<value>${stderr}</value>
<status status="PASS" start="2026-09-29T01:14:26.117010" elapsed="0.000095"/>
</return>
<status status="PASS" start="2026-09-29T01:14:26.116744" elapsed="0.000441"/>
</branch>
<status status="PASS" start="2026-09-29T01:14:26.116678" elapsed="0.000587"/>
</if>
<if>
<branch type="IF" condition="&quot;${return_stderr}&quot;!=&quot;True&quot;">
<return>
<value>${stdout}</value>
<status status="NOT RUN" start="2026-09-29T01:14:26.117462" elapsed="0.000027"/>
</return>
<status status="NOT RUN" start="2026-09-29T01:14:26.117390" elapsed="0.000182"/>
</branch>
<status status="NOT RUN" start="2026-09-29T01:14:26.117357" elapsed="0.000266"/>
</if>
<return>
<value>${stdout}</value>
<value>${stderr}</value>
<status status="NOT RUN" start="2026-09-29T01:14:26.117700" elapsed="0.000032"/>
</return>
<kw name="Restore_Current_Ssh_Connection_From_Index" owner="SSHKeywords" type="TEARDOWN">
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${connection_index} is not None</arg>
<arg>SSHLibrary.Switch Connection</arg>
<arg>${connection_index}</arg>
<doc>Conditionally runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-29T01:14:26.119349" elapsed="0.000679"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<arg>127.0.0.1</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-09-29T01:14:26.120434" elapsed="0.000357"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-29T01:14:26.121066" elapsed="0.000230"/>
</kw>
<arg>${current_ssh_connection.index}</arg>
<doc>Restore active SSH connection in SSHLibrary to given index.</doc>
<status status="PASS" start="2026-09-29T01:14:26.118394" elapsed="0.003010"/>
</kw>
<msg time="2026-09-29T01:14:26.121657" level="INFO">${output} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.170.255:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 10...</msg>
<var>${output}</var>
<arg>${cbench_system}</arg>
<arg>${cbench_executable} -c ${ODL_SYSTEM_IP} ${cbench_args}</arg>
<arg>prompt_timeout=${test_timeout}</arg>
<arg>return_stdout=False</arg>
<arg>return_stderr=True</arg>
<doc>Reduces the common work of running a command on a remote system to a single higher level
robot keyword, taking care to log in with a public key and. The command given is written and the return value
depends on the passed argument values of return_stdout (default: True) and return_stderr (default: False).
At least one should be True, or the keyword will exit and FAIL. If both are True, the resulting return value
will be a two element list containing both. Otherwise the resulting return value is a string.
No test conditions are checked.</doc>
<status status="PASS" start="2026-09-29T01:04:19.612219" elapsed="606.509493"/>
</kw>
<status status="PASS" start="2026-09-29T01:04:19.611917" elapsed="606.509892"/>
</branch>
<status status="PASS" start="2026-09-29T01:04:19.611593" elapsed="606.510265"/>
</if>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.122884" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.170.255:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100 unique source MACs per switch
   learning destination mac addresses before the test
   starting test with 5000 ms delay after features_reply
   ignoring first 1 "warmup" and last 0 "cooldown" loops
   debugging info is off
Master Thread: Creating thread-0! 
Thread-0: All switches initialized. Running at node 10.30.170.255. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
01:05:25.204 16  switches: flows:  88651 88864 86465 86506 86562 87935 87029 88286 85903 86740 86340 85104 85192 86381 86758 85669  total = 23.139750 per ms 
01:06:25.304 16  switches: flows:  90713 88900 86651 87353 89447 89321 88032 89842 87105 86672 87823 87169 86007 86899 86280 86320  total = 23.369889 per ms 
01:07:25.404 16  switches: flows:  98228 96267 94810 93723 95442 94471 97096 95966 95115 93854 96627 95967 92224 95070 93249 94362  total = 25.332245 per ms 
01:08:25.505 16  switches: flows:  91413 89849 89260 88281 91255 89142 92074 92551 90837 91430 92650 93212 89363 89760 88066 88821  total = 24.092470 per ms 
01:09:25.605 16  switches: flows:  92622 91124 89428 89670 89907 89944 91856 91621 90991 91108 90209 91266 88866 90777 87462 88684  total = 24.052113 per ms 
01:10:25.705 16  switches: flows:  94674 92431 94000 90404 91544 88695 93528 92628 92656 92680 91342 92360 90754 91420 89612 89209  total = 24.424859 per ms 
01:11:25.805 16  switches: flows:  93611 93270 93638 90787 92961 91067 93192 91118 92086 91573 91941 89873 91025 91320 91243 89812  total = 24.434516 per ms 
01:12:25.905 16  switches: flows:  95728 91402 92714 91330 93329 91479 93107 90992 91116 91235 91743 90578 91915 93469 88624 91430  total = 24.462355 per ms 
01:13:26.005 16  switches: flows:  96198 93198 92810 91527 94473 91213 94562 91138 91473 91379 92725 91549 96913 93442 92523 92108  total = 24.745870 per ms 
01:14:26.106 16  switches: flows:  95302 92484 92966 92436 93014 91925 92710 90950 90853 91642 93689 90537 94151 93106 90036 90747  total = 24.568135 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 23369.89/25332.25/24386.94/8144.67 responses/s</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:14:26.122281" elapsed="0.000753"/>
</kw>
<kw name="Should Contain" owner="BuiltIn">
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Fails if the `container` does not contain the `item` one or more times.</doc>
<status status="PASS" start="2026-09-29T01:14:26.123429" elapsed="0.000778"/>
</kw>
<kw name="Get Lines Containing String" owner="String">
<msg time="2026-09-29T01:14:26.125065" level="INFO">1 out of 23 lines matched.</msg>
<msg time="2026-09-29T01:14:26.125287" level="INFO">${result_line} = RESULT: 16 switches 9 tests min/max/avg/stdev = 23369.89/25332.25/24386.94/8144.67 responses/s</msg>
<var>${result_line}</var>
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Returns lines of the given `string` that contain the `pattern`.</doc>
<status status="PASS" start="2026-09-29T01:14:26.124636" elapsed="0.000705"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-29T01:14:26.126197" level="INFO">@{results_list} = [ RESULT: | 16 | switches | 9 | tests | min/max/avg/stdev | = | 23369.89/25332.25/24386.94/8144.67 | responses/s ]</msg>
<var>@{results_list}</var>
<arg>${result_line}</arg>
<doc>Splits the `string` using `separator` as a delimiter string.</doc>
<status status="PASS" start="2026-09-29T01:14:26.125720" elapsed="0.000563"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.127483" level="INFO">min/max/avg/stdev</msg>
<arg>${results_list[5]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:14:26.126737" elapsed="0.000884"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.128793" level="INFO">23369.89/25332.25/24386.94/8144.67</msg>
<arg>${results_list[7]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:14:26.128022" elapsed="0.000889"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-29T01:14:26.129924" level="INFO">@{result_name_list} = [ min | max | avg | stdev ]</msg>
<var>@{result_name_list}</var>
<arg>${results_list[5]}</arg>
<arg>/</arg>
<doc>Splits the `string` using `separator` as a delimiter string.</doc>
<status status="PASS" start="2026-09-29T01:14:26.129280" elapsed="0.000699"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-29T01:14:26.131132" level="INFO">@{result_value_list} = [ 23369.89 | 25332.25 | 24386.94 | 8144.67 ]</msg>
<var>@{result_value_list}</var>
<arg>${results_list[7]}</arg>
<arg>/</arg>
<doc>Splits the `string` using `separator` as a delimiter string.</doc>
<status status="PASS" start="2026-09-29T01:14:26.130463" elapsed="0.000724"/>
</kw>
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.132181" level="INFO">Length is 4.</msg>
<msg time="2026-09-29T01:14:26.132391" level="INFO">${num_stats} = 4</msg>
<var>${num_stats}</var>
<arg>${result_name_list}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-09-29T01:14:26.131578" elapsed="0.000863"/>
</kw>
<for flavor="IN RANGE">
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.134292" level="INFO">min :: 23369.89</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:14:26.133348" elapsed="0.001063"/>
</kw>
<var name="${i}">0</var>
<status status="PASS" start="2026-09-29T01:14:26.132943" elapsed="0.001537"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.136013" level="INFO">max :: 25332.25</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:14:26.135079" elapsed="0.001047"/>
</kw>
<var name="${i}">1</var>
<status status="PASS" start="2026-09-29T01:14:26.134685" elapsed="0.001508"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.137841" level="INFO">avg :: 24386.94</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:14:26.136879" elapsed="0.001074"/>
</kw>
<var name="${i}">2</var>
<status status="PASS" start="2026-09-29T01:14:26.136450" elapsed="0.001571"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.139550" level="INFO">stdev :: 8144.67</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:14:26.138623" elapsed="0.001043"/>
</kw>
<var name="${i}">3</var>
<status status="PASS" start="2026-09-29T01:14:26.138200" elapsed="0.001534"/>
</iter>
<var>${i}</var>
<value>0</value>
<value>${num_stats}</value>
<status status="PASS" start="2026-09-29T01:14:26.132594" elapsed="0.007204"/>
</for>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.141009" level="INFO">${min} = 23369.89</msg>
<var>${min}</var>
<arg>${result_value_list[${0}]}</arg>
<doc>Returns the given values which can then be assigned to a variable.</doc>
<status status="PASS" start="2026-09-29T01:14:26.140139" elapsed="0.000923"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.142338" level="INFO">${max} = 25332.25</msg>
<var>${max}</var>
<arg>${result_value_list[${1}]}</arg>
<doc>Returns the given values which can then be assigned to a variable.</doc>
<status status="PASS" start="2026-09-29T01:14:26.141434" elapsed="0.000961"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.143626" level="INFO">${average} = 24386.94</msg>
<var>${average}</var>
<arg>${result_value_list[${2}]}</arg>
<doc>Returns the given values which can then be assigned to a variable.</doc>
<status status="PASS" start="2026-09-29T01:14:26.142751" elapsed="0.000930"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.144876" level="INFO">${stdev} = 8144.67</msg>
<var>${stdev}</var>
<arg>${result_value_list[${3}]}</arg>
<doc>Returns the given values which can then be assigned to a variable.</doc>
<status status="PASS" start="2026-09-29T01:14:26.144009" elapsed="0.000920"/>
</kw>
<kw name="Get Time" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.146133" level="INFO">${date} = 2026-09-29 01:14:26</msg>
<var>${date}</var>
<arg>d,m,s</arg>
<doc>Returns the given time in the requested format.</doc>
<status status="PASS" start="2026-09-29T01:14:26.145363" elapsed="0.000824"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.147386" level="INFO">CBench Result: 2026-09-29 01:14:26,-m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633,23369.89,25332.25,24386.94,8144.67</msg>
<arg>CBench Result: ${date},${cbench_args},${min},${max},${average},${stdev}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:14:26.146657" elapsed="0.000839"/>
</kw>
<kw name="Log Results And Determine Status">
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-09-29T01:14:26.149867" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-titanium/latency.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-titanium/latency.csv&lt;/a&gt;'.</msg>
<arg>${output_file}</arg>
<arg>${min},${max},${average}\n</arg>
<doc>Appends the given content to the specified file.</doc>
<status status="PASS" start="2026-09-29T01:14:26.148749" elapsed="0.001255"/>
</kw>
<kw name="Should Be True" owner="BuiltIn">
<arg>${average} &gt; ${threshold}</arg>
<arg>${average} flow_mods per/sec did not exceed threshold of ${threshold}</arg>
<doc>Fails if the given condition is not true.</doc>
<status status="PASS" start="2026-09-29T01:14:26.150385" elapsed="0.001072"/>
</kw>
<arg>${min}</arg>
<arg>${max}</arg>
<arg>${average}</arg>
<arg>${average_threshold}</arg>
<arg>${output_filename}</arg>
<status status="PASS" start="2026-09-29T01:14:26.147796" elapsed="0.003825"/>
</kw>
<arg>-m ${duration_in_ms} -M ${num_of_unique_macs} -S ${switch_count} -l ${loops} -D ${start_delay} -p ${of_port}</arg>
<arg>${latency_threshold}</arg>
<arg>${latency_results_file}</arg>
<status status="PASS" start="2026-09-29T01:04:19.610606" elapsed="606.541135"/>
</kw>
<kw name="Sleep" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.152812" level="INFO">Slept 0 seconds.</msg>
<arg>${test_delay}</arg>
<doc>Pauses execution for the given time.</doc>
<status status="PASS" start="2026-09-29T01:14:26.152059" elapsed="0.000870"/>
</kw>
<kw name="Log Results As Zero If Cbench Timed Out" type="TEARDOWN">
<kw name="Run Keyword If Timeout Occurred" owner="BuiltIn">
<arg>Log Results And Determine Status</arg>
<arg>0</arg>
<arg>0</arg>
<arg>0</arg>
<arg>0</arg>
<arg>${output_filename}</arg>
<doc>Runs the given keyword if either a test or a keyword timeout has occurred.</doc>
<status status="PASS" start="2026-09-29T01:14:26.154039" elapsed="0.000724"/>
</kw>
<status status="PASS" start="2026-09-29T01:14:26.153376" elapsed="0.001532"/>
</kw>
<doc>cbench executed in default latency mode. Test parameters have defaults, but can be overridden
on the pybot command line</doc>
<tag>cbench</tag>
<tag>latency</tag>
<timeout value="15 minutes"/>
<status status="PASS" start="2026-09-29T01:04:19.608544" elapsed="606.546437"/>
</test>
<test id="s1-t2" name="Cbench Throughput Test" line="51">
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.158354" level="INFO">Cbench tests using 10 iterations of 60 second tests. Switch Count: 16. Unique MACS to cycle: 100</msg>
<arg>Cbench tests using ${loops} iterations of ${duration_in_secs} second tests. Switch Count: ${switch_count}. Unique MACS to cycle: ${num_of_unique_macs}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:14:26.157862" elapsed="0.000567"/>
</kw>
<kw name="Run Cbench And Log Results">
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.159949" level="INFO">${output_filename} = throughput.csv</msg>
<arg>${output_filename}</arg>
<doc>Makes the variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-09-29T01:14:26.159542" elapsed="0.000462"/>
</kw>
<if>
<branch type="IF" condition="&quot;${cbench_system}&quot; == &quot;localhost&quot;">
<kw name="Run" owner="OperatingSystem">
<var>${output}</var>
<arg>${cbench_executable} -c ${ODL_SYSTEM_IP} ${cbench_args}</arg>
<doc>*This keyword is considered deprecated. Use the [Process] library instead.*</doc>
<status status="NOT RUN" start="2026-09-29T01:14:26.160476" elapsed="0.000028"/>
</kw>
<status status="NOT RUN" start="2026-09-29T01:14:26.160093" elapsed="0.000473"/>
</branch>
<branch type="ELSE">
<kw name="Run Command On Remote System" owner="Utils">
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot; and &quot;${return_stderr}&quot;!=&quot;True&quot;">
<kw name="Fail" owner="BuiltIn">
<arg>At least one of {return_stdout} or {return_stderr} args should be set to True</arg>
<doc>Fails the test or task with the given message and optionally alters its tags.</doc>
<status status="NOT RUN" start="2026-09-29T01:14:26.162037" elapsed="0.000026"/>
</kw>
<status status="NOT RUN" start="2026-09-29T01:14:26.161737" elapsed="0.000369"/>
</branch>
<status status="PASS" start="2026-09-29T01:14:26.161712" elapsed="0.000425"/>
</if>
<kw name="Get Connection" owner="SSHLibrary">
<msg time="2026-09-29T01:14:26.162679" level="INFO">index=None
host=None
alias=None
port=22
timeout=3 seconds
newline=

prompt=None
term_type=vt100
width=80
height=24
path_separator=/
encoding=utf8
escape_ansi=False
encoding_errors=strict</msg>
<msg time="2026-09-29T01:14:26.162827" level="INFO">${current_ssh_connection} = index=None
host=None
alias=None
port=22
timeout=3 seconds
newline=

prompt=None
term_type=vt100
width=80
height=24
path_separator=/
encoding=utf8
escape_ansi=False
encoding_errors=strict</msg>
<var>${current_ssh_connection}</var>
<doc>Returns information about the connection.</doc>
<status status="PASS" start="2026-09-29T01:14:26.162406" elapsed="0.000456"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.163601" level="INFO">Attempting to execute command "/usr/local/bin/cbench -c 10.30.170.255 -t -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633" on remote system "10.30.171.66" by user "jenkins" with keyfile pass "any" and prompt "&gt;" and password ""</msg>
<arg>Attempting to execute command "${cmd}" on remote system "${system}" by user "${user}" with keyfile pass "${keyfile_pass}" and prompt "${prompt}" and password "${password}"</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:14:26.163099" elapsed="0.000576"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-09-29T01:14:26.164382" level="INFO">${conn_id} = 7</msg>
<var>${conn_id}</var>
<arg>${system}</arg>
<arg>prompt=${prompt}</arg>
<arg>timeout=${prompt_timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-09-29T01:14:26.163897" elapsed="0.000520"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-29T01:14:26.165593" level="INFO">Length is 0.</msg>
<msg time="2026-09-29T01:14:26.165704" level="INFO">${pwd_length} = 0</msg>
<var>${pwd_length}</var>
<arg>${password}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-09-29T01:14:26.165154" elapsed="0.000581"/>
</kw>
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${pwd_length} &gt; 0</arg>
<arg>SSHLibrary.Login</arg>
<arg>${user}</arg>
<arg>${password}</arg>
<arg>delay=${delay}</arg>
<doc>Conditionally runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-29T01:14:26.165919" elapsed="0.000424"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-09-29T01:14:26.167448" level="INFO">Logging into '10.30.171.66:22' as 'jenkins'.</msg>
<msg time="2026-09-29T01:14:26.790571" level="INFO">Read output: Welcome to Ubuntu 22.04.5 LTS (GNU/Linux 5.15.0-187-generic x86_64)

 * Documentation:  https://help.ubuntu.com
 * Management:     https://landscape.canonical.com
 * Support:        https://ubuntu.com/pro

 System information as of Tue Sep 29 01:14:26 UTC 2026

  System load:  1.01               Processes:             104
  Usage of /:   19.8% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.171.66
  Swap usage:   0%


Expanded Security Maintenance for Applications is not enabled.

5 updates can be applied immediately.
To see these additional updates run: apt list --upgradable

14 additional security updates can be applied with ESM Apps.
Learn more about enabling ESM Apps service at https://ubuntu.com/esm


The list of available updates is more than a week old.
To check for new updates run: sudo apt update
New release '24.04.5 LTS' available.
Run 'do-release-upgrade' to upgrade to it.


Last login: Tue Sep 29 01:04:19 2026 from 10.30.170.36
[?2004h[jenkins@releng-48317-851-1-mininet-ovs-217-0 ~]&gt;</msg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Logs into the SSH server using key-based authentication.</doc>
<status status="PASS" start="2026-09-29T01:14:26.167057" elapsed="0.623801"/>
</kw>
<msg time="2026-09-29T01:14:26.790998" level="INFO">Returning from the enclosing user keyword.</msg>
<arg>SSHLibrary.Login With Public Key</arg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-29T01:14:26.166567" elapsed="0.624584"/>
</kw>
<arg>${user}</arg>
<arg>${password}</arg>
<doc>On active SSH session: if given non-empty password, do Login, else do Login With Public Key.</doc>
<status status="PASS" start="2026-09-29T01:14:26.164680" elapsed="0.626681"/>
</kw>
<kw name="Execute Command" owner="SSHLibrary">
<msg time="2026-09-29T01:14:26.792092" level="INFO">Executing command '/usr/local/bin/cbench -c 10.30.170.255 -t -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633'.</msg>
<msg time="2026-09-29T01:24:32.860793" level="INFO">Command exited with return code 0.</msg>
<msg time="2026-09-29T01:24:32.861571" level="INFO">${stdout} = </msg>
<msg time="2026-09-29T01:24:32.861678" level="INFO">${stderr} = cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.170.255:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with...</msg>
<var>${stdout}</var>
<var>${stderr}</var>
<arg>${cmd}</arg>
<arg>return_stderr=True</arg>
<doc>Executes ``command`` on the remote machine and returns its outputs.</doc>
<status status="PASS" start="2026-09-29T01:14:26.791735" elapsed="606.070011"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-29T01:24:32.862313" elapsed="0.000805"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.864528" level="INFO">cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.170.255:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100 unique source MACs per switch
   learning destination mac addresses before the test
   starting test with 5000 ms delay after features_reply
   ignoring first 1 "warmup" and last 0 "cooldown" loops
   debugging info is off
Master Thread: Creating thread-0! 
Thread-0: All switches initialized. Running at node 10.30.170.255. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
01:15:31.929 16  switches: flows:  547408 541728 538311 540214 535206 542160 534086 536499 530479 540080 539243 535010 537026 530031 537667 530005  total = 143.252550 per ms 
01:16:32.038 16  switches: flows:  544257 537460 542105 541518 545562 538820 542502 538504 542456 541402 535115 543894 539492 537076 537690 540780  total = 143.883675 per ms 
01:17:32.140 16  switches: flows:  574128 573427 577915 572985 574968 574616 569157 575718 577560 573057 578580 582450 575712 569318 575796 579484  total = 153.157625 per ms 
01:18:32.243 16  switches: flows:  572914 562343 561198 566437 569365 566274 566123 564178 567861 566359 567386 562113 558713 558391 560154 566699  total = 150.351426 per ms 
01:19:32.345 16  switches: flows:  598910 583426 598466 594299 593401 596930 590526 590881 597162 589034 598772 586371 594604 591708 586332 596166  total = 157.850830 per ms 
01:20:32.446 16  switches: flows:  568313 565726 557671 562534 560658 567171 565771 567685 563931 568833 559658 563199 566602 564663 567583 567517  total = 150.372837 per ms 
01:21:32.548 16  switches: flows:  561068 558826 556700 553496 558341 562195 558639 555316 549117 559725 555611 553490 562850 555033 563313 557824  total = 148.441810 per ms 
01:22:32.650 16  switches: flows:  545525 543489 537419 542418 550102 543175 540397 544293 539295 542789 539904 544383 549154 542702 542499 549483  total = 144.707163 per ms 
01:23:32.752 16  switches: flows:  554689 552888 550051 542298 555770 556925 555082 549271 556850 558509 554268 550829 549529 554420 547756 550626  total = 147.081153 per ms 
01:24:32.854 16  switches: flows:  540616 539655 539684 533557 537190 542353 542276 540563 540904 534512 539549 541514 545311 533538 538775 540170  total = 143.594834 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 143594.83/157850.83/148826.82/49806.63 responses/s</msg>
<arg>${stderr}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:24:32.863708" elapsed="0.001142"/>
</kw>
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot;">
<return>
<value>${stderr}</value>
<status status="PASS" start="2026-09-29T01:24:32.865377" elapsed="0.000120"/>
</return>
<status status="PASS" start="2026-09-29T01:24:32.865048" elapsed="0.000548"/>
</branch>
<status status="PASS" start="2026-09-29T01:24:32.864992" elapsed="0.000670"/>
</if>
<if>
<branch type="IF" condition="&quot;${return_stderr}&quot;!=&quot;True&quot;">
<return>
<value>${stdout}</value>
<status status="NOT RUN" start="2026-09-29T01:24:32.865861" elapsed="0.000027"/>
</return>
<status status="NOT RUN" start="2026-09-29T01:24:32.865787" elapsed="0.000157"/>
</branch>
<status status="NOT RUN" start="2026-09-29T01:24:32.865749" elapsed="0.000237"/>
</if>
<return>
<value>${stdout}</value>
<value>${stderr}</value>
<status status="NOT RUN" start="2026-09-29T01:24:32.866048" elapsed="0.000027"/>
</return>
<kw name="Restore_Current_Ssh_Connection_From_Index" owner="SSHKeywords" type="TEARDOWN">
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${connection_index} is not None</arg>
<arg>SSHLibrary.Switch Connection</arg>
<arg>${connection_index}</arg>
<doc>Conditionally runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-29T01:24:32.867789" elapsed="0.000734"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<arg>127.0.0.1</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-09-29T01:24:32.868937" elapsed="0.000385"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-29T01:24:32.869698" elapsed="0.000251"/>
</kw>
<arg>${current_ssh_connection.index}</arg>
<doc>Restore active SSH connection in SSHLibrary to given index.</doc>
<status status="PASS" start="2026-09-29T01:24:32.866798" elapsed="0.003263"/>
</kw>
<msg time="2026-09-29T01:24:32.870337" level="INFO">${output} = cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.170.255:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with...</msg>
<var>${output}</var>
<arg>${cbench_system}</arg>
<arg>${cbench_executable} -c ${ODL_SYSTEM_IP} ${cbench_args}</arg>
<arg>prompt_timeout=${test_timeout}</arg>
<arg>return_stdout=False</arg>
<arg>return_stderr=True</arg>
<doc>Reduces the common work of running a command on a remote system to a single higher level
robot keyword, taking care to log in with a public key and. The command given is written and the return value
depends on the passed argument values of return_stdout (default: True) and return_stderr (default: False).
At least one should be True, or the keyword will exit and FAIL. If both are True, the resulting return value
will be a two element list containing both. Otherwise the resulting return value is a string.
No test conditions are checked.</doc>
<status status="PASS" start="2026-09-29T01:14:26.161017" elapsed="606.709400"/>
</kw>
<status status="PASS" start="2026-09-29T01:14:26.160600" elapsed="606.709922"/>
</branch>
<status status="PASS" start="2026-09-29T01:14:26.160066" elapsed="606.710505"/>
</if>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.871674" level="INFO">cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.170.255:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100 unique source MACs per switch
   learning destination mac addresses before the test
   starting test with 5000 ms delay after features_reply
   ignoring first 1 "warmup" and last 0 "cooldown" loops
   debugging info is off
Master Thread: Creating thread-0! 
Thread-0: All switches initialized. Running at node 10.30.170.255. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
01:15:31.929 16  switches: flows:  547408 541728 538311 540214 535206 542160 534086 536499 530479 540080 539243 535010 537026 530031 537667 530005  total = 143.252550 per ms 
01:16:32.038 16  switches: flows:  544257 537460 542105 541518 545562 538820 542502 538504 542456 541402 535115 543894 539492 537076 537690 540780  total = 143.883675 per ms 
01:17:32.140 16  switches: flows:  574128 573427 577915 572985 574968 574616 569157 575718 577560 573057 578580 582450 575712 569318 575796 579484  total = 153.157625 per ms 
01:18:32.243 16  switches: flows:  572914 562343 561198 566437 569365 566274 566123 564178 567861 566359 567386 562113 558713 558391 560154 566699  total = 150.351426 per ms 
01:19:32.345 16  switches: flows:  598910 583426 598466 594299 593401 596930 590526 590881 597162 589034 598772 586371 594604 591708 586332 596166  total = 157.850830 per ms 
01:20:32.446 16  switches: flows:  568313 565726 557671 562534 560658 567171 565771 567685 563931 568833 559658 563199 566602 564663 567583 567517  total = 150.372837 per ms 
01:21:32.548 16  switches: flows:  561068 558826 556700 553496 558341 562195 558639 555316 549117 559725 555611 553490 562850 555033 563313 557824  total = 148.441810 per ms 
01:22:32.650 16  switches: flows:  545525 543489 537419 542418 550102 543175 540397 544293 539295 542789 539904 544383 549154 542702 542499 549483  total = 144.707163 per ms 
01:23:32.752 16  switches: flows:  554689 552888 550051 542298 555770 556925 555082 549271 556850 558509 554268 550829 549529 554420 547756 550626  total = 147.081153 per ms 
01:24:32.854 16  switches: flows:  540616 539655 539684 533557 537190 542353 542276 540563 540904 534512 539549 541514 545311 533538 538775 540170  total = 143.594834 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 143594.83/157850.83/148826.82/49806.63 responses/s</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:24:32.871000" elapsed="0.000829"/>
</kw>
<kw name="Should Contain" owner="BuiltIn">
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Fails if the `container` does not contain the `item` one or more times.</doc>
<status status="PASS" start="2026-09-29T01:24:32.872205" elapsed="0.000851"/>
</kw>
<kw name="Get Lines Containing String" owner="String">
<msg time="2026-09-29T01:24:32.873927" level="INFO">1 out of 23 lines matched.</msg>
<msg time="2026-09-29T01:24:32.874139" level="INFO">${result_line} = RESULT: 16 switches 9 tests min/max/avg/stdev = 143594.83/157850.83/148826.82/49806.63 responses/s</msg>
<var>${result_line}</var>
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Returns lines of the given `string` that contain the `pattern`.</doc>
<status status="PASS" start="2026-09-29T01:24:32.873493" elapsed="0.000700"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-29T01:24:32.875120" level="INFO">@{results_list} = [ RESULT: | 16 | switches | 9 | tests | min/max/avg/stdev | = | 143594.83/157850.83/148826.82/49806.63 | responses/s ]</msg>
<var>@{results_list}</var>
<arg>${result_line}</arg>
<doc>Splits the `string` using `separator` as a delimiter string.</doc>
<status status="PASS" start="2026-09-29T01:24:32.874606" elapsed="0.000574"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.876447" level="INFO">min/max/avg/stdev</msg>
<arg>${results_list[5]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:24:32.875657" elapsed="0.000910"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.877761" level="INFO">143594.83/157850.83/148826.82/49806.63</msg>
<arg>${results_list[7]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:24:32.876978" elapsed="0.000898"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-29T01:24:32.878964" level="INFO">@{result_name_list} = [ min | max | avg | stdev ]</msg>
<var>@{result_name_list}</var>
<arg>${results_list[5]}</arg>
<arg>/</arg>
<doc>Splits the `string` using `separator` as a delimiter string.</doc>
<status status="PASS" start="2026-09-29T01:24:32.878282" elapsed="0.000740"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-29T01:24:32.880166" level="INFO">@{result_value_list} = [ 143594.83 | 157850.83 | 148826.82 | 49806.63 ]</msg>
<var>@{result_value_list}</var>
<arg>${results_list[7]}</arg>
<arg>/</arg>
<doc>Splits the `string` using `separator` as a delimiter string.</doc>
<status status="PASS" start="2026-09-29T01:24:32.879525" elapsed="0.000697"/>
</kw>
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.881306" level="INFO">Length is 4.</msg>
<msg time="2026-09-29T01:24:32.881562" level="INFO">${num_stats} = 4</msg>
<var>${num_stats}</var>
<arg>${result_name_list}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-09-29T01:24:32.880658" elapsed="0.000957"/>
</kw>
<for flavor="IN RANGE">
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.883622" level="INFO">min :: 143594.83</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:24:32.882609" elapsed="0.001133"/>
</kw>
<var name="${i}">0</var>
<status status="PASS" start="2026-09-29T01:24:32.882101" elapsed="0.001709"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.885373" level="INFO">max :: 157850.83</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:24:32.884389" elapsed="0.001125"/>
</kw>
<var name="${i}">1</var>
<status status="PASS" start="2026-09-29T01:24:32.883989" elapsed="0.001617"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.887166" level="INFO">avg :: 148826.82</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:24:32.886169" elapsed="0.001149"/>
</kw>
<var name="${i}">2</var>
<status status="PASS" start="2026-09-29T01:24:32.885794" elapsed="0.001594"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.889016" level="INFO">stdev :: 49806.63</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:24:32.887986" elapsed="0.001146"/>
</kw>
<var name="${i}">3</var>
<status status="PASS" start="2026-09-29T01:24:32.887590" elapsed="0.001611"/>
</iter>
<var>${i}</var>
<value>0</value>
<value>${num_stats}</value>
<status status="PASS" start="2026-09-29T01:24:32.881744" elapsed="0.007563"/>
</for>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.890638" level="INFO">${min} = 143594.83</msg>
<var>${min}</var>
<arg>${result_value_list[${0}]}</arg>
<doc>Returns the given values which can then be assigned to a variable.</doc>
<status status="PASS" start="2026-09-29T01:24:32.889682" elapsed="0.001011"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.892016" level="INFO">${max} = 157850.83</msg>
<var>${max}</var>
<arg>${result_value_list[${1}]}</arg>
<doc>Returns the given values which can then be assigned to a variable.</doc>
<status status="PASS" start="2026-09-29T01:24:32.891059" elapsed="0.001012"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.893367" level="INFO">${average} = 148826.82</msg>
<var>${average}</var>
<arg>${result_value_list[${2}]}</arg>
<doc>Returns the given values which can then be assigned to a variable.</doc>
<status status="PASS" start="2026-09-29T01:24:32.892467" elapsed="0.000976"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.894715" level="INFO">${stdev} = 49806.63</msg>
<var>${stdev}</var>
<arg>${result_value_list[${3}]}</arg>
<doc>Returns the given values which can then be assigned to a variable.</doc>
<status status="PASS" start="2026-09-29T01:24:32.893780" elapsed="0.001018"/>
</kw>
<kw name="Get Time" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.896088" level="INFO">${date} = 2026-09-29 01:24:32</msg>
<var>${date}</var>
<arg>d,m,s</arg>
<doc>Returns the given time in the requested format.</doc>
<status status="PASS" start="2026-09-29T01:24:32.895278" elapsed="0.000873"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.897504" level="INFO">CBench Result: 2026-09-29 01:24:32,-t -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633,143594.83,157850.83,148826.82,49806.63</msg>
<arg>CBench Result: ${date},${cbench_args},${min},${max},${average},${stdev}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:24:32.896679" elapsed="0.001012"/>
</kw>
<kw name="Log Results And Determine Status">
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-09-29T01:24:32.899834" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-titanium/throughput.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-titanium/throughput.csv&lt;/a&gt;'.</msg>
<arg>${output_file}</arg>
<arg>${min},${max},${average}\n</arg>
<doc>Appends the given content to the specified file.</doc>
<status status="PASS" start="2026-09-29T01:24:32.899002" elapsed="0.000972"/>
</kw>
<kw name="Should Be True" owner="BuiltIn">
<arg>${average} &gt; ${threshold}</arg>
<arg>${average} flow_mods per/sec did not exceed threshold of ${threshold}</arg>
<doc>Fails if the given condition is not true.</doc>
<status status="PASS" start="2026-09-29T01:24:32.900503" elapsed="0.001303"/>
</kw>
<arg>${min}</arg>
<arg>${max}</arg>
<arg>${average}</arg>
<arg>${average_threshold}</arg>
<arg>${output_filename}</arg>
<status status="PASS" start="2026-09-29T01:24:32.897990" elapsed="0.003953"/>
</kw>
<arg>-t -m ${duration_in_ms} -M ${num_of_unique_macs} -S ${switch_count} -l ${loops} -D ${start_delay} -p ${of_port}</arg>
<arg>${throughput_threshold}</arg>
<arg>${throughput_results_file}</arg>
<status status="PASS" start="2026-09-29T01:14:26.158835" elapsed="606.743227"/>
</kw>
<kw name="Sleep" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.903124" level="INFO">Slept 0 seconds.</msg>
<arg>${test_delay}</arg>
<doc>Pauses execution for the given time.</doc>
<status status="PASS" start="2026-09-29T01:24:32.902475" elapsed="0.000807"/>
</kw>
<kw name="Log Results As Zero If Cbench Timed Out" type="TEARDOWN">
<kw name="Run Keyword If Timeout Occurred" owner="BuiltIn">
<arg>Log Results And Determine Status</arg>
<arg>0</arg>
<arg>0</arg>
<arg>0</arg>
<arg>0</arg>
<arg>${output_filename}</arg>
<doc>Runs the given keyword if either a test or a keyword timeout has occurred.</doc>
<status status="PASS" start="2026-09-29T01:24:32.904497" elapsed="0.000380"/>
</kw>
<status status="PASS" start="2026-09-29T01:24:32.903749" elapsed="0.001218"/>
</kw>
<doc>cbench executed in throughput mode (-t). Test parameters have defaults, but can be overridden
on the pybot command line</doc>
<tag>cbench</tag>
<tag>throughput</tag>
<timeout value="15 minutes"/>
<status status="PASS" start="2026-09-29T01:14:26.156491" elapsed="606.748525"/>
</test>
<test id="s1-t3" name="Rerun Latency Test To Check Bug 6176" line="65">
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.907086" level="INFO">Cbench tests using 10 iterations of 60 second tests. Switch Count: 16. Unique MACS to cycle: 100</msg>
<arg>Cbench tests using ${loops} iterations of ${duration_in_secs} second tests. Switch Count: ${switch_count}. Unique MACS to cycle: ${num_of_unique_macs}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:24:32.906629" elapsed="0.000531"/>
</kw>
<kw name="Run Cbench And Log Results">
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.909013" level="INFO">${output_filename} = bug.csv</msg>
<arg>${output_filename}</arg>
<doc>Makes the variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-09-29T01:24:32.908308" elapsed="0.000761"/>
</kw>
<if>
<branch type="IF" condition="&quot;${cbench_system}&quot; == &quot;localhost&quot;">
<kw name="Run" owner="OperatingSystem">
<var>${output}</var>
<arg>${cbench_executable} -c ${ODL_SYSTEM_IP} ${cbench_args}</arg>
<doc>*This keyword is considered deprecated. Use the [Process] library instead.*</doc>
<status status="NOT RUN" start="2026-09-29T01:24:32.909557" elapsed="0.000029"/>
</kw>
<status status="NOT RUN" start="2026-09-29T01:24:32.909209" elapsed="0.000427"/>
</branch>
<branch type="ELSE">
<kw name="Run Command On Remote System" owner="Utils">
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot; and &quot;${return_stderr}&quot;!=&quot;True&quot;">
<kw name="Fail" owner="BuiltIn">
<arg>At least one of {return_stdout} or {return_stderr} args should be set to True</arg>
<doc>Fails the test or task with the given message and optionally alters its tags.</doc>
<status status="NOT RUN" start="2026-09-29T01:24:32.911144" elapsed="0.000027"/>
</kw>
<status status="NOT RUN" start="2026-09-29T01:24:32.910840" elapsed="0.000376"/>
</branch>
<status status="PASS" start="2026-09-29T01:24:32.910815" elapsed="0.000454"/>
</if>
<kw name="Get Connection" owner="SSHLibrary">
<msg time="2026-09-29T01:24:32.911799" level="INFO">index=None
host=None
alias=None
port=22
timeout=3 seconds
newline=

prompt=None
term_type=vt100
width=80
height=24
path_separator=/
encoding=utf8
escape_ansi=False
encoding_errors=strict</msg>
<msg time="2026-09-29T01:24:32.911951" level="INFO">${current_ssh_connection} = index=None
host=None
alias=None
port=22
timeout=3 seconds
newline=

prompt=None
term_type=vt100
width=80
height=24
path_separator=/
encoding=utf8
escape_ansi=False
encoding_errors=strict</msg>
<var>${current_ssh_connection}</var>
<doc>Returns information about the connection.</doc>
<status status="PASS" start="2026-09-29T01:24:32.911552" elapsed="0.000433"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.912726" level="INFO">Attempting to execute command "/usr/local/bin/cbench -c 10.30.170.255 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633" on remote system "10.30.171.66" by user "jenkins" with keyfile pass "any" and prompt "&gt;" and password ""</msg>
<arg>Attempting to execute command "${cmd}" on remote system "${system}" by user "${user}" with keyfile pass "${keyfile_pass}" and prompt "${prompt}" and password "${password}"</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:24:32.912225" elapsed="0.000592"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-09-29T01:24:32.913548" level="INFO">${conn_id} = 9</msg>
<var>${conn_id}</var>
<arg>${system}</arg>
<arg>prompt=${prompt}</arg>
<arg>timeout=${prompt_timeout}</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-09-29T01:24:32.913043" elapsed="0.000540"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-29T01:24:32.914765" level="INFO">Length is 0.</msg>
<msg time="2026-09-29T01:24:32.914894" level="INFO">${pwd_length} = 0</msg>
<var>${pwd_length}</var>
<arg>${password}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-09-29T01:24:32.914351" elapsed="0.000577"/>
</kw>
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${pwd_length} &gt; 0</arg>
<arg>SSHLibrary.Login</arg>
<arg>${user}</arg>
<arg>${password}</arg>
<arg>delay=${delay}</arg>
<doc>Conditionally runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-29T01:24:32.915113" elapsed="0.000442"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-09-29T01:24:32.916732" level="INFO">Logging into '10.30.171.66:22' as 'jenkins'.</msg>
<msg time="2026-09-29T01:24:33.531782" level="INFO">Read output: Welcome to Ubuntu 22.04.5 LTS (GNU/Linux 5.15.0-187-generic x86_64)

 * Documentation:  https://help.ubuntu.com
 * Management:     https://landscape.canonical.com
 * Support:        https://ubuntu.com/pro

 System information as of Tue Sep 29 01:24:33 UTC 2026

  System load:  0.52               Processes:             106
  Usage of /:   19.8% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.171.66
  Swap usage:   0%


Expanded Security Maintenance for Applications is not enabled.

5 updates can be applied immediately.
To see these additional updates run: apt list --upgradable

14 additional security updates can be applied with ESM Apps.
Learn more about enabling ESM Apps service at https://ubuntu.com/esm


The list of available updates is more than a week old.
To check for new updates run: sudo apt update
New release '24.04.5 LTS' available.
Run 'do-release-upgrade' to upgrade to it.


Last login: Tue Sep 29 01:14:26 2026 from 10.30.170.36
[?2004h[jenkins@releng-48317-851-1-mininet-ovs-217-0 ~]&gt;</msg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Logs into the SSH server using key-based authentication.</doc>
<status status="PASS" start="2026-09-29T01:24:32.916382" elapsed="0.615602"/>
</kw>
<msg time="2026-09-29T01:24:33.532067" level="INFO">Returning from the enclosing user keyword.</msg>
<arg>SSHLibrary.Login With Public Key</arg>
<arg>${user}</arg>
<arg>${USER_HOME}/.ssh/${SSH_KEY}</arg>
<arg>${KEYFILE_PASS}</arg>
<arg>delay=${delay}</arg>
<doc>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-29T01:24:32.915821" elapsed="0.616330"/>
</kw>
<arg>${user}</arg>
<arg>${password}</arg>
<doc>On active SSH session: if given non-empty password, do Login, else do Login With Public Key.</doc>
<status status="PASS" start="2026-09-29T01:24:32.913854" elapsed="0.618420"/>
</kw>
<kw name="Execute Command" owner="SSHLibrary">
<msg time="2026-09-29T01:24:33.532836" level="INFO">Executing command '/usr/local/bin/cbench -c 10.30.170.255 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633'.</msg>
<msg time="2026-09-29T01:34:39.600427" level="INFO">Command exited with return code 0.</msg>
<msg time="2026-09-29T01:34:39.601139" level="INFO">${stdout} = </msg>
<msg time="2026-09-29T01:34:39.601272" level="INFO">${stderr} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.170.255:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 10...</msg>
<var>${stdout}</var>
<var>${stderr}</var>
<arg>${cmd}</arg>
<arg>return_stderr=True</arg>
<doc>Executes ``command`` on the remote machine and returns its outputs.</doc>
<status status="PASS" start="2026-09-29T01:24:33.532549" elapsed="606.068795"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-29T01:34:39.601885" elapsed="0.000712"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.603920" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.170.255:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100 unique source MACs per switch
   learning destination mac addresses before the test
   starting test with 5000 ms delay after features_reply
   ignoring first 1 "warmup" and last 0 "cooldown" loops
   debugging info is off
Master Thread: Creating thread-0! 
Thread-0: All switches initialized. Running at node 10.30.170.255. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
01:25:38.679 16  switches: flows:  98534 99153 98958 99994 104223 96143 98241 97805 94902 98107 95486 91206 95416 94155 96576 93440  total = 25.872317 per ms 
01:26:38.780 16  switches: flows:  98668 99995 103202 100765 95003 96322 97157 98337 95674 98367 93521 92183 93603 94448 96941 95499  total = 25.785043 per ms 
01:27:38.881 16  switches: flows:  96971 97834 102958 100111 95304 96832 96321 98164 92424 95979 93086 91477 93145 96034 95631 94840  total = 25.575828 per ms 
01:28:38.981 16  switches: flows:  99520 101782 106241 101227 97007 97280 96918 97017 93923 98488 92330 92597 94080 95953 97926 97995  total = 25.961419 per ms 
01:29:39.082 16  switches: flows:  98250 100284 102366 100571 94684 97480 94800 97385 93134 97796 93306 91550 93844 96149 96665 94276  total = 25.666147 per ms 
01:30:39.183 16  switches: flows:  101242 103323 107619 101937 96379 98908 98738 99936 94740 98695 96328 93786 95647 99355 97858 98557  total = 26.340145 per ms 
01:31:39.283 16  switches: flows:  99854 99606 102754 100653 95733 96090 99540 99568 94726 96265 93825 94189 95138 95098 98305 98204  total = 25.949121 per ms 
01:32:39.384 16  switches: flows:  98470 98451 101772 98693 95068 96918 95071 97398 94412 95493 91401 93645 95480 94616 95445 95700  total = 25.591141 per ms 
01:33:39.485 16  switches: flows:  99162 99773 104475 100252 95458 96906 95370 97542 95005 96836 93432 94743 97343 94517 95644 98725  total = 25.876515 per ms 
01:34:39.586 16  switches: flows:  99118 98761 102238 97755 96740 97575 96639 98196 94504 95312 92837 92063 96423 94931 94674 97322  total = 25.708551 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 25575.83/26340.14/25828.21/8612.37 responses/s</msg>
<arg>${stderr}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:34:39.603129" elapsed="0.001068"/>
</kw>
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot;">
<return>
<value>${stderr}</value>
<status status="PASS" start="2026-09-29T01:34:39.604702" elapsed="0.000111"/>
</return>
<status status="PASS" start="2026-09-29T01:34:39.604421" elapsed="0.000474"/>
</branch>
<status status="PASS" start="2026-09-29T01:34:39.604370" elapsed="0.000579"/>
</if>
<if>
<branch type="IF" condition="&quot;${return_stderr}&quot;!=&quot;True&quot;">
<return>
<value>${stdout}</value>
<status status="NOT RUN" start="2026-09-29T01:34:39.605140" elapsed="0.000029"/>
</return>
<status status="NOT RUN" start="2026-09-29T01:34:39.605067" elapsed="0.000159"/>
</branch>
<status status="NOT RUN" start="2026-09-29T01:34:39.605035" elapsed="0.000271"/>
</if>
<return>
<value>${stdout}</value>
<value>${stderr}</value>
<status status="NOT RUN" start="2026-09-29T01:34:39.605371" elapsed="0.000028"/>
</return>
<kw name="Restore_Current_Ssh_Connection_From_Index" owner="SSHKeywords" type="TEARDOWN">
<kw name="Run Keyword And Return If" owner="BuiltIn">
<arg>${connection_index} is not None</arg>
<arg>SSHLibrary.Switch Connection</arg>
<arg>${connection_index}</arg>
<doc>Conditionally runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-29T01:34:39.607055" elapsed="0.000721"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<arg>127.0.0.1</arg>
<doc>Opens a new SSH connection to the given ``host`` and ``port``.</doc>
<status status="PASS" start="2026-09-29T01:34:39.608174" elapsed="0.000367"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-29T01:34:39.608845" elapsed="0.000234"/>
</kw>
<arg>${current_ssh_connection.index}</arg>
<doc>Restore active SSH connection in SSHLibrary to given index.</doc>
<status status="PASS" start="2026-09-29T01:34:39.606062" elapsed="0.003129"/>
</kw>
<msg time="2026-09-29T01:34:39.609459" level="INFO">${output} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.170.255:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 10...</msg>
<var>${output}</var>
<arg>${cbench_system}</arg>
<arg>${cbench_executable} -c ${ODL_SYSTEM_IP} ${cbench_args}</arg>
<arg>prompt_timeout=${test_timeout}</arg>
<arg>return_stdout=False</arg>
<arg>return_stderr=True</arg>
<doc>Reduces the common work of running a command on a remote system to a single higher level
robot keyword, taking care to log in with a public key and. The command given is written and the return value
depends on the passed argument values of return_stdout (default: True) and return_stderr (default: False).
At least one should be True, or the keyword will exit and FAIL. If both are True, the resulting return value
will be a two element list containing both. Otherwise the resulting return value is a string.
No test conditions are checked.</doc>
<status status="PASS" start="2026-09-29T01:24:32.910089" elapsed="606.699427"/>
</kw>
<status status="PASS" start="2026-09-29T01:24:32.909680" elapsed="606.699935"/>
</branch>
<status status="PASS" start="2026-09-29T01:24:32.909181" elapsed="606.700505"/>
</if>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.610765" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.170.255:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100 unique source MACs per switch
   learning destination mac addresses before the test
   starting test with 5000 ms delay after features_reply
   ignoring first 1 "warmup" and last 0 "cooldown" loops
   debugging info is off
Master Thread: Creating thread-0! 
Thread-0: All switches initialized. Running at node 10.30.170.255. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
01:25:38.679 16  switches: flows:  98534 99153 98958 99994 104223 96143 98241 97805 94902 98107 95486 91206 95416 94155 96576 93440  total = 25.872317 per ms 
01:26:38.780 16  switches: flows:  98668 99995 103202 100765 95003 96322 97157 98337 95674 98367 93521 92183 93603 94448 96941 95499  total = 25.785043 per ms 
01:27:38.881 16  switches: flows:  96971 97834 102958 100111 95304 96832 96321 98164 92424 95979 93086 91477 93145 96034 95631 94840  total = 25.575828 per ms 
01:28:38.981 16  switches: flows:  99520 101782 106241 101227 97007 97280 96918 97017 93923 98488 92330 92597 94080 95953 97926 97995  total = 25.961419 per ms 
01:29:39.082 16  switches: flows:  98250 100284 102366 100571 94684 97480 94800 97385 93134 97796 93306 91550 93844 96149 96665 94276  total = 25.666147 per ms 
01:30:39.183 16  switches: flows:  101242 103323 107619 101937 96379 98908 98738 99936 94740 98695 96328 93786 95647 99355 97858 98557  total = 26.340145 per ms 
01:31:39.283 16  switches: flows:  99854 99606 102754 100653 95733 96090 99540 99568 94726 96265 93825 94189 95138 95098 98305 98204  total = 25.949121 per ms 
01:32:39.384 16  switches: flows:  98470 98451 101772 98693 95068 96918 95071 97398 94412 95493 91401 93645 95480 94616 95445 95700  total = 25.591141 per ms 
01:33:39.485 16  switches: flows:  99162 99773 104475 100252 95458 96906 95370 97542 95005 96836 93432 94743 97343 94517 95644 98725  total = 25.876515 per ms 
01:34:39.586 16  switches: flows:  99118 98761 102238 97755 96740 97575 96639 98196 94504 95312 92837 92063 96423 94931 94674 97322  total = 25.708551 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 25575.83/26340.14/25828.21/8612.37 responses/s</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:34:39.610119" elapsed="0.000800"/>
</kw>
<kw name="Should Contain" owner="BuiltIn">
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Fails if the `container` does not contain the `item` one or more times.</doc>
<status status="PASS" start="2026-09-29T01:34:39.611325" elapsed="0.000765"/>
</kw>
<kw name="Get Lines Containing String" owner="String">
<msg time="2026-09-29T01:34:39.612954" level="INFO">1 out of 23 lines matched.</msg>
<msg time="2026-09-29T01:34:39.613148" level="INFO">${result_line} = RESULT: 16 switches 9 tests min/max/avg/stdev = 25575.83/26340.14/25828.21/8612.37 responses/s</msg>
<var>${result_line}</var>
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Returns lines of the given `string` that contain the `pattern`.</doc>
<status status="PASS" start="2026-09-29T01:34:39.612513" elapsed="0.000702"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-29T01:34:39.614135" level="INFO">@{results_list} = [ RESULT: | 16 | switches | 9 | tests | min/max/avg/stdev | = | 25575.83/26340.14/25828.21/8612.37 | responses/s ]</msg>
<var>@{results_list}</var>
<arg>${result_line}</arg>
<doc>Splits the `string` using `separator` as a delimiter string.</doc>
<status status="PASS" start="2026-09-29T01:34:39.613605" elapsed="0.000598"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.615428" level="INFO">min/max/avg/stdev</msg>
<arg>${results_list[5]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:34:39.614682" elapsed="0.000859"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.616748" level="INFO">25575.83/26340.14/25828.21/8612.37</msg>
<arg>${results_list[7]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:34:39.615977" elapsed="0.000887"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-29T01:34:39.617943" level="INFO">@{result_name_list} = [ min | max | avg | stdev ]</msg>
<var>@{result_name_list}</var>
<arg>${results_list[5]}</arg>
<arg>/</arg>
<doc>Splits the `string` using `separator` as a delimiter string.</doc>
<status status="PASS" start="2026-09-29T01:34:39.617207" elapsed="0.000791"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-29T01:34:39.619138" level="INFO">@{result_value_list} = [ 25575.83 | 26340.14 | 25828.21 | 8612.37 ]</msg>
<var>@{result_value_list}</var>
<arg>${results_list[7]}</arg>
<arg>/</arg>
<doc>Splits the `string` using `separator` as a delimiter string.</doc>
<status status="PASS" start="2026-09-29T01:34:39.618475" elapsed="0.000721"/>
</kw>
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.620270" level="INFO">Length is 4.</msg>
<msg time="2026-09-29T01:34:39.620455" level="INFO">${num_stats} = 4</msg>
<var>${num_stats}</var>
<arg>${result_name_list}</arg>
<doc>Returns and logs the length of the given item as an integer.</doc>
<status status="PASS" start="2026-09-29T01:34:39.619602" elapsed="0.000920"/>
</kw>
<for flavor="IN RANGE">
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.622414" level="INFO">min :: 25575.83</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:34:39.621417" elapsed="0.001111"/>
</kw>
<var name="${i}">0</var>
<status status="PASS" start="2026-09-29T01:34:39.621016" elapsed="0.001597"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.624161" level="INFO">max :: 26340.14</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:34:39.623192" elapsed="0.001120"/>
</kw>
<var name="${i}">1</var>
<status status="PASS" start="2026-09-29T01:34:39.622826" elapsed="0.001556"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.626001" level="INFO">avg :: 25828.21</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:34:39.624998" elapsed="0.001117"/>
</kw>
<var name="${i}">2</var>
<status status="PASS" start="2026-09-29T01:34:39.624576" elapsed="0.001608"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.627763" level="INFO">stdev :: 8612.37</msg>
<arg>${result_name_list[${i}]} :: ${result_value_list[${i}]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:34:39.626776" elapsed="0.001125"/>
</kw>
<var name="${i}">3</var>
<status status="PASS" start="2026-09-29T01:34:39.626386" elapsed="0.001588"/>
</iter>
<var>${i}</var>
<value>0</value>
<value>${num_stats}</value>
<status status="PASS" start="2026-09-29T01:34:39.620676" elapsed="0.007357"/>
</for>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.629345" level="INFO">${min} = 25575.83</msg>
<var>${min}</var>
<arg>${result_value_list[${0}]}</arg>
<doc>Returns the given values which can then be assigned to a variable.</doc>
<status status="PASS" start="2026-09-29T01:34:39.628410" elapsed="0.000992"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.630706" level="INFO">${max} = 26340.14</msg>
<var>${max}</var>
<arg>${result_value_list[${1}]}</arg>
<doc>Returns the given values which can then be assigned to a variable.</doc>
<status status="PASS" start="2026-09-29T01:34:39.629764" elapsed="0.000999"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.632036" level="INFO">${average} = 25828.21</msg>
<var>${average}</var>
<arg>${result_value_list[${2}]}</arg>
<doc>Returns the given values which can then be assigned to a variable.</doc>
<status status="PASS" start="2026-09-29T01:34:39.631119" elapsed="0.000990"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.633412" level="INFO">${stdev} = 8612.37</msg>
<var>${stdev}</var>
<arg>${result_value_list[${3}]}</arg>
<doc>Returns the given values which can then be assigned to a variable.</doc>
<status status="PASS" start="2026-09-29T01:34:39.632476" elapsed="0.000990"/>
</kw>
<kw name="Get Time" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.634706" level="INFO">${date} = 2026-09-29 01:34:39</msg>
<var>${date}</var>
<arg>d,m,s</arg>
<doc>Returns the given time in the requested format.</doc>
<status status="PASS" start="2026-09-29T01:34:39.633902" elapsed="0.000861"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.636053" level="INFO">CBench Result: 2026-09-29 01:34:39,-m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633,25575.83,26340.14,25828.21,8612.37</msg>
<arg>CBench Result: ${date},${cbench_args},${min},${max},${average},${stdev}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-29T01:34:39.635195" elapsed="0.000973"/>
</kw>
<kw name="Log Results And Determine Status">
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-09-29T01:34:39.638740" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-titanium/bug.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-titanium/bug.csv&lt;/a&gt;'.</msg>
<arg>${output_file}</arg>
<arg>${min},${max},${average}\n</arg>
<doc>Appends the given content to the specified file.</doc>
<status status="PASS" start="2026-09-29T01:34:39.637500" elapsed="0.001391"/>
</kw>
<kw name="Should Be True" owner="BuiltIn">
<arg>${average} &gt; ${threshold}</arg>
<arg>${average} flow_mods per/sec did not exceed threshold of ${threshold}</arg>
<doc>Fails if the given condition is not true.</doc>
<status status="PASS" start="2026-09-29T01:34:39.639288" elapsed="0.001310"/>
</kw>
<arg>${min}</arg>
<arg>${max}</arg>
<arg>${average}</arg>
<arg>${average_threshold}</arg>
<arg>${output_filename}</arg>
<status status="PASS" start="2026-09-29T01:34:39.636521" elapsed="0.004251"/>
</kw>
<arg>-m ${duration_in_ms} -M ${num_of_unique_macs} -S ${switch_count} -l ${loops} -D ${start_delay} -p ${of_port}</arg>
<arg>${latency_threshold}</arg>
<arg>bug.csv</arg>
<status status="PASS" start="2026-09-29T01:24:32.907597" elapsed="606.733297"/>
</kw>
<kw name="Sleep" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.641822" level="INFO">Slept 0 seconds.</msg>
<arg>${test_delay}</arg>
<doc>Pauses execution for the given time.</doc>
<status status="PASS" start="2026-09-29T01:34:39.641212" elapsed="0.000685"/>
</kw>
<kw name="Report Failure Due To Bug" owner="Utils" type="TEARDOWN">
<kw name="Evaluate" owner="BuiltIn">
<msg time="2026-09-29T01:34:39.643531" level="INFO">${test_skipped} = False</msg>
<var>${test_skipped}</var>
<arg>len(re.findall('SKIPPED', """${TEST_MESSAGE}""")) &gt; 0</arg>
<arg>modules=re</arg>
<doc>Evaluates the given expression in Python and returns the result.</doc>
<status status="PASS" start="2026-09-29T01:34:39.642897" elapsed="0.000677"/>
</kw>
<if>
<branch type="IF" condition="('${TEST_STATUS}' != 'FAIL') or ${test_skipped}">
<return>
<status status="PASS" start="2026-09-29T01:34:39.643854" elapsed="0.000039"/>
</return>
<status status="PASS" start="2026-09-29T01:34:39.643698" elapsed="0.000234"/>
</branch>
<status status="PASS" start="2026-09-29T01:34:39.643671" elapsed="0.000292"/>
</if>
<kw name="Comment" owner="BuiltIn">
<arg>Jira tickets are {PROJECT}-{NUMBER} while Bugzilla tickets are {NUMBER}</arg>
<doc>Displays the given messages in the log file as keyword arguments.</doc>
<status status="NOT RUN" start="2026-09-29T01:34:39.644157" elapsed="0.000027"/>
</kw>
<kw name="Run Keyword And Return Status" owner="BuiltIn">
<var>${match}</var>
<arg>Should Contain</arg>
<arg>${number}</arg>
<arg>-</arg>
<doc>Runs the specified keyword and returns the status as a Boolean value.</doc>
<status status="NOT RUN" start="2026-09-29T01:34:39.644403" elapsed="0.000027"/>
</kw>
<kw name="Set Variable If" owner="BuiltIn">
<var>${bug_url}</var>
<arg>${match}</arg>
<arg>https://jira.opendaylight.org/browse/${number}</arg>
<arg>https://bugs.opendaylight.org/show_bug.cgi?id=${number}</arg>
<doc>Sets variable based on the given condition.</doc>
<status status="NOT RUN" start="2026-09-29T01:34:39.644672" elapsed="0.000030"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<var>${msg}</var>
<arg>This test fails due to ${bug_url}</arg>
<doc>Returns the given values which can then be assigned to a variable.</doc>
<status status="NOT RUN" start="2026-09-29T01:34:39.644891" elapsed="0.000025"/>
</kw>
<kw name="Evaluate" owner="BuiltIn">
<var>${newline}</var>
<arg>chr(10)</arg>
<doc>Evaluates the given expression in Python and returns the result.</doc>
<status status="NOT RUN" start="2026-09-29T01:34:39.645161" elapsed="0.000027"/>
</kw>
<kw name="Set Test Message" owner="BuiltIn">
<arg>${msg}${newline}${newline}${TEST_MESSAGE}</arg>
<doc>Sets message of the current test case.</doc>
<status status="NOT RUN" start="2026-09-29T01:34:39.645407" elapsed="0.000027"/>
</kw>
<kw name="Log" owner="BuiltIn">
<arg>${msg}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="NOT RUN" start="2026-09-29T01:34:39.645700" elapsed="0.000030"/>
</kw>
<if>
<branch type="IF" condition="&quot;${include_bug_in_tags}&quot;==&quot;True&quot;">
<kw name="Set Tags" owner="BuiltIn">
<arg>${bug_url}</arg>
<doc>Adds The given `tags` for the current test or all tests in a suite.</doc>
<status status="NOT RUN" start="2026-09-29T01:34:39.645998" elapsed="0.000025"/>
</kw>
<status status="NOT RUN" start="2026-09-29T01:34:39.645813" elapsed="0.000250"/>
</branch>
<status status="NOT RUN" start="2026-09-29T01:34:39.645791" elapsed="0.000302"/>
</if>
<arg>6176</arg>
<doc>Report that a test failed due to a known Bugzilla bug whose
number is provided as an argument.
Not FAILED (incl. SKIPPED) test are not reported.
This keyword must be used in the [Teardown] setting of the affected test
or as the first line of the test if FastFail module is not being
used. It reports the URL of the bug on console and also puts it
into the Robot log file.</doc>
<status status="PASS" start="2026-09-29T01:34:39.642390" elapsed="0.003769"/>
</kw>
<doc>cbench executed in latency mode to see if controller is healthy</doc>
<tag>cbench</tag>
<tag>latency</tag>
<timeout value="15 minutes"/>
<status status="PASS" start="2026-09-29T01:24:32.905645" elapsed="606.740571"/>
</test>
<doc>Cbench Latency and Throughput tests can be run from an external
cbench.
If cbench is run with a medium number of switches or higher (e.g. 32+)
the normal openflow operations seem to break.
BUG: https://bugs.opendaylight.org/show_bug.cgi?id=2897</doc>
<status status="PASS" start="2026-09-29T01:04:16.933897" elapsed="1822.713211"/>
</suite>
<statistics>
<total>
<stat pass="3" fail="0" skip="0">All Tests</stat>
</total>
<tag>
<stat pass="3" fail="0" skip="0">cbench</stat>
<stat pass="2" fail="0" skip="0">latency</stat>
<stat pass="1" fail="0" skip="0">throughput</stat>
</tag>
<suite>
<stat name="openflowplugin-cbench.txt" id="s1" pass="3" fail="0" skip="0">openflowplugin-cbench.txt</stat>
</suite>
</statistics>
<errors>
</errors>
</robot>
