Closed peppepetra closed 8 years ago
Can you provide a bit more information? INclude the logmessage and also what backend library you are using?
Also have you installed the last NetIP library?
I can't update NetIp lib now. Beacuse we forked it and it is released with the Shim. I am receiving Fence messages in the Shim. Is that possible?
@peppepetra might be a bug in the core. Can you check the log of the core why it is forwarding these messages?
2016-02-05 17:27:37,426 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,426 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,426 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1870,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,426 | INFO | pool-6-thread-3 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,426 | INFO | pool-6-thread-3 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,426 | WARN | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1870,DatapathId=1],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=50, bufferId=316, inPort=1, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,426 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,426 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,426 | WARN | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 1
2016-02-05 17:27:37,426 | INFO | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,430 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,430 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,430 | INFO | pool-6-thread-12 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=170,ModuleId=0,TransactionId=0,DatapathId=3],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=311, totalLen=508, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=2), data=[51, 51, 0, 0, 0, -5, 6, -81, 39, -80, -121, -40, -122, -35, 96, 0, 0, 0, 1, -58, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 4, -81, 39, -1, -2, -80, -121, -40, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -58, -33, 82, 0, 0, -124, 0, 0, 0, 0, 11, 0, 0, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 48, 54, 58, 97, 102, 58, 50, 55, 58, 98, 48, 58, 56, 55, 58, 100, 56, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0])]
2016-02-05 17:27:37,430 | INFO | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=170,ModuleId=0,TransactionId=0,DatapathId=2],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=311, totalLen=508, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=3), data=[51, 51, 0, 0, 0, -5, 6, -81, 39, -80, -121, -40, -122, -35, 96, 0, 0, 0, 1, -58, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 4, -81, 39, -1, -2, -80, -121, -40, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -58, -33, 82, 0, 0, -124, 0, 0, 0, 0, 11, 0, 0, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 48, 54, 58, 97, 102, 58, 50, 55, 58, 98, 48, 58, 56, 55, 58, 100, 56, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0])]
2016-02-05 17:27:37,430 | INFO | pool-6-thread-12 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,430 | INFO | pool-6-thread-12 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1871,DatapathId=3],Payload=[4, 10, 0, -86, 0, 0, 0, 0, 0, 0, 1, 55, 1, -4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, 51, 51, 0, 0, 0, -5, 6, -81, 39, -80, -121, -40, -122, -35, 96, 0, 0, 0, 1, -58, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 4, -81, 39, -1, -2, -80, -121, -40, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -58, -33, 82, 0, 0, -124, 0, 0, 0, 0, 11, 0, 0, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 48, 54, 58, 97, 102, 58, 50, 55, 58, 98, 48, 58, 56, 55, 58, 100, 56, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,430 | INFO | pool-6-thread-12 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,430 | INFO | pool-6-thread-12 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,430 | INFO | pool-6-thread-12 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,432 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,432 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,432 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1871,DatapathId=3],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=49, bufferId=311, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,432 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,432 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,432 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1871,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,432 | INFO | pool-6-thread-12 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,432 | INFO | pool-6-thread-12 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,432 | WARN | pool-6-thread-12 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1871,DatapathId=3],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=49, bufferId=311, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,432 | INFO | pool-6-thread-12 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,432 | INFO | pool-6-thread-12 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,432 | WARN | pool-6-thread-12 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 3
2016-02-05 17:27:37,432 | INFO | pool-6-thread-12 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,432 | INFO | pool-6-thread-16 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,432 | INFO | pool-6-thread-16 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1872,DatapathId=2],Payload=[4, 10, 0, -86, 0, 0, 0, 0, 0, 0, 1, 55, 1, -4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 3, 0, 0, 0, 0, 0, 0, 51, 51, 0, 0, 0, -5, 6, -81, 39, -80, -121, -40, -122, -35, 96, 0, 0, 0, 1, -58, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 4, -81, 39, -1, -2, -80, -121, -40, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -58, -33, 82, 0, 0, -124, 0, 0, 0, 0, 11, 0, 0, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 48, 54, 58, 97, 102, 58, 50, 55, 58, 98, 48, 58, 56, 55, 58, 100, 56, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,432 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,432 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,432 | INFO | pool-6-thread-16 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,434 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,434 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,434 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1872,DatapathId=2],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=49, bufferId=311, inPort=3, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,434 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,434 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,434 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1872,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,434 | INFO | pool-6-thread-16 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,434 | INFO | pool-6-thread-16 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,435 | WARN | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1872,DatapathId=2],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=49, bufferId=311, inPort=3, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,435 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,435 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,435 | WARN | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 2
2016-02-05 17:27:37,435 | INFO | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,442 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,446 | INFO | pool-6-thread-12 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=170,ModuleId=0,TransactionId=0,DatapathId=3],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=312, totalLen=342, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=2), data=[-1, -1, -1, -1, -1, -1, 62, 80, 50, 106, -16, 94, 8, 0, 69, 16, 1, 72, 0, 0, 0, 0, -128, 17, 57, -106, 0, 0, 0, 0, -1, -1, -1, -1, 0, 68, 0, 67, 1, 52, 85, -97, 1, 1, 6, 0, -18, 11, -86, 21, 0, 3, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 62, 80, 50, 106, -16, 94, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0])]
2016-02-05 17:27:37,446 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,446 | INFO | pool-6-thread-12 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,446 | INFO | pool-6-thread-12 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1873,DatapathId=3],Payload=[4, 10, 0, -86, 0, 0, 0, 0, 0, 0, 1, 56, 1, 86, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, -1, -1, -1, -1, -1, -1, 62, 80, 50, 106, -16, 94, 8, 0, 69, 16, 1, 72, 0, 0, 0, 0, -128, 17, 57, -106, 0, 0, 0, 0, -1, -1, -1, -1, 0, 68, 0, 67, 1, 52, 85, -97, 1, 1, 6, 0, -18, 11, -86, 21, 0, 3, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 62, 80, 50, 106, -16, 94, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,446 | INFO | pool-6-thread-12 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,447 | INFO | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=132,ModuleId=0,TransactionId=0,DatapathId=1],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=317, totalLen=90, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=2), data=[51, 51, 0, 0, 0, 22, 18, -13, -27, 30, 79, -68, -122, -35, 96, 0, 0, 0, 0, 36, 0, 1, -2, -128, 0, 0, 0, 0, 0, 0, 16, -13, -27, -1, -2, 30, 79, -68, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 22, 58, 0, 5, 2, 0, 0, 1, 0, -113, 0, 42, 66, 0, 0, 0, 1, 4, 0, 0, 0, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5])]
2016-02-05 17:27:37,447 | INFO | pool-6-thread-12 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,447 | INFO | pool-6-thread-12 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,451 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,451 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,451 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1873,DatapathId=3],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=50, bufferId=312, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,451 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,451 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,451 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1873,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,451 | INFO | pool-6-thread-12 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,451 | INFO | pool-6-thread-12 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,451 | WARN | pool-6-thread-12 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1873,DatapathId=3],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=50, bufferId=312, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,452 | INFO | pool-6-thread-12 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,452 | INFO | pool-6-thread-12 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,452 | WARN | pool-6-thread-12 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 3
2016-02-05 17:27:37,452 | INFO | pool-6-thread-12 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,452 | INFO | pool-6-thread-9 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,452 | INFO | pool-6-thread-9 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1874,DatapathId=1],Payload=[4, 10, 0, -124, 0, 0, 0, 0, 0, 0, 1, 61, 0, 90, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, 51, 51, 0, 0, 0, 22, 18, -13, -27, 30, 79, -68, -122, -35, 96, 0, 0, 0, 0, 36, 0, 1, -2, -128, 0, 0, 0, 0, 0, 0, 16, -13, -27, -1, -2, 30, 79, -68, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 22, 58, 0, 5, 2, 0, 0, 1, 0, -113, 0, 42, 66, 0, 0, 0, 1, 4, 0, 0, 0, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,452 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,452 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,452 | INFO | pool-6-thread-9 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,453 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,453 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,453 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1874,DatapathId=1],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=51, bufferId=317, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,453 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,453 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,453 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1874,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,453 | INFO | pool-6-thread-9 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,453 | INFO | pool-6-thread-9 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,453 | WARN | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1874,DatapathId=1],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=51, bufferId=317, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,453 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,454 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,454 | WARN | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 1
2016-02-05 17:27:37,454 | INFO | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,455 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,455 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,455 | INFO | pool-6-thread-12 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=132,ModuleId=0,TransactionId=0,DatapathId=10],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=300, totalLen=90, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=2), data=[51, 51, 0, 0, 0, 22, 18, -13, -27, 30, 79, -68, -122, -35, 96, 0, 0, 0, 0, 36, 0, 1, -2, -128, 0, 0, 0, 0, 0, 0, 16, -13, -27, -1, -2, 30, 79, -68, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 22, 58, 0, 5, 2, 0, 0, 1, 0, -113, 0, 42, 66, 0, 0, 0, 1, 4, 0, 0, 0, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5])]
2016-02-05 17:27:37,455 | INFO | pool-6-thread-12 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,455 | INFO | pool-6-thread-12 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1875,DatapathId=10],Payload=[4, 10, 0, -124, 0, 0, 0, 0, 0, 0, 1, 44, 0, 90, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, 51, 51, 0, 0, 0, 22, 18, -13, -27, 30, 79, -68, -122, -35, 96, 0, 0, 0, 0, 36, 0, 1, -2, -128, 0, 0, 0, 0, 0, 0, 16, -13, -27, -1, -2, 30, 79, -68, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 22, 58, 0, 5, 2, 0, 0, 1, 0, -113, 0, 42, 66, 0, 0, 0, 1, 4, 0, 0, 0, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,455 | INFO | pool-6-thread-12 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,455 | INFO | pool-6-thread-12 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,455 | INFO | pool-6-thread-12 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,455 | INFO | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=132,ModuleId=0,TransactionId=0,DatapathId=3],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=313, totalLen=90, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=2), data=[51, 51, 0, 0, 0, 22, 18, -13, -27, 30, 79, -68, -122, -35, 96, 0, 0, 0, 0, 36, 0, 1, -2, -128, 0, 0, 0, 0, 0, 0, 16, -13, -27, -1, -2, 30, 79, -68, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 22, 58, 0, 5, 2, 0, 0, 1, 0, -113, 0, 42, 66, 0, 0, 0, 1, 4, 0, 0, 0, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5])]
2016-02-05 17:27:37,457 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,457 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,457 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1875,DatapathId=10],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=49, bufferId=300, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,457 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,457 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,457 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1875,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,457 | INFO | pool-6-thread-12 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,457 | INFO | pool-6-thread-12 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,457 | WARN | pool-6-thread-12 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1875,DatapathId=10],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=49, bufferId=300, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,457 | INFO | pool-6-thread-12 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,457 | INFO | pool-6-thread-12 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,457 | WARN | pool-6-thread-12 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 10
2016-02-05 17:27:37,457 | INFO | pool-6-thread-12 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,457 | INFO | pool-6-thread-3 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,458 | INFO | pool-6-thread-3 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1876,DatapathId=3],Payload=[4, 10, 0, -124, 0, 0, 0, 0, 0, 0, 1, 57, 0, 90, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, 51, 51, 0, 0, 0, 22, 18, -13, -27, 30, 79, -68, -122, -35, 96, 0, 0, 0, 0, 36, 0, 1, -2, -128, 0, 0, 0, 0, 0, 0, 16, -13, -27, -1, -2, 30, 79, -68, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 22, 58, 0, 5, 2, 0, 0, 1, 0, -113, 0, 42, 66, 0, 0, 0, 1, 4, 0, 0, 0, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,458 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,458 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,458 | INFO | pool-6-thread-3 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,459 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,459 | INFO | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=170,ModuleId=0,TransactionId=0,DatapathId=2],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=312, totalLen=342, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=3), data=[-1, -1, -1, -1, -1, -1, 110, -53, -1, 26, -3, 89, 8, 0, 69, 16, 1, 72, 0, 0, 0, 0, -128, 17, 57, -106, 0, 0, 0, 0, -1, -1, -1, -1, 0, 68, 0, 67, 1, 52, -10, -71, 1, 1, 6, 0, -100, -48, 80, 15, 0, 3, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 110, -53, -1, 26, -3, 89, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0])]
2016-02-05 17:27:37,460 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,460 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,460 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1876,DatapathId=3],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=51, bufferId=313, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,460 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,460 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,460 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1876,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,460 | INFO | pool-6-thread-3 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,460 | INFO | pool-6-thread-3 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,460 | WARN | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1876,DatapathId=3],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=51, bufferId=313, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,460 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,460 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,460 | WARN | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 3
2016-02-05 17:27:37,460 | INFO | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,461 | INFO | pool-6-thread-16 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,461 | INFO | pool-6-thread-16 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1877,DatapathId=2],Payload=[4, 10, 0, -86, 0, 0, 0, 0, 0, 0, 1, 56, 1, 86, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 3, 0, 0, 0, 0, 0, 0, -1, -1, -1, -1, -1, -1, 110, -53, -1, 26, -3, 89, 8, 0, 69, 16, 1, 72, 0, 0, 0, 0, -128, 17, 57, -106, 0, 0, 0, 0, -1, -1, -1, -1, 0, 68, 0, 67, 1, 52, -10, -71, 1, 1, 6, 0, -100, -48, 80, 15, 0, 3, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 110, -53, -1, 26, -3, 89, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,461 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,461 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,461 | INFO | pool-6-thread-16 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,462 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,462 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,462 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1877,DatapathId=2],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=50, bufferId=312, inPort=3, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,462 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,462 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,462 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1877,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,462 | INFO | pool-6-thread-16 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,462 | INFO | pool-6-thread-16 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,462 | WARN | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1877,DatapathId=2],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=50, bufferId=312, inPort=3, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,463 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,463 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,463 | WARN | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 2
2016-02-05 17:27:37,463 | INFO | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,543 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,544 | INFO | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=170,ModuleId=0,TransactionId=0,DatapathId=2],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=313, totalLen=544, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=3), data=[51, 51, 0, 0, 0, -5, 110, -53, -1, 26, -3, 89, -122, -35, 96, 0, 0, 0, 1, -22, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 108, -53, -1, -1, -2, 26, -3, 89, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -22, 121, 37, 0, 0, 0, 0, 0, 6, 0, 0, 0, 11, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 54, 101, 58, 99, 98, 58, 102, 102, 58, 49, 97, 58, 102, 100, 58, 53, 57, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0])]
2016-02-05 17:27:37,544 | INFO | pool-6-thread-3 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,544 | INFO | pool-6-thread-3 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1878,DatapathId=2],Payload=[4, 10, 0, -86, 0, 0, 0, 0, 0, 0, 1, 57, 2, 32, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 3, 0, 0, 0, 0, 0, 0, 51, 51, 0, 0, 0, -5, 110, -53, -1, 26, -3, 89, -122, -35, 96, 0, 0, 0, 1, -22, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 108, -53, -1, -1, -2, 26, -3, 89, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -22, 121, 37, 0, 0, 0, 0, 0, 6, 0, 0, 0, 11, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 54, 101, 58, 99, 98, 58, 102, 102, 58, 49, 97, 58, 102, 100, 58, 53, 57, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,544 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,544 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,544 | INFO | pool-6-thread-3 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,545 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,545 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,545 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1878,DatapathId=2],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=51, bufferId=313, inPort=3, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,545 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,545 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,545 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1878,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,545 | INFO | pool-6-thread-3 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,545 | INFO | pool-6-thread-3 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,549 | WARN | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1878,DatapathId=2],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=51, bufferId=313, inPort=3, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,549 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,549 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,549 | WARN | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 2
2016-02-05 17:27:37,549 | INFO | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,564 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,564 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,564 | INFO | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=170,ModuleId=0,TransactionId=0,DatapathId=10],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=301, totalLen=508, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=2), data=[51, 51, 0, 0, 0, -5, 90, -25, 127, 29, -18, -40, -122, -35, 96, 0, 0, 0, 1, -58, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 88, -25, 127, -1, -2, 29, -18, -40, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -58, 94, 90, 0, 0, -124, 0, 0, 0, 0, 11, 0, 0, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 53, 97, 58, 101, 55, 58, 55, 102, 58, 49, 100, 58, 101, 101, 58, 100, 56, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0])]
2016-02-05 17:27:37,564 | INFO | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=170,ModuleId=0,TransactionId=0,DatapathId=1],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=318, totalLen=508, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=2), data=[51, 51, 0, 0, 0, -5, 18, -13, -27, 30, 79, -68, -122, -35, 96, 0, 0, 0, 1, -58, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 16, -13, -27, -1, -2, 30, 79, -68, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -58, -64, 112, 0, 0, -124, 0, 0, 0, 0, 11, 0, 0, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 49, 50, 58, 102, 51, 58, 101, 53, 58, 49, 101, 58, 52, 102, 58, 98, 99, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0])]
2016-02-05 17:27:37,564 | INFO | pool-6-thread-16 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,564 | INFO | pool-6-thread-16 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1879,DatapathId=10],Payload=[4, 10, 0, -86, 0, 0, 0, 0, 0, 0, 1, 45, 1, -4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, 51, 51, 0, 0, 0, -5, 90, -25, 127, 29, -18, -40, -122, -35, 96, 0, 0, 0, 1, -58, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 88, -25, 127, -1, -2, 29, -18, -40, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -58, 94, 90, 0, 0, -124, 0, 0, 0, 0, 11, 0, 0, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 53, 97, 58, 101, 55, 58, 55, 102, 58, 49, 100, 58, 101, 101, 58, 100, 56, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,564 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,565 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,565 | INFO | pool-6-thread-16 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,566 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,566 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,566 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1879,DatapathId=10],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=50, bufferId=301, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,566 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,566 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,566 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1879,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,567 | INFO | pool-6-thread-16 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,567 | INFO | pool-6-thread-16 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,567 | WARN | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1879,DatapathId=10],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=50, bufferId=301, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,567 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,567 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,567 | WARN | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 10
2016-02-05 17:27:37,567 | INFO | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,567 | INFO | pool-6-thread-9 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,567 | INFO | pool-6-thread-9 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1880,DatapathId=1],Payload=[4, 10, 0, -86, 0, 0, 0, 0, 0, 0, 1, 62, 1, -4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, 51, 51, 0, 0, 0, -5, 18, -13, -27, 30, 79, -68, -122, -35, 96, 0, 0, 0, 1, -58, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 16, -13, -27, -1, -2, 30, 79, -68, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -58, -64, 112, 0, 0, -124, 0, 0, 0, 0, 11, 0, 0, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 49, 50, 58, 102, 51, 58, 101, 53, 58, 49, 101, 58, 52, 102, 58, 98, 99, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,567 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,567 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,567 | INFO | pool-6-thread-9 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,571 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,571 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,572 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1880,DatapathId=1],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=52, bufferId=318, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,572 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,572 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,572 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1880,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,572 | INFO | pool-6-thread-9 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,572 | INFO | pool-6-thread-9 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,572 | WARN | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1880,DatapathId=1],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=52, bufferId=318, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,572 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,572 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,572 | WARN | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 1
2016-02-05 17:27:37,572 | INFO | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,574 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,574 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,574 | INFO | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=170,ModuleId=0,TransactionId=0,DatapathId=10],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=302, totalLen=508, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=2), data=[51, 51, 0, 0, 0, -5, 18, -13, -27, 30, 79, -68, -122, -35, 96, 0, 0, 0, 1, -58, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 16, -13, -27, -1, -2, 30, 79, -68, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -58, -64, 112, 0, 0, -124, 0, 0, 0, 0, 11, 0, 0, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 49, 50, 58, 102, 51, 58, 101, 53, 58, 49, 101, 58, 52, 102, 58, 98, 99, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0])]
2016-02-05 17:27:37,574 | INFO | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=170,ModuleId=0,TransactionId=0,DatapathId=3],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=314, totalLen=508, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=2), data=[51, 51, 0, 0, 0, -5, 18, -13, -27, 30, 79, -68, -122, -35, 96, 0, 0, 0, 1, -58, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 16, -13, -27, -1, -2, 30, 79, -68, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -58, -64, 112, 0, 0, -124, 0, 0, 0, 0, 11, 0, 0, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 49, 50, 58, 102, 51, 58, 101, 53, 58, 49, 101, 58, 52, 102, 58, 98, 99, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0])]
2016-02-05 17:27:37,574 | INFO | pool-6-thread-16 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,574 | INFO | pool-6-thread-16 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1881,DatapathId=10],Payload=[4, 10, 0, -86, 0, 0, 0, 0, 0, 0, 1, 46, 1, -4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, 51, 51, 0, 0, 0, -5, 18, -13, -27, 30, 79, -68, -122, -35, 96, 0, 0, 0, 1, -58, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 16, -13, -27, -1, -2, 30, 79, -68, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -58, -64, 112, 0, 0, -124, 0, 0, 0, 0, 11, 0, 0, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 49, 50, 58, 102, 51, 58, 101, 53, 58, 49, 101, 58, 52, 102, 58, 98, 99, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,574 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,574 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,575 | INFO | pool-6-thread-16 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,578 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,578 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,578 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1881,DatapathId=10],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=51, bufferId=302, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,578 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,578 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,578 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1881,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,578 | INFO | pool-6-thread-16 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,578 | INFO | pool-6-thread-16 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,578 | WARN | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1881,DatapathId=10],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=51, bufferId=302, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,578 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,578 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,578 | WARN | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 10
2016-02-05 17:27:37,578 | INFO | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,578 | INFO | pool-6-thread-9 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,578 | INFO | pool-6-thread-9 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1882,DatapathId=3],Payload=[4, 10, 0, -86, 0, 0, 0, 0, 0, 0, 1, 58, 1, -4, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, 51, 51, 0, 0, 0, -5, 18, -13, -27, 30, 79, -68, -122, -35, 96, 0, 0, 0, 1, -58, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, 16, -13, -27, -1, -2, 30, 79, -68, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -58, -64, 112, 0, 0, -124, 0, 0, 0, 0, 11, 0, 0, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 49, 50, 58, 102, 51, 58, 101, 53, 58, 49, 101, 58, 52, 102, 58, 98, 99, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,578 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,578 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,578 | INFO | pool-6-thread-9 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,580 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,580 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,580 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1882,DatapathId=3],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=52, bufferId=314, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,580 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,580 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,580 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1882,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,580 | INFO | pool-6-thread-9 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,580 | INFO | pool-6-thread-9 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,580 | WARN | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1882,DatapathId=3],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=52, bufferId=314, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,580 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,580 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,580 | WARN | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 3
2016-02-05 17:27:37,580 | INFO | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,600 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,600 | INFO | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=170,ModuleId=0,TransactionId=0,DatapathId=1],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=319, totalLen=544, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=3), data=[51, 51, 0, 0, 0, -5, -50, -48, 88, 19, -120, -15, -122, -35, 96, 0, 0, 0, 1, -22, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, -52, -48, 88, -1, -2, 19, -120, -15, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -22, -119, -26, 0, 0, 0, 0, 0, 6, 0, 0, 0, 11, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 99, 101, 58, 100, 48, 58, 53, 56, 58, 49, 51, 58, 56, 56, 58, 102, 49, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0])]
2016-02-05 17:27:37,601 | INFO | pool-6-thread-16 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,601 | INFO | pool-6-thread-16 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1883,DatapathId=1],Payload=[4, 10, 0, -86, 0, 0, 0, 0, 0, 0, 1, 63, 2, 32, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 3, 0, 0, 0, 0, 0, 0, 51, 51, 0, 0, 0, -5, -50, -48, 88, 19, -120, -15, -122, -35, 96, 0, 0, 0, 1, -22, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, -52, -48, 88, -1, -2, 19, -120, -15, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -22, -119, -26, 0, 0, 0, 0, 0, 6, 0, 0, 0, 11, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 99, 101, 58, 100, 48, 58, 53, 56, 58, 49, 51, 58, 56, 56, 58, 102, 49, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,601 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,601 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,601 | INFO | pool-6-thread-16 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,602 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,602 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,602 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1883,DatapathId=1],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=53, bufferId=319, inPort=3, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,602 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,602 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,602 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1883,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,602 | INFO | pool-6-thread-16 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,603 | INFO | pool-6-thread-16 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,603 | WARN | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1883,DatapathId=1],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=53, bufferId=319, inPort=3, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,603 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,603 | INFO | pool-6-thread-16 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,603 | WARN | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 1
2016-02-05 17:27:37,603 | INFO | pool-6-thread-16 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,604 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,604 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'shim'.
2016-02-05 17:27:37,604 | INFO | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=170,ModuleId=0,TransactionId=0,DatapathId=2],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=314, totalLen=544, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=3), data=[51, 51, 0, 0, 0, -5, -50, -48, 88, 19, -120, -15, -122, -35, 96, 0, 0, 0, 1, -22, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, -52, -48, 88, -1, -2, 19, -120, -15, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -22, -119, -26, 0, 0, 0, 0, 0, 6, 0, 0, 0, 11, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 99, 101, 58, 100, 48, 58, 53, 56, 58, 49, 51, 58, 56, 56, 58, 102, 49, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0])]
2016-02-05 17:27:37,604 | INFO | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | CompositionManager received message from shim: OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=170,ModuleId=0,TransactionId=0,DatapathId=10],Type=PACKET_IN,OFMessage=OFPacketInVer13(xid=0, bufferId=303, totalLen=544, reason=NO_MATCH, tableId=0x0, cookie=0x0000000000000000, match=OFMatchV3Ver13(in_port=2), data=[51, 51, 0, 0, 0, -5, -50, -48, 88, 19, -120, -15, -122, -35, 96, 0, 0, 0, 1, -22, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, -52, -48, 88, -1, -2, 19, -120, -15, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -22, -119, -26, 0, 0, 0, 0, 0, 6, 0, 0, 0, 11, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 99, 101, 58, 100, 48, 58, 53, 56, 58, 49, 51, 58, 56, 56, 58, 102, 49, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0])]
2016-02-05 17:27:37,605 | INFO | pool-6-thread-9 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,605 | INFO | pool-6-thread-9 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1884,DatapathId=2],Payload=[4, 10, 0, -86, 0, 0, 0, 0, 0, 0, 1, 58, 2, 32, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 3, 0, 0, 0, 0, 0, 0, 51, 51, 0, 0, 0, -5, -50, -48, 88, 19, -120, -15, -122, -35, 96, 0, 0, 0, 1, -22, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, -52, -48, 88, -1, -2, 19, -120, -15, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -22, -119, -26, 0, 0, 0, 0, 0, 6, 0, 0, 0, 11, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 99, 101, 58, 100, 48, 58, 53, 56, 58, 49, 51, 58, 56, 56, 58, 102, 49, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,605 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,605 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,605 | INFO | pool-6-thread-9 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,606 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,606 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,606 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1884,DatapathId=2],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=52, bufferId=314, inPort=3, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,606 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,606 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,606 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1884,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,606 | INFO | pool-6-thread-9 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,606 | INFO | pool-6-thread-9 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,606 | WARN | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1884,DatapathId=2],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=52, bufferId=314, inPort=3, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,606 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,606 | INFO | pool-6-thread-9 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,606 | WARN | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 2
2016-02-05 17:27:37,606 | INFO | pool-6-thread-9 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
2016-02-05 17:27:37,606 | INFO | pool-6-thread-3 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending Request to module '198'...
2016-02-05 17:27:37,607 | INFO | pool-6-thread-3 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending message 'Message [Header=MessageHeader [Version=VERSION_1_1,Type=OPENFLOW,Length=0,ModuleId=198,TransactionId=1885,DatapathId=10],Payload=[4, 10, 0, -86, 0, 0, 0, 0, 0, 0, 1, 47, 2, 32, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 12, -128, 0, 0, 4, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, 51, 51, 0, 0, 0, -5, -50, -48, 88, 19, -120, -15, -122, -35, 96, 0, 0, 0, 1, -22, 17, -1, -2, -128, 0, 0, 0, 0, 0, 0, -52, -48, 88, -1, -2, 19, -120, -15, -1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, -5, 20, -23, 20, -23, 1, -22, -119, -26, 0, 0, 0, 0, 0, 6, 0, 0, 0, 11, 0, 0, 28, 73, 82, 73, 76, 68, 48, 50, 48, 32, 91, 99, 101, 58, 100, 48, 58, 53, 56, 58, 49, 51, 58, 56, 56, 58, 102, 49, 93, 12, 95, 119, 111, 114, 107, 115, 116, 97, 116, 105, 111, 110, 4, 95, 116, 99, 112, 5, 108, 111, 99, 97, 108, 0]]' to backend 'backend-ryu-25649'.
2016-02-05 17:27:37,607 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'backend-ryu-25649'.
2016-02-05 17:27:37,607 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,607 | INFO | pool-6-thread-3 | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Waiting for request with id '198' to complete...
2016-02-05 17:27:37,609 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,609 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,609 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1885,DatapathId=10],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=52, bufferId=303, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:27:37,609 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-25649'.
2016-02-05 17:27:37,609 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-25649' with moduleId '198'.
2016-02-05 17:27:37,609 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data completes request (Message [Header=MessageHeader [Version=VERSION_1_2,Type=FENCE,Length=0,ModuleId=198,TransactionId=1885,DatapathId=0],Payload=[]]).
2016-02-05 17:27:37,609 | INFO | pool-6-thread-3 | ModuleCallNodeExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | Request returned from module '198'.
2016-02-05 17:27:37,609 | INFO | pool-6-thread-3 | SequentialFlowExecutor | 67 - core.caos - 1.0.0.0-SNAPSHOT | End of sequential flow reached. Collected 1 messages.
2016-02-05 17:27:37,609 | WARN | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sending composition result message OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=198,TransactionId=1885,DatapathId=10],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=52, bufferId=303, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])] to shim.
2016-02-05 17:27:37,609 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sending to via relay to 'shim'.
2016-02-05 17:27:37,609 | INFO | pool-6-thread-3 | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Sent.
2016-02-05 17:27:37,609 | WARN | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Sent 1 rules to switch 10
2016-02-05 17:27:37,609 | INFO | pool-6-thread-3 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Flow execution finished.
This is part of the log. It starts sending Fence message to the switch. And the composition is successfully loaded with noFenceSupport="true",
2016-02-05 17:32:17,493 | INFO | FelixStartLevel | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Accepted new CompositionSpecification '<?xml version="1.0" encoding="utf-8"?>
<CompositionSpecification xmlns="http://netide.eu/schemas/compositionspecification/v1">
<Modules>
<Module id="SimpleSwitch" loaderIdentification="simple_switch.py" noFenceSupport="true"/>
</Modules>
<Composition>
<ModuleCall module="SimpleSwitch"/>
</Composition>
</CompositionSpecification>
'.
2016-02-05 17:32:17,493 | INFO | FelixStartLevel | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Set composition finished.
2016-02-05 17:32:17,493 | INFO | pool-8-thread-1 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Waiting for required modules to connect, 1 left...
2016-02-05 17:32:17,494 | INFO | FelixStartLevel | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | Unsupported message bypass activated.
2016-02-05 17:32:17,508 | INFO | FelixStartLevel | LogPub | 68 - core.logpub - 1.0.0.0-SNAPSHOT | LogPub Constructor.
2016-02-05 17:32:17,509 | INFO | FelixStartLevel | LogPub | 68 - core.logpub - 1.0.0.0-SNAPSHOT | LogPub Start().
2016-02-05 17:32:17,512 | INFO | Pub Receive Loop | LogPub | 68 - core.logpub - 1.0.0.0-SNAPSHOT | LogPub started.
2016-02-05 17:32:17,521 | INFO | Pub Receive Loop | LogPub | 68 - core.logpub - 1.0.0.0-SNAPSHOT | Listening PUB queue on port 5557
2016-02-05 17:32:17,522 | INFO | Pub Receive Loop | LogPub | 68 - core.logpub - 1.0.0.0-SNAPSHOT | Control queue on address: inproc://LogPubControl
2016-02-05 17:32:17,533 | INFO | tor Receive Loop | ZeroMQBasedManagementConnector | 69 - core.management - 1.0.0.0-SNAPSHOT | ZeroMQBasedManagementConnector started.
2016-02-05 17:32:17,535 | INFO | FelixStartLevel | ManagementHandler | 69 - core.management - 1.0.0.0-SNAPSHOT | ManagementHandler started.
2016-02-05 17:32:17,542 | INFO | tor Receive Loop | ZeroMQBasedManagementConnector | 69 - core.management - 1.0.0.0-SNAPSHOT | Listening on port 5556
and also shim and backend are correctly connected to the Core.
2016-02-05 17:33:21,508 | INFO | pool-8-thread-1 | CompositionManager | 67 - core.caos - 1.0.0.0-SNAPSHOT | All required modules connected. Reconfiguration successful.
The simple switch works but it is unstable. It doesn't work "always".
If I delete and recreate the switches I receive two errors in the Core:
2016-02-05 17:36:39,912 | ERROR | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | FIXME
java.lang.IllegalStateException: Cannot add result messages after setting state to done.
at eu.netide.core.api.RequestResult.addResultMessage(RequestResult.java:38)[65:core.api:1.0.0.0-SNAPSHOT]
at eu.netide.core.connectivity.BackendManager.OnDataReceived(BackendManager.java:205)[66:core.connectivity:1.0.0.0-SNAPSHOT]
at eu.netide.core.connectivity.ZeroMQBasedConnector.run(ZeroMQBasedConnector.java:112)[66:core.connectivity:1.0.0.0-SNAPSHOT]
at java.lang.Thread.run(Thread.java:745)[:1.8.0_72-internal]
2016-02-05 17:36:39,912 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Message adds to running request (OpenFlowMessage [Header=MessageHeader [Version=VERSION_1_2,Type=OPENFLOW,Length=40,ModuleId=918,TransactionId=2869,DatapathId=10],Type=PACKET_OUT,OFMessage=OFPacketOutVer13(xid=163, bufferId=422, inPort=2, actions=[OFActionOutputVer13(port=flood, maxLen=65509)], data=[])]).
2016-02-05 17:36:39,912 | INFO | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from 'backend-ryu-27254'.
2016-02-05 17:36:39,912 | INFO | tor Receive Loop | BackendManager | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Data received from backend 'backend-ryu-27254' with moduleId '918'.
2016-02-05 17:36:39,912 | ERROR | tor Receive Loop | ZeroMQBasedConnector | 66 - core.connectivity - 1.0.0.0-SNAPSHOT | Error in ZeroMQBasedConnector receive loop.
java.lang.IllegalStateException: Cannot set finished state more than once.
at eu.netide.core.api.RequestResult.signalIsDone(RequestResult.java:86)[65:core.api:1.0.0.0-SNAPSHOT]
at eu.netide.core.connectivity.BackendManager.OnDataReceived(BackendManager.java:199)[66:core.connectivity:1.0.0.0-SNAPSHOT]
at eu.netide.core.connectivity.ZeroMQBasedConnector.run(ZeroMQBasedConnector.java:112)[66:core.connectivity:1.0.0.0-SNAPSHOT]
at java.lang.Thread.run(Thread.java:745)[:1.8.0_72-internal]
I am using the last version of ryu-backend with simple_switch_13.py And ODL-Shim. The topology is created using https://github.com/fp7-netide/Engine/blob/master/ryu-backend/tests/netide-topo.py and substing Openflow10 with Openflow13 in the file in all the occurences
@peppepetra restarting/reconnecting modules is not supported at all because we have not yet defined a semantic for that. Error there are expected.
As for the fence message error. I do not see the error in the core log.
Implementing the fence message in the ODL shim version of netip should not be hard. Could you at least capture the errornous message and print the byte array of it? Then I could extract the transaction id of it and see what message in the core log it belong to.
Or is every fence message forwarded?
I suppose that all the Fence messages are forwarded to the Shim.
@peppepetra I do not believe that all fence messages are sent to the shim. There something else going on in your example. It should only be the case for unexpected fence messages.
I pushed a commit to master that will drop fence messages in this case.
Thanks @schwabe. Simple Switch is working fine with RyuBackend-JavaCore-ODLShim using two different karaf instances.
Sorry @schwabe I am receiving from the Java Core NetIP messages of type 8 that cause an IllegalArgumentException in the NetIP lib, as 8 is not recognized as a valid NetIP type.
Can you help me with that?