<?xml version="1.0" encoding="UTF-8"?>
<robot generator="Robot 7.4.2 (Python 3.11.10 on linux)" generated="2026-09-02T00:46:34.005986" rpa="false" schemaversion="5">
<suite id="s1" name="openflowplugin-cbench.txt" source="/w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/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-02T00:46:34.539899" elapsed="0.000022"/>
</return>
<status status="NOT RUN" start="2026-09-02T00:46:34.539752" elapsed="0.000220"/>
</branch>
<status status="PASS" start="2026-09-02T00:46:34.539722" elapsed="0.000276"/>
</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-02T00:46:34.540134" elapsed="0.000507"/>
</kw>
<kw name="Login" owner="SSHLibrary">
<msg time="2026-09-02T00:46:34.541097" level="INFO">Logging into '10.30.171.88:8101' as 'karaf'.</msg>
<msg time="2026-09-02T00:46:35.337676" 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-02T00:46:34.540820" elapsed="0.797036"/>
</kw>
<kw name="Write" owner="SSHLibrary">
<msg time="2026-09-02T00:46:35.387345" 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-02T00:46:35.338120" elapsed="0.049292"/>
</kw>
<kw name="Read Until Regexp" owner="SSHLibrary">
<msg time="2026-09-02T00:46:35.412201" level="INFO">[?1l&gt;[?2004l[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<msg time="2026-09-02T00:46:35.412328" 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-02T00:46:35.387573" elapsed="0.024786"/>
</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-02T00:46:35.412518" elapsed="0.000280"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-02T00:46:35.412951" elapsed="0.000211"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:46:35.413678" 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-02T00:46:35.413304" elapsed="0.000431"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-09-02T00:46:35.413786" elapsed="0.000055"/>
</return>
<arg>log:set ${log_level}</arg>
<doc>Will execute the given ${cmd} by ssh'ing to the karaf console running on 10.30.171.88
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-02T00:46:34.539364" elapsed="0.874597"/>
</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-02T00:46:34.536010" elapsed="0.878013"/>
</kw>
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-09-02T00:46:35.414577" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/latency.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/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-02T00:46:35.414199" elapsed="0.000509"/>
</kw>
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-09-02T00:46:35.415101" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/throughput.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/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-02T00:46:35.414858" elapsed="0.000307"/>
</kw>
<kw name="Evaluate" owner="BuiltIn">
<msg time="2026-09-02T00:46:35.415720" 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-02T00:46:35.415308" elapsed="0.000438"/>
</kw>
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-09-02T00:46:35.416246" level="INFO">${duration_in_ms} = 60000</msg>
<arg>${duration_in_ms}</arg>
<doc>Makes a variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-09-02T00:46:35.415904" elapsed="0.000383"/>
</kw>
<kw name="Evaluate" owner="BuiltIn">
<msg time="2026-09-02T00:46:35.416828" 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-02T00:46:35.416425" elapsed="0.000430"/>
</kw>
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-09-02T00:46:35.417297" level="INFO">${test_timeout} = 900.0</msg>
<arg>${test_timeout}</arg>
<doc>Makes a variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-09-02T00:46:35.417006" elapsed="0.000331"/>
</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-02T00:46:35.417643" elapsed="0.000039"/>
</kw>
<status status="NOT RUN" start="2026-09-02T00:46:35.417419" elapsed="0.000297"/>
</branch>
<branch type="ELSE">
<kw name="Verify File Exists On Remote System" owner="Utils">
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-09-02T00:46:35.421994" 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-02T00:46:35.421571" elapsed="0.000450"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-02T00:46:35.423306" level="INFO">Length is 0.</msg>
<msg time="2026-09-02T00:46:35.423389" 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-02T00:46:35.422584" elapsed="0.000828"/>
</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>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-02T00:46:35.423551" elapsed="0.000364"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-09-02T00:46:35.424906" level="INFO">Logging into '10.30.170.97:22' as 'jenkins'.</msg>
<msg time="2026-09-02T00:46:35.915694" level="INFO">Read output: Welcome to Ubuntu 22.04.5 LTS (GNU/Linux 5.15.0-186-generic x86_64)

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

 System information as of Wed Sep  2 00:45:50 UTC 2026

  System load:  0.21               Processes:             113
  Usage of /:   19.7% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.170.97
  Swap usage:   0%


Expanded Security Maintenance for Applications is not enabled.

0 updates can be applied immediately.

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.4 LTS' available.
Run 'do-release-upgrade' to upgrade to it.


[?2004h[jenkins@releng-63164-387-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-02T00:46:35.424380" elapsed="0.491447"/>
</kw>
<msg time="2026-09-02T00:46:35.915908" 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-02T00:46:35.424060" elapsed="0.491932"/>
</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-02T00:46:35.422214" elapsed="0.493886"/>
</kw>
<kw name="File Should Exist" owner="SSHLibrary">
<msg time="2026-09-02T00:46:35.925362" 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-02T00:46:35.916302" elapsed="0.010711"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-02T00:46:35.927193" elapsed="0.000276"/>
</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-02T00:46:35.421058" elapsed="0.506471"/>
</kw>
<status status="PASS" start="2026-09-02T00:46:35.417789" elapsed="0.509775"/>
</branch>
<status status="PASS" start="2026-09-02T00:46:35.417395" elapsed="0.510203"/>
</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-02T00:46:35.927784" elapsed="0.000448"/>
</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-02T00:46:35.933587" elapsed="0.000023"/>
</return>
<status status="NOT RUN" start="2026-09-02T00:46:35.933438" elapsed="0.000249"/>
</branch>
<status status="PASS" start="2026-09-02T00:46:35.933408" elapsed="0.000310"/>
</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-02T00:46:35.938201" elapsed="0.000017"/>
</return>
<status status="NOT RUN" start="2026-09-02T00:46:35.938052" elapsed="0.000206"/>
</branch>
<status status="PASS" start="2026-09-02T00:46:35.938027" elapsed="0.000259"/>
</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-02T00:46:35.938448" elapsed="0.000560"/>
</kw>
<kw name="Login" owner="SSHLibrary">
<msg time="2026-09-02T00:46:35.939482" level="INFO">Logging into '10.30.171.88:8101' as 'karaf'.</msg>
<msg time="2026-09-02T00:46:36.211393" 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-02T00:46:35.939191" elapsed="0.272350"/>
</kw>
<kw name="Write" owner="SSHLibrary">
<msg time="2026-09-02T00:46:36.299504" 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-02T00:46:36.211816" elapsed="0.087795"/>
</kw>
<kw name="Read Until Regexp" owner="SSHLibrary">
<msg time="2026-09-02T00:46:36.323820" level="INFO">[?1l&gt;[?2004l[31;1modl-openflowplugin-drop-test[0m                                    x 0.21.5           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-02T00:46:36.324008" level="INFO">${output} = [?1l&gt;[?2004l[31;1modl-openflowplugin-drop-test[0m                                    x 0.21.5           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-02T00:46:36.299854" elapsed="0.024184"/>
</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-02T00:46:36.324172" elapsed="0.000189"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-02T00:46:36.324488" elapsed="0.000231"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:46:36.325128" level="INFO">[?1l&gt;[?2004l[31;1modl-openflowplugin-drop-test[0m                                    x 0.21.5           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-02T00:46:36.324848" elapsed="0.000330"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-09-02T00:46:36.325224" elapsed="0.000045"/>
</return>
<msg time="2026-09-02T00:46:36.325403" level="INFO">${output} = [?1l&gt;[?2004l[31;1modl-openflowplugin-drop-test[0m                                    x 0.21.5           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.171.88
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-02T00:46:35.937589" elapsed="0.387843"/>
</kw>
<kw name="Should Contain" owner="BuiltIn">
<arg>${output}</arg>
<arg>${feature_name}</arg>
<doc>Fails if ``container`` does not contain ``item`` one or more times.</doc>
<status status="PASS" start="2026-09-02T00:46:36.325563" elapsed="0.000492"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-09-02T00:46:36.326100" elapsed="0.000029"/>
</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-02T00:46:35.933076" elapsed="0.393140"/>
</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-02T00:46:36.329713" elapsed="0.000014"/>
</return>
<status status="NOT RUN" start="2026-09-02T00:46:36.329553" elapsed="0.000202"/>
</branch>
<status status="PASS" start="2026-09-02T00:46:36.329532" elapsed="0.000244"/>
</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-02T00:46:36.329894" elapsed="0.000395"/>
</kw>
<kw name="Login" owner="SSHLibrary">
<msg time="2026-09-02T00:46:36.330678" level="INFO">Logging into '10.30.171.88:8101' as 'karaf'.</msg>
<msg time="2026-09-02T00:46:36.496384" 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-02T00:46:36.330420" elapsed="0.166120"/>
</kw>
<kw name="Write" owner="SSHLibrary">
<msg time="2026-09-02T00:46:36.518812" 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-02T00:46:36.496804" elapsed="0.022132"/>
</kw>
<kw name="Read Until Regexp" owner="SSHLibrary">
<msg time="2026-09-02T00:46:36.521123" level="INFO">[?1l&gt;[?2004lDropAllFlows transitions to on
[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<msg time="2026-09-02T00:46:36.521223" 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-02T00:46:36.519199" elapsed="0.002051"/>
</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-02T00:46:36.521375" elapsed="0.000181"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-02T00:46:36.521714" elapsed="0.000186"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:46:36.522304" 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-02T00:46:36.522031" elapsed="0.000319"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-09-02T00:46:36.522397" elapsed="0.000045"/>
</return>
<arg>dropallpacketsrpc on</arg>
<doc>Will execute the given ${cmd} by ssh'ing to the karaf console running on 10.30.171.88
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-02T00:46:36.329258" elapsed="0.193288"/>
</kw>
<status status="PASS" start="2026-09-02T00:46:34.533739" elapsed="1.988862"/>
</kw>
<test id="s1-t1" name="Cbench Latency Test" line="37">
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:46:36.524489" 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-02T00:46:36.523518" elapsed="0.001030"/>
</kw>
<kw name="Run Cbench And Log Results">
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-09-02T00:46:36.526176" level="INFO">${output_filename} = latency.csv</msg>
<arg>${output_filename}</arg>
<doc>Makes a variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-09-02T00:46:36.525716" elapsed="0.000499"/>
</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
[http://robotframework.org/robotframework/latest/libraries/Process.html|
Process] library instead._</doc>
<status status="NOT RUN" start="2026-09-02T00:46:36.526502" elapsed="0.000020"/>
</kw>
<status status="NOT RUN" start="2026-09-02T00:46:36.526286" elapsed="0.000268"/>
</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 with the given message and optionally alters its tags.</doc>
<status status="NOT RUN" start="2026-09-02T00:46:36.529189" elapsed="0.000019"/>
</kw>
<status status="NOT RUN" start="2026-09-02T00:46:36.528966" elapsed="0.000273"/>
</branch>
<status status="PASS" start="2026-09-02T00:46:36.528949" elapsed="0.000313"/>
</if>
<kw name="Get Connection" owner="SSHLibrary">
<msg time="2026-09-02T00:46:36.529573" 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-02T00:46:36.529715" 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-02T00:46:36.529382" elapsed="0.000357"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:46:36.530234" level="INFO">Attempting to execute command "/usr/local/bin/cbench -c 10.30.171.88 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633" on remote system "10.30.170.97" 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-02T00:46:36.529859" elapsed="0.000429"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-09-02T00:46:36.530851" 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-02T00:46:36.530413" elapsed="0.000462"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-02T00:46:36.531709" level="INFO">Length is 0.</msg>
<msg time="2026-09-02T00:46:36.531788" 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-02T00:46:36.531383" elapsed="0.000426"/>
</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>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-02T00:46:36.531934" elapsed="0.000298"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-09-02T00:46:36.533017" level="INFO">Logging into '10.30.170.97:22' as 'jenkins'.</msg>
<msg time="2026-09-02T00:46:36.902648" level="INFO">Read output: Welcome to Ubuntu 22.04.5 LTS (GNU/Linux 5.15.0-186-generic x86_64)

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

 System information as of Wed Sep  2 00:45:50 UTC 2026

  System load:  0.21               Processes:             113
  Usage of /:   19.7% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.170.97
  Swap usage:   0%


Expanded Security Maintenance for Applications is not enabled.

0 updates can be applied immediately.

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.4 LTS' available.
Run 'do-release-upgrade' to upgrade to it.


Last login: Wed Sep  2 00:46:35 2026 from 10.30.171.166
[?2004h[jenkins@releng-63164-387-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-02T00:46:36.532701" elapsed="0.370119"/>
</kw>
<msg time="2026-09-02T00:46:36.902899" 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-02T00:46:36.532368" elapsed="0.370611"/>
</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-02T00:46:36.531056" elapsed="0.372034"/>
</kw>
<kw name="Execute Command" owner="SSHLibrary">
<msg time="2026-09-02T00:46:36.903558" level="INFO">Executing command '/usr/local/bin/cbench -c 10.30.171.88 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633'.</msg>
<msg time="2026-09-02T00:56:43.053027" level="INFO">Command exited with return code 0.</msg>
<msg time="2026-09-02T00:56:43.053695" level="INFO">${stdout} = </msg>
<msg time="2026-09-02T00:56:43.053797" level="INFO">${stderr} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.88:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100...</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-02T00:46:36.903289" elapsed="606.150562"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-02T00:56:43.054359" elapsed="0.001005"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.056482" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.88: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.171.88. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
00:47:42.131 16  switches: flows:  90050 89975 89917 86424 87747 87378 85178 87422 85628 83511 83049 84405 82279 84768 83216 82639  total = 22.893100 per ms 
00:48:42.232 16  switches: flows:  98230 97465 97512 95885 95560 93060 93109 94066 90841 89583 90510 91184 89728 90305 90634 87943  total = 24.718976 per ms 
00:49:42.332 16  switches: flows:  97944 97429 96966 94861 96171 95076 93596 95193 91790 91143 89714 91865 89892 91863 89989 88293  total = 24.821673 per ms 
00:50:42.432 16  switches: flows:  100451 100174 99069 96788 98867 96262 95577 97014 93670 92281 92146 92977 91248 92901 92423 91450  total = 25.346008 per ms 
00:51:42.533 16  switches: flows:  99200 99783 99886 96150 97808 96055 94147 97280 93050 90789 91211 93935 92374 93593 91555 90534  total = 25.247017 per ms 
00:52:42.634 16  switches: flows:  98355 98354 97471 94665 97074 93061 93493 95446 91556 89418 89115 93245 90706 92701 92360 88858  total = 24.889707 per ms 
00:53:42.735 16  switches: flows:  98617 96895 96331 95374 96091 94699 91851 95222 92268 89519 88751 92902 89801 92843 89432 88380  total = 24.774925 per ms 
00:54:42.836 16  switches: flows:  99365 97432 97873 95252 97031 95705 94270 93755 91267 90931 90826 93602 88811 93518 91047 89452  total = 24.960620 per ms 
00:55:42.936 16  switches: flows:  99623 99509 98643 95465 99095 97200 94657 94527 92796 91329 90872 92711 90762 93687 90777 91105  total = 25.170583 per ms 
00:56:43.038 16  switches: flows:  100080 99011 97957 96831 97947 96260 95317 94546 93089 91089 90712 92689 91150 93597 92249 91591  total = 25.193167 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 24718.98/25346.01/25013.63/8340.68 responses/s</msg>
<arg>${stderr}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-02T00:56:43.055767" elapsed="0.001013"/>
</kw>
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot;">
<return>
<value>${stderr}</value>
<status status="PASS" start="2026-09-02T00:56:43.057313" elapsed="0.000084"/>
</return>
<status status="PASS" start="2026-09-02T00:56:43.056977" elapsed="0.000500"/>
</branch>
<status status="PASS" start="2026-09-02T00:56:43.056906" elapsed="0.000626"/>
</if>
<if>
<branch type="IF" condition="&quot;${return_stderr}&quot;!=&quot;True&quot;">
<return>
<value>${stdout}</value>
<status status="NOT RUN" start="2026-09-02T00:56:43.057818" elapsed="0.000026"/>
</return>
<status status="NOT RUN" start="2026-09-02T00:56:43.057735" elapsed="0.000170"/>
</branch>
<status status="NOT RUN" start="2026-09-02T00:56:43.057693" elapsed="0.000255"/>
</if>
<return>
<value>${stdout}</value>
<value>${stderr}</value>
<status status="NOT RUN" start="2026-09-02T00:56:43.058017" 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>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-02T00:56:43.063477" elapsed="0.000715"/>
</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-02T00:56:43.064520" elapsed="0.000371"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-02T00:56:43.065181" elapsed="0.000236"/>
</kw>
<arg>${current_ssh_connection.index}</arg>
<doc>Restore active SSH connection in SSHLibrary to given index.</doc>
<status status="PASS" start="2026-09-02T00:56:43.058806" elapsed="0.006721"/>
</kw>
<msg time="2026-09-02T00:56:43.065843" level="INFO">${output} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.88:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100...</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-02T00:46:36.526894" elapsed="606.539004"/>
</kw>
<status status="PASS" start="2026-09-02T00:46:36.526580" elapsed="606.539414"/>
</branch>
<status status="PASS" start="2026-09-02T00:46:36.526265" elapsed="606.539776"/>
</if>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.066972" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.88: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.171.88. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
00:47:42.131 16  switches: flows:  90050 89975 89917 86424 87747 87378 85178 87422 85628 83511 83049 84405 82279 84768 83216 82639  total = 22.893100 per ms 
00:48:42.232 16  switches: flows:  98230 97465 97512 95885 95560 93060 93109 94066 90841 89583 90510 91184 89728 90305 90634 87943  total = 24.718976 per ms 
00:49:42.332 16  switches: flows:  97944 97429 96966 94861 96171 95076 93596 95193 91790 91143 89714 91865 89892 91863 89989 88293  total = 24.821673 per ms 
00:50:42.432 16  switches: flows:  100451 100174 99069 96788 98867 96262 95577 97014 93670 92281 92146 92977 91248 92901 92423 91450  total = 25.346008 per ms 
00:51:42.533 16  switches: flows:  99200 99783 99886 96150 97808 96055 94147 97280 93050 90789 91211 93935 92374 93593 91555 90534  total = 25.247017 per ms 
00:52:42.634 16  switches: flows:  98355 98354 97471 94665 97074 93061 93493 95446 91556 89418 89115 93245 90706 92701 92360 88858  total = 24.889707 per ms 
00:53:42.735 16  switches: flows:  98617 96895 96331 95374 96091 94699 91851 95222 92268 89519 88751 92902 89801 92843 89432 88380  total = 24.774925 per ms 
00:54:42.836 16  switches: flows:  99365 97432 97873 95252 97031 95705 94270 93755 91267 90931 90826 93602 88811 93518 91047 89452  total = 24.960620 per ms 
00:55:42.936 16  switches: flows:  99623 99509 98643 95465 99095 97200 94657 94527 92796 91329 90872 92711 90762 93687 90777 91105  total = 25.170583 per ms 
00:56:43.038 16  switches: flows:  100080 99011 97957 96831 97947 96260 95317 94546 93089 91089 90712 92689 91150 93597 92249 91591  total = 25.193167 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 24718.98/25346.01/25013.63/8340.68 responses/s</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-02T00:56:43.066351" elapsed="0.000769"/>
</kw>
<kw name="Should Contain" owner="BuiltIn">
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Fails if ``container`` does not contain ``item`` one or more times.</doc>
<status status="PASS" start="2026-09-02T00:56:43.067441" elapsed="0.000774"/>
</kw>
<kw name="Get Lines Containing String" owner="String">
<msg time="2026-09-02T00:56:43.069117" level="INFO">1 out of 23 lines matched.</msg>
<msg time="2026-09-02T00:56:43.069329" level="INFO">${result_line} = RESULT: 16 switches 9 tests min/max/avg/stdev = 24718.98/25346.01/25013.63/8340.68 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-02T00:56:43.068593" elapsed="0.000788"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-02T00:56:43.070395" level="INFO">@{results_list} = [ RESULT: | 16 | switches | 9 | tests | min/max/avg/stdev | = | 24718.98/25346.01/25013.63/8340.68 | 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-02T00:56:43.069759" elapsed="0.000703"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.075491" 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-02T00:56:43.070854" elapsed="0.004803"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.080726" level="INFO">24718.98/25346.01/25013.63/8340.68</msg>
<arg>${results_list[7]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-02T00:56:43.075997" elapsed="0.004856"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-02T00:56:43.085728" 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-02T00:56:43.081157" elapsed="0.004630"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-02T00:56:43.090925" level="INFO">@{result_value_list} = [ 24718.98 | 25346.01 | 25013.63 | 8340.68 ]</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-02T00:56:43.086162" elapsed="0.004819"/>
</kw>
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.091979" level="INFO">Length is 4.</msg>
<msg time="2026-09-02T00:56:43.092154" 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-02T00:56:43.091319" elapsed="0.000881"/>
</kw>
<for flavor="IN RANGE">
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.099532" level="INFO">min :: 24718.98</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-02T00:56:43.093041" elapsed="0.006561"/>
</kw>
<var name="${i}">0</var>
<status status="PASS" start="2026-09-02T00:56:43.092771" elapsed="0.006913"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.105646" level="INFO">max :: 25346.01</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-02T00:56:43.099959" elapsed="0.005764"/>
</kw>
<var name="${i}">1</var>
<status status="PASS" start="2026-09-02T00:56:43.099798" elapsed="0.005968"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.111962" level="INFO">avg :: 25013.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-02T00:56:43.106037" elapsed="0.006008"/>
</kw>
<var name="${i}">2</var>
<status status="PASS" start="2026-09-02T00:56:43.105876" elapsed="0.006215"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.117788" level="INFO">stdev :: 8340.68</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-02T00:56:43.112410" elapsed="0.005448"/>
</kw>
<var name="${i}">3</var>
<status status="PASS" start="2026-09-02T00:56:43.112217" elapsed="0.005683"/>
</iter>
<var>${i}</var>
<value>0</value>
<value>${num_stats}</value>
<status status="PASS" start="2026-09-02T00:56:43.092366" elapsed="0.025584"/>
</for>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.122212" level="INFO">${min} = 24718.98</msg>
<var>${min}</var>
<arg>${result_value_list[${0}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-09-02T00:56:43.118155" elapsed="0.004082"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.126866" level="INFO">${max} = 25346.01</msg>
<var>${max}</var>
<arg>${result_value_list[${1}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-09-02T00:56:43.122388" elapsed="0.004503"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.130575" level="INFO">${average} = 25013.63</msg>
<var>${average}</var>
<arg>${result_value_list[${2}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-09-02T00:56:43.127031" elapsed="0.003568"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.134600" level="INFO">${stdev} = 8340.68</msg>
<var>${stdev}</var>
<arg>${result_value_list[${3}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-09-02T00:56:43.130848" elapsed="0.003795"/>
</kw>
<kw name="Get Time" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.135168" level="INFO">${date} = 2026-09-02 00:56:43</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-02T00:56:43.134814" elapsed="0.000377"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.135731" level="INFO">CBench Result: 2026-09-02 00:56:43,-m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633,24718.98,25346.01,25013.63,8340.68</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-02T00:56:43.135343" elapsed="0.000440"/>
</kw>
<kw name="Log Results And Determine Status">
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-09-02T00:56:43.136811" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/latency.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/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-02T00:56:43.136380" elapsed="0.000492"/>
</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-02T00:56:43.137008" elapsed="0.000451"/>
</kw>
<arg>${min}</arg>
<arg>${max}</arg>
<arg>${average}</arg>
<arg>${average_threshold}</arg>
<arg>${output_filename}</arg>
<status status="PASS" start="2026-09-02T00:56:43.135931" elapsed="0.001590"/>
</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-02T00:46:36.524944" elapsed="606.612649"/>
</kw>
<kw name="Sleep" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.138048" level="INFO">Slept 0 seconds.</msg>
<arg>${test_delay}</arg>
<doc>Pauses the test executed for the given time.</doc>
<status status="PASS" start="2026-09-02T00:56:43.137754" elapsed="0.000346"/>
</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-02T00:56:43.138588" elapsed="0.000256"/>
</kw>
<status status="PASS" start="2026-09-02T00:56:43.138312" elapsed="0.000593"/>
</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-02T00:46:36.522762" elapsed="606.616177"/>
</test>
<test id="s1-t2" name="Cbench Throughput Test" line="51">
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.140633" 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-02T00:56:43.140182" elapsed="0.000522"/>
</kw>
<kw name="Run Cbench And Log Results">
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.141927" level="INFO">${output_filename} = throughput.csv</msg>
<arg>${output_filename}</arg>
<doc>Makes a variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-09-02T00:56:43.141597" elapsed="0.000367"/>
</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
[http://robotframework.org/robotframework/latest/libraries/Process.html|
Process] library instead._</doc>
<status status="NOT RUN" start="2026-09-02T00:56:43.142301" elapsed="0.000021"/>
</kw>
<status status="NOT RUN" start="2026-09-02T00:56:43.142037" elapsed="0.000319"/>
</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 with the given message and optionally alters its tags.</doc>
<status status="NOT RUN" start="2026-09-02T00:56:43.145277" elapsed="0.000020"/>
</kw>
<status status="NOT RUN" start="2026-09-02T00:56:43.145040" elapsed="0.000289"/>
</branch>
<status status="PASS" start="2026-09-02T00:56:43.145022" elapsed="0.000328"/>
</if>
<kw name="Get Connection" owner="SSHLibrary">
<msg time="2026-09-02T00:56:43.145719" 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-02T00:56:43.145828" 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-02T00:56:43.145473" elapsed="0.000379"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.146362" level="INFO">Attempting to execute command "/usr/local/bin/cbench -c 10.30.171.88 -t -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633" on remote system "10.30.170.97" 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-02T00:56:43.145974" elapsed="0.000439"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-09-02T00:56:43.146924" 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-02T00:56:43.146538" elapsed="0.000409"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-02T00:56:43.147771" level="INFO">Length is 0.</msg>
<msg time="2026-09-02T00:56:43.147850" 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-02T00:56:43.147465" elapsed="0.000406"/>
</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>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-02T00:56:43.147997" elapsed="0.000310"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-09-02T00:56:43.149089" level="INFO">Logging into '10.30.170.97:22' as 'jenkins'.</msg>
<msg time="2026-09-02T00:56:43.753603" level="INFO">Read output: Welcome to Ubuntu 22.04.5 LTS (GNU/Linux 5.15.0-186-generic x86_64)

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

 System information as of Wed Sep  2 00:56:43 UTC 2026

  System load:  1.0                Processes:             104
  Usage of /:   20.2% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.170.97
  Swap usage:   0%


Expanded Security Maintenance for Applications is not enabled.

0 updates can be applied immediately.

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.4 LTS' available.
Run 'do-release-upgrade' to upgrade to it.


Last login: Wed Sep  2 00:46:36 2026 from 10.30.171.166
[?2004h[jenkins@releng-63164-387-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-02T00:56:43.148773" elapsed="0.605056"/>
</kw>
<msg time="2026-09-02T00:56:43.753926" 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-02T00:56:43.148446" elapsed="0.605583"/>
</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-02T00:56:43.147122" elapsed="0.607029"/>
</kw>
<kw name="Execute Command" owner="SSHLibrary">
<msg time="2026-09-02T00:56:43.754788" level="INFO">Executing command '/usr/local/bin/cbench -c 10.30.171.88 -t -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633'.</msg>
<msg time="2026-09-02T01:06:49.815688" level="INFO">Command exited with return code 0.</msg>
<msg time="2026-09-02T01:06:49.816319" level="INFO">${stdout} = </msg>
<msg time="2026-09-02T01:06:49.816420" level="INFO">${stderr} = cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.171.88: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-02T00:56:43.754417" elapsed="606.062076"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-02T01:06:49.817018" elapsed="0.000743"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.818944" level="INFO">cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.171.88: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.171.88. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
00:57:48.869 16  switches: flows:  622381 624319 608320 620284 619472 619494 620012 607075 613068 614220 616322 613434 616072 611512 615518 617302  total = 164.313417 per ms 
00:58:48.975 16  switches: flows:  644046 635083 643789 642274 642444 640432 645356 637464 643143 642643 642843 639114 640344 639519 636669 641802  total = 170.651280 per ms 
00:59:49.076 16  switches: flows:  643124 649257 645367 649249 650187 641999 641723 646186 649020 653231 644428 648885 645752 642772 653559 648263  total = 172.260656 per ms 
01:00:49.176 16  switches: flows:  626748 622156 622184 619096 623494 627256 624131 623379 620873 620634 622390 622786 624694 617193 625034 627609  total = 165.882464 per ms 
01:01:49.278 16  switches: flows:  620104 621996 620697 620897 622087 621259 615192 619864 621947 622475 618935 618442 623686 618659 616402 626694  total = 165.209273 per ms 
01:02:49.385 16  switches: flows:  643227 650164 649543 649281 651589 650950 649690 647831 646389 647911 654544 650333 651170 647417 651837 650771  total = 172.904935 per ms 
01:03:49.490 16  switches: flows:  644399 647098 649371 643167 643226 646364 647622 642341 642028 646183 643546 641123 642266 646549 648087 645599  total = 171.682781 per ms 
01:04:49.591 16  switches: flows:  641486 642787 635728 638803 637499 638441 640519 636996 638401 635551 643210 635466 639513 645757 640202 642738  total = 170.264508 per ms 
01:05:49.694 16  switches: flows:  648083 643045 639321 647396 640115 645609 645176 652477 645565 644897 646065 638883 644868 645140 649864 645888  total = 171.748755 per ms 
01:06:49.796 16  switches: flows:  646672 650866 645877 647945 646536 647246 641327 643930 644844 648128 647823 650781 638911 649238 645209 642892  total = 172.012667 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 165209.27/172904.93/170290.81/56825.36 responses/s</msg>
<arg>${stderr}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-02T01:06:49.818140" elapsed="0.001140"/>
</kw>
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot;">
<return>
<value>${stderr}</value>
<status status="PASS" start="2026-09-02T01:06:49.819987" elapsed="0.000073"/>
</return>
<status status="PASS" start="2026-09-02T01:06:49.819485" elapsed="0.000660"/>
</branch>
<status status="PASS" start="2026-09-02T01:06:49.819407" elapsed="0.000791"/>
</if>
<if>
<branch type="IF" condition="&quot;${return_stderr}&quot;!=&quot;True&quot;">
<return>
<value>${stdout}</value>
<status status="NOT RUN" start="2026-09-02T01:06:49.820397" elapsed="0.000025"/>
</return>
<status status="NOT RUN" start="2026-09-02T01:06:49.820321" elapsed="0.000190"/>
</branch>
<status status="NOT RUN" start="2026-09-02T01:06:49.820288" elapsed="0.000269"/>
</if>
<return>
<value>${stdout}</value>
<value>${stderr}</value>
<status status="NOT RUN" start="2026-09-02T01:06:49.820662" elapsed="0.000030"/>
</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>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-02T01:06:49.826425" elapsed="0.000743"/>
</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-02T01:06:49.827510" elapsed="0.000393"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-02T01:06:49.828169" elapsed="0.000203"/>
</kw>
<arg>${current_ssh_connection.index}</arg>
<doc>Restore active SSH connection in SSHLibrary to given index.</doc>
<status status="PASS" start="2026-09-02T01:06:49.821314" elapsed="0.007187"/>
</kw>
<msg time="2026-09-02T01:06:49.828782" level="INFO">${output} = cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.171.88: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-02T00:56:43.142698" elapsed="606.686136"/>
</kw>
<status status="PASS" start="2026-09-02T00:56:43.142381" elapsed="606.686546"/>
</branch>
<status status="PASS" start="2026-09-02T00:56:43.142015" elapsed="606.686957"/>
</if>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.829988" level="INFO">cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.171.88: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.171.88. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
00:57:48.869 16  switches: flows:  622381 624319 608320 620284 619472 619494 620012 607075 613068 614220 616322 613434 616072 611512 615518 617302  total = 164.313417 per ms 
00:58:48.975 16  switches: flows:  644046 635083 643789 642274 642444 640432 645356 637464 643143 642643 642843 639114 640344 639519 636669 641802  total = 170.651280 per ms 
00:59:49.076 16  switches: flows:  643124 649257 645367 649249 650187 641999 641723 646186 649020 653231 644428 648885 645752 642772 653559 648263  total = 172.260656 per ms 
01:00:49.176 16  switches: flows:  626748 622156 622184 619096 623494 627256 624131 623379 620873 620634 622390 622786 624694 617193 625034 627609  total = 165.882464 per ms 
01:01:49.278 16  switches: flows:  620104 621996 620697 620897 622087 621259 615192 619864 621947 622475 618935 618442 623686 618659 616402 626694  total = 165.209273 per ms 
01:02:49.385 16  switches: flows:  643227 650164 649543 649281 651589 650950 649690 647831 646389 647911 654544 650333 651170 647417 651837 650771  total = 172.904935 per ms 
01:03:49.490 16  switches: flows:  644399 647098 649371 643167 643226 646364 647622 642341 642028 646183 643546 641123 642266 646549 648087 645599  total = 171.682781 per ms 
01:04:49.591 16  switches: flows:  641486 642787 635728 638803 637499 638441 640519 636996 638401 635551 643210 635466 639513 645757 640202 642738  total = 170.264508 per ms 
01:05:49.694 16  switches: flows:  648083 643045 639321 647396 640115 645609 645176 652477 645565 644897 646065 638883 644868 645140 649864 645888  total = 171.748755 per ms 
01:06:49.796 16  switches: flows:  646672 650866 645877 647945 646536 647246 641327 643930 644844 648128 647823 650781 638911 649238 645209 642892  total = 172.012667 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 165209.27/172904.93/170290.81/56825.36 responses/s</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-02T01:06:49.829252" elapsed="0.000890"/>
</kw>
<kw name="Should Contain" owner="BuiltIn">
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Fails if ``container`` does not contain ``item`` one or more times.</doc>
<status status="PASS" start="2026-09-02T01:06:49.830495" elapsed="0.000784"/>
</kw>
<kw name="Get Lines Containing String" owner="String">
<msg time="2026-09-02T01:06:49.832222" level="INFO">1 out of 23 lines matched.</msg>
<msg time="2026-09-02T01:06:49.832430" level="INFO">${result_line} = RESULT: 16 switches 9 tests min/max/avg/stdev = 165209.27/172904.93/170290.81/56825.36 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-02T01:06:49.831709" elapsed="0.000798"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-02T01:06:49.833394" level="INFO">@{results_list} = [ RESULT: | 16 | switches | 9 | tests | min/max/avg/stdev | = | 165209.27/172904.93/170290.81/56825.36 | 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-02T01:06:49.832851" elapsed="0.000625"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.838395" 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-02T01:06:49.833806" elapsed="0.004733"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.843519" level="INFO">165209.27/172904.93/170290.81/56825.36</msg>
<arg>${results_list[7]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-02T01:06:49.838862" elapsed="0.004831"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-02T01:06:49.848492" 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-02T01:06:49.843996" elapsed="0.004554"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-02T01:06:49.853680" level="INFO">@{result_value_list} = [ 165209.27 | 172904.93 | 170290.81 | 56825.36 ]</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-02T01:06:49.848976" elapsed="0.004761"/>
</kw>
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.854863" level="INFO">Length is 4.</msg>
<msg time="2026-09-02T01:06:49.855099" 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-02T01:06:49.854046" elapsed="0.001103"/>
</kw>
<for flavor="IN RANGE">
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.863719" level="INFO">min :: 165209.27</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-02T01:06:49.856136" elapsed="0.007657"/>
</kw>
<var name="${i}">0</var>
<status status="PASS" start="2026-09-02T01:06:49.855785" elapsed="0.008054"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.870570" level="INFO">max :: 172904.93</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-02T01:06:49.864119" elapsed="0.006588"/>
</kw>
<var name="${i}">1</var>
<status status="PASS" start="2026-09-02T01:06:49.863952" elapsed="0.006806"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.877114" level="INFO">avg :: 170290.81</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-02T01:06:49.871060" elapsed="0.006155"/>
</kw>
<var name="${i}">2</var>
<status status="PASS" start="2026-09-02T01:06:49.870879" elapsed="0.006397"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.883860" level="INFO">stdev :: 56825.36</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-02T01:06:49.877667" elapsed="0.006269"/>
</kw>
<var name="${i}">3</var>
<status status="PASS" start="2026-09-02T01:06:49.877401" elapsed="0.006584"/>
</iter>
<var>${i}</var>
<value>0</value>
<value>${num_stats}</value>
<status status="PASS" start="2026-09-02T01:06:49.855278" elapsed="0.028748"/>
</for>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.889823" level="INFO">${min} = 165209.27</msg>
<var>${min}</var>
<arg>${result_value_list[${0}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-09-02T01:06:49.884251" elapsed="0.005620"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.895253" level="INFO">${max} = 172904.93</msg>
<var>${max}</var>
<arg>${result_value_list[${1}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-09-02T01:06:49.890104" elapsed="0.005183"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.900761" level="INFO">${average} = 170290.81</msg>
<var>${average}</var>
<arg>${result_value_list[${2}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-09-02T01:06:49.895517" elapsed="0.005281"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.905762" level="INFO">${stdev} = 56825.36</msg>
<var>${stdev}</var>
<arg>${result_value_list[${3}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-09-02T01:06:49.901043" elapsed="0.004745"/>
</kw>
<kw name="Get Time" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.906308" level="INFO">${date} = 2026-09-02 01:06:49</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-02T01:06:49.905948" elapsed="0.000384"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.906870" level="INFO">CBench Result: 2026-09-02 01:06:49,-t -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633,165209.27,172904.93,170290.81,56825.36</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-02T01:06:49.906479" elapsed="0.000442"/>
</kw>
<kw name="Log Results And Determine Status">
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-09-02T01:06:49.908153" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/throughput.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/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-02T01:06:49.907701" elapsed="0.000537"/>
</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-02T01:06:49.908386" elapsed="0.000473"/>
</kw>
<arg>${min}</arg>
<arg>${max}</arg>
<arg>${average}</arg>
<arg>${average_threshold}</arg>
<arg>${output_filename}</arg>
<status status="PASS" start="2026-09-02T01:06:49.907084" elapsed="0.001835"/>
</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-02T00:56:43.141050" elapsed="606.767923"/>
</kw>
<kw name="Sleep" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.909415" level="INFO">Slept 0 seconds.</msg>
<arg>${test_delay}</arg>
<doc>Pauses the test executed for the given time.</doc>
<status status="PASS" start="2026-09-02T01:06:49.909108" elapsed="0.000381"/>
</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-02T01:06:49.910004" elapsed="0.000245"/>
</kw>
<status status="PASS" start="2026-09-02T01:06:49.909720" elapsed="0.000597"/>
</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-02T00:56:43.139548" elapsed="606.770804"/>
</test>
<test id="s1-t3" name="Rerun Latency Test To Check Bug 6176" line="65">
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.911931" 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-02T01:06:49.911540" elapsed="0.000441"/>
</kw>
<kw name="Run Cbench And Log Results">
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.913182" level="INFO">${output_filename} = bug.csv</msg>
<arg>${output_filename}</arg>
<doc>Makes a variable available everywhere within the scope of the current suite.</doc>
<status status="PASS" start="2026-09-02T01:06:49.912840" elapsed="0.000381"/>
</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
[http://robotframework.org/robotframework/latest/libraries/Process.html|
Process] library instead._</doc>
<status status="NOT RUN" start="2026-09-02T01:06:49.913582" elapsed="0.000020"/>
</kw>
<status status="NOT RUN" start="2026-09-02T01:06:49.913354" elapsed="0.000297"/>
</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 with the given message and optionally alters its tags.</doc>
<status status="NOT RUN" start="2026-09-02T01:06:49.916474" elapsed="0.000020"/>
</kw>
<status status="NOT RUN" start="2026-09-02T01:06:49.916235" elapsed="0.000291"/>
</branch>
<status status="PASS" start="2026-09-02T01:06:49.916217" elapsed="0.000330"/>
</if>
<kw name="Get Connection" owner="SSHLibrary">
<msg time="2026-09-02T01:06:49.917117" 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-02T01:06:49.917252" 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-02T01:06:49.916770" elapsed="0.000511"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.917841" level="INFO">Attempting to execute command "/usr/local/bin/cbench -c 10.30.171.88 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633" on remote system "10.30.170.97" 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-02T01:06:49.917415" elapsed="0.000478"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-09-02T01:06:49.918424" 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-02T01:06:49.918022" elapsed="0.000447"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-02T01:06:49.919318" level="INFO">Length is 0.</msg>
<msg time="2026-09-02T01:06:49.919401" 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-02T01:06:49.919007" elapsed="0.000415"/>
</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>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-02T01:06:49.919607" elapsed="0.000400"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-09-02T01:06:49.920963" level="INFO">Logging into '10.30.170.97:22' as 'jenkins'.</msg>
<msg time="2026-09-02T01:06:50.550491" level="INFO">Read output: Welcome to Ubuntu 22.04.5 LTS (GNU/Linux 5.15.0-186-generic x86_64)

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

 System information as of Wed Sep  2 01:06:50 UTC 2026

  System load:  0.21               Processes:             104
  Usage of /:   20.2% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.170.97
  Swap usage:   0%


Expanded Security Maintenance for Applications is not enabled.

0 updates can be applied immediately.

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.4 LTS' available.
Run 'do-release-upgrade' to upgrade to it.


Last login: Wed Sep  2 00:56:43 2026 from 10.30.171.166
[?2004h[jenkins@releng-63164-387-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-02T01:06:49.920568" elapsed="0.630143"/>
</kw>
<msg time="2026-09-02T01:06:50.550798" 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-02T01:06:49.920181" elapsed="0.630696"/>
</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-02T01:06:49.918669" elapsed="0.632315"/>
</kw>
<kw name="Execute Command" owner="SSHLibrary">
<msg time="2026-09-02T01:06:50.551486" level="INFO">Executing command '/usr/local/bin/cbench -c 10.30.171.88 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633'.</msg>
<msg time="2026-09-02T01:16:56.611249" level="INFO">Command exited with return code 0.</msg>
<msg time="2026-09-02T01:16:56.611830" level="INFO">${stdout} = </msg>
<msg time="2026-09-02T01:16:56.611926" level="INFO">${stderr} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.88:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100...</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-02T01:06:50.551195" elapsed="606.060802"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-02T01:16:56.612461" elapsed="0.000773"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.614699" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.88: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.171.88. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
01:07:55.673 16  switches: flows:  91851 90868 91166 90314 89425 88819 87618 88129 85913 85458 84786 85564 85180 85095 84183 84057  total = 23.307100 per ms 
01:08:55.774 16  switches: flows:  91884 91422 92072 89487 88896 88036 88182 88009 85204 85095 85055 85461 85299 86018 84732 85264  total = 23.296348 per ms 
01:09:55.874 16  switches: flows:  94832 92423 94018 90679 90703 89805 88895 89327 87050 85831 87604 87190 87833 87715 86610 87278  total = 23.756853 per ms 
01:10:55.974 16  switches: flows:  96673 94173 95497 91719 91913 90996 90166 90043 87658 87780 86978 87725 88479 88577 87192 87037  total = 24.003334 per ms 
01:11:56.075 16  switches: flows:  92458 91444 92875 88986 89157 87964 88036 86997 83980 85089 84589 85214 85452 86502 86400 85031  total = 23.297301 per ms 
01:12:56.175 16  switches: flows:  97362 94737 96439 92508 92110 91637 89896 90514 89038 87188 86472 88963 89159 89815 88513 88281  total = 24.170133 per ms 
01:13:56.275 16  switches: flows:  96209 94001 96893 92844 92594 91246 90113 90422 87976 86940 87526 88117 88864 88376 87588 88411  total = 24.095114 per ms 
01:14:56.376 16  switches: flows:  97328 94849 96936 93049 91052 91305 91966 89969 89396 86802 86837 88643 88448 88438 88043 88660  total = 24.155038 per ms 
01:15:56.476 16  switches: flows:  97230 95566 97553 93656 92878 92157 92959 91759 88988 88300 87729 88660 89924 88595 88365 89857  total = 24.362245 per ms 
01:16:56.576 16  switches: flows:  96229 92939 96054 91843 91321 89994 90971 89980 87712 86671 87370 87002 88605 88507 88614 88997  total = 24.006734 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 23296.35/24362.25/23904.79/7976.34 responses/s</msg>
<arg>${stderr}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-02T01:16:56.613773" elapsed="0.001240"/>
</kw>
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot;">
<return>
<value>${stderr}</value>
<status status="PASS" start="2026-09-02T01:16:56.615470" elapsed="0.000076"/>
</return>
<status status="PASS" start="2026-09-02T01:16:56.615185" elapsed="0.000488"/>
</branch>
<status status="PASS" start="2026-09-02T01:16:56.615135" elapsed="0.000595"/>
</if>
<if>
<branch type="IF" condition="&quot;${return_stderr}&quot;!=&quot;True&quot;">
<return>
<value>${stdout}</value>
<status status="NOT RUN" start="2026-09-02T01:16:56.615917" elapsed="0.000024"/>
</return>
<status status="NOT RUN" start="2026-09-02T01:16:56.615847" elapsed="0.000182"/>
</branch>
<status status="NOT RUN" start="2026-09-02T01:16:56.615816" elapsed="0.000269"/>
</if>
<return>
<value>${stdout}</value>
<value>${stderr}</value>
<status status="NOT RUN" start="2026-09-02T01:16:56.616167" 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>Runs the specified keyword and returns from the enclosing user keyword.</doc>
<status status="PASS" start="2026-09-02T01:16:56.621862" elapsed="0.000695"/>
</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-02T01:16:56.622898" elapsed="0.000349"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-09-02T01:16:56.623512" elapsed="0.000319"/>
</kw>
<arg>${current_ssh_connection.index}</arg>
<doc>Restore active SSH connection in SSHLibrary to given index.</doc>
<status status="PASS" start="2026-09-02T01:16:56.616833" elapsed="0.007109"/>
</kw>
<msg time="2026-09-02T01:16:56.624235" level="INFO">${output} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.88:6633 
   faking 16 switches with 1 threads :: 10 tests each; 60000 ms per test
   with 100...</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-02T01:06:49.913965" elapsed="606.710322"/>
</kw>
<status status="PASS" start="2026-09-02T01:06:49.913675" elapsed="606.710719"/>
</branch>
<status status="PASS" start="2026-09-02T01:06:49.913330" elapsed="606.711127"/>
</if>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.625429" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.88: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.171.88. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
01:07:55.673 16  switches: flows:  91851 90868 91166 90314 89425 88819 87618 88129 85913 85458 84786 85564 85180 85095 84183 84057  total = 23.307100 per ms 
01:08:55.774 16  switches: flows:  91884 91422 92072 89487 88896 88036 88182 88009 85204 85095 85055 85461 85299 86018 84732 85264  total = 23.296348 per ms 
01:09:55.874 16  switches: flows:  94832 92423 94018 90679 90703 89805 88895 89327 87050 85831 87604 87190 87833 87715 86610 87278  total = 23.756853 per ms 
01:10:55.974 16  switches: flows:  96673 94173 95497 91719 91913 90996 90166 90043 87658 87780 86978 87725 88479 88577 87192 87037  total = 24.003334 per ms 
01:11:56.075 16  switches: flows:  92458 91444 92875 88986 89157 87964 88036 86997 83980 85089 84589 85214 85452 86502 86400 85031  total = 23.297301 per ms 
01:12:56.175 16  switches: flows:  97362 94737 96439 92508 92110 91637 89896 90514 89038 87188 86472 88963 89159 89815 88513 88281  total = 24.170133 per ms 
01:13:56.275 16  switches: flows:  96209 94001 96893 92844 92594 91246 90113 90422 87976 86940 87526 88117 88864 88376 87588 88411  total = 24.095114 per ms 
01:14:56.376 16  switches: flows:  97328 94849 96936 93049 91052 91305 91966 89969 89396 86802 86837 88643 88448 88438 88043 88660  total = 24.155038 per ms 
01:15:56.476 16  switches: flows:  97230 95566 97553 93656 92878 92157 92959 91759 88988 88300 87729 88660 89924 88595 88365 89857  total = 24.362245 per ms 
01:16:56.576 16  switches: flows:  96229 92939 96054 91843 91321 89994 90971 89980 87712 86671 87370 87002 88605 88507 88614 88997  total = 24.006734 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 23296.35/24362.25/23904.79/7976.34 responses/s</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-02T01:16:56.624801" elapsed="0.000784"/>
</kw>
<kw name="Should Contain" owner="BuiltIn">
<arg>${output}</arg>
<arg>RESULT</arg>
<doc>Fails if ``container`` does not contain ``item`` one or more times.</doc>
<status status="PASS" start="2026-09-02T01:16:56.625918" elapsed="0.000860"/>
</kw>
<kw name="Get Lines Containing String" owner="String">
<msg time="2026-09-02T01:16:56.627575" level="INFO">1 out of 23 lines matched.</msg>
<msg time="2026-09-02T01:16:56.627788" level="INFO">${result_line} = RESULT: 16 switches 9 tests min/max/avg/stdev = 23296.35/24362.25/23904.79/7976.34 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-02T01:16:56.627134" elapsed="0.000703"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-02T01:16:56.628718" level="INFO">@{results_list} = [ RESULT: | 16 | switches | 9 | tests | min/max/avg/stdev | = | 23296.35/24362.25/23904.79/7976.34 | 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-02T01:16:56.628179" elapsed="0.000594"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.633695" 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-02T01:16:56.629097" elapsed="0.004714"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.638742" level="INFO">23296.35/24362.25/23904.79/7976.34</msg>
<arg>${results_list[7]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-09-02T01:16:56.634132" elapsed="0.004725"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-02T01:16:56.643892" 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-02T01:16:56.639208" elapsed="0.004739"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-09-02T01:16:56.649224" level="INFO">@{result_value_list} = [ 23296.35 | 24362.25 | 23904.79 | 7976.34 ]</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-02T01:16:56.644360" elapsed="0.004919"/>
</kw>
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.650275" level="INFO">Length is 4.</msg>
<msg time="2026-09-02T01:16:56.650450" 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-02T01:16:56.649595" elapsed="0.000901"/>
</kw>
<for flavor="IN RANGE">
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.660571" level="INFO">min :: 23296.35</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-02T01:16:56.651499" elapsed="0.009226"/>
</kw>
<var name="${i}">0</var>
<status status="PASS" start="2026-09-02T01:16:56.651184" elapsed="0.009612"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.670347" level="INFO">max :: 24362.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-02T01:16:56.661270" elapsed="0.009191"/>
</kw>
<var name="${i}">1</var>
<status status="PASS" start="2026-09-02T01:16:56.660995" elapsed="0.009535"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.676818" level="INFO">avg :: 23904.79</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-02T01:16:56.671014" elapsed="0.005874"/>
</kw>
<var name="${i}">2</var>
<status status="PASS" start="2026-09-02T01:16:56.670732" elapsed="0.006200"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.682690" level="INFO">stdev :: 7976.34</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-02T01:16:56.677217" elapsed="0.005559"/>
</kw>
<var name="${i}">3</var>
<status status="PASS" start="2026-09-02T01:16:56.677056" elapsed="0.005764"/>
</iter>
<var>${i}</var>
<value>0</value>
<value>${num_stats}</value>
<status status="PASS" start="2026-09-02T01:16:56.650672" elapsed="0.032192"/>
</for>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.687879" level="INFO">${min} = 23296.35</msg>
<var>${min}</var>
<arg>${result_value_list[${0}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-09-02T01:16:56.683117" elapsed="0.004797"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.692809" level="INFO">${max} = 24362.25</msg>
<var>${max}</var>
<arg>${result_value_list[${1}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-09-02T01:16:56.688128" elapsed="0.004715"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.697792" level="INFO">${average} = 23904.79</msg>
<var>${average}</var>
<arg>${result_value_list[${2}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-09-02T01:16:56.693058" elapsed="0.004768"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.701472" level="INFO">${stdev} = 7976.34</msg>
<var>${stdev}</var>
<arg>${result_value_list[${3}]}</arg>
<doc>Returns the given values which can then be assigned to a variables.</doc>
<status status="PASS" start="2026-09-02T01:16:56.698057" elapsed="0.003442"/>
</kw>
<kw name="Get Time" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.702040" level="INFO">${date} = 2026-09-02 01:16:56</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-02T01:16:56.701688" elapsed="0.000376"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.702572" level="INFO">CBench Result: 2026-09-02 01:16:56,-m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633,23296.35,24362.25,23904.79,7976.34</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-02T01:16:56.702201" elapsed="0.000477"/>
</kw>
<kw name="Log Results And Determine Status">
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-09-02T01:16:56.703733" level="INFO" html="true">Appended to file '&lt;a href="file:///w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/bug.csv"&gt;/w/workspace/openflowplugin-csit-1node-cbench-only-vanadium/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-02T01:16:56.703283" elapsed="0.000514"/>
</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-02T01:16:56.703932" elapsed="0.000432"/>
</kw>
<arg>${min}</arg>
<arg>${max}</arg>
<arg>${average}</arg>
<arg>${average_threshold}</arg>
<arg>${output_filename}</arg>
<status status="PASS" start="2026-09-02T01:16:56.702831" elapsed="0.001591"/>
</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-02T01:06:49.912316" elapsed="606.792158"/>
</kw>
<kw name="Sleep" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.704917" level="INFO">Slept 0 seconds.</msg>
<arg>${test_delay}</arg>
<doc>Pauses the test executed for the given time.</doc>
<status status="PASS" start="2026-09-02T01:16:56.704633" elapsed="0.000348"/>
</kw>
<kw name="Report Failure Due To Bug" owner="Utils" type="TEARDOWN">
<kw name="Evaluate" owner="BuiltIn">
<msg time="2026-09-02T01:16:56.706154" 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-02T01:16:56.705647" elapsed="0.000533"/>
</kw>
<if>
<branch type="IF" condition="('${TEST_STATUS}' != 'FAIL') or ${test_skipped}">
<return>
<status status="PASS" start="2026-09-02T01:16:56.706388" elapsed="0.000030"/>
</return>
<status status="PASS" start="2026-09-02T01:16:56.706251" elapsed="0.000194"/>
</branch>
<status status="PASS" start="2026-09-02T01:16:56.706233" elapsed="0.000234"/>
</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-02T01:16:56.706627" elapsed="0.000022"/>
</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-02T01:16:56.706773" elapsed="0.000018"/>
</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-02T01:16:56.706915" elapsed="0.000018"/>
</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 variables.</doc>
<status status="NOT RUN" start="2026-09-02T01:16:56.707074" elapsed="0.000018"/>
</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-02T01:16:56.707243" elapsed="0.000018"/>
</kw>
<kw name="Set Test Message" owner="BuiltIn">
<arg>${msg}${newline}${newline}${TEST_MESSAGE}</arg>
<doc>Sets message for the current test case.</doc>
<status status="NOT RUN" start="2026-09-02T01:16:56.707371" elapsed="0.000018"/>
</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-02T01:16:56.707499" elapsed="0.000018"/>
</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 given ``tags`` for the current test or all tests in a suite.</doc>
<status status="NOT RUN" start="2026-09-02T01:16:56.707741" elapsed="0.000024"/>
</kw>
<status status="NOT RUN" start="2026-09-02T01:16:56.707597" elapsed="0.000199"/>
</branch>
<status status="NOT RUN" start="2026-09-02T01:16:56.707578" elapsed="0.000238"/>
</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-02T01:16:56.705326" elapsed="0.002540"/>
</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-02T01:06:49.910948" elapsed="606.796960"/>
</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-02T00:46:34.007433" elapsed="1822.701174"/>
</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>
