Loggers - Gateway Network

This article covers loggers related to Redundancy that were introduced in Ignition version 7.9.X.

Loggers in this article:

• metro.Transports.Websocket.WebSocketConnection

metro.Transports.Websocket.WSConnection

• Provides information about initialization, active connection, and closing of GAN connections. 7.8 contains a logger with the same name, however, the output of this logger provides different information for each level.
  • INFO logger showing both the initialization and later, the closing of the same connection from the outgoing connection’s perspective:

    [100] 10.10.65.60:8088 connection status has been updated from Initialized to Connecting gan-remote-connection-id=http://10.10.65.60:8088/main/system … [534414562] ignition-ws connection status has been updated from Connecting to Running: Ping to remote machine was successful gan-remote-connection-id=http://10.10.65.60:8088/main/system … -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=pingSuccess: Session id now set to [534414562] gan-remote-connection-id=http://10.10.65.60:8088/main/system … -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=shutdown: [534414562] Shutting down connection 'ignition-su-2c8rz12-ws' to http://10.10.65.60:8088/main/system: Outgoing: AbstractHttpConnectionFactory.destroyConnection() gan-remote-connection-id=http://10.10.65.60:8088/main/system [534414562] ignition-ws connection status has been updated from Running to Shutdown: shutdown() has been called gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=sendControlMsg: Web socket session has already been lost, SHUTDOWN message will not be sent gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=shutdown: [534414562] Shutting down connection 'ignition-su-2c8rz12-ws' to http://10.10.65.60:8088/main/system: Outgoing: AbstractHttpConnectionFactory.destroyConnection() gan-remote-connection-id=http://10.10.65.60:8088/main/system

• • INFO logger showing both the initialization and later, the closing of the same connection from the incoming connection’s perspective:

    [100] ignition-su-2c8rz12-ws connection status has been updated from Unknown to Initialized gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 ... [100] ignition-su-2c8rz12-ws connection status has been updated from Initialized to Running: Web socket session established for ignition-su-2c8rz12-ws|72779e17-2502-48f7-a3c9-c901364e0670 gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 … <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=shutdown: [534414562] Shutting down connection 'ignition-ws' to http://10.10.60.75:8088/main/system: Incoming: AbstractHttpConnectionFactory.destroyConnection() gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 [534414562] ignition-su-2c8rz12-ws connection status has been updated from Running to Shutdown: shutdown() has been called gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 [534414562] ignition-su-2c8rz12-ws connection status has been updated from Shutdown to Faulted: onClose has been called on web socket:Shutdown gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670

• • DEBUG log showing additional detail for both the initialization and later, the closing of the same connection from the outgoing connection’s perspective. Also provides details regarding messages sent between nodes to ensure an active connection:

    -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=doSend: Message [2410] sent, processing time: 23 ms gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=Metro-RunDownloadThread: System processing time=1 ms, message=opCode=MSG_SEND, subCode=0, flags=1, messageId=18408, senderId='ignition-ws', targetAddress='_0:0:Ignition-SU-2C8RZ12-WS' gan-remote-connection-id=http://10.10.65.60:8088/main/system ... -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=Metro-RunDownloadThread: Message [18408] download complete, processing time: 5 ms gan-remote-connection-id=http://10.10.65.60:8088/main/system … -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=doSend: Message [2417] sent, processing time: 5 ms gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=Metro-RunDownloadThread: System processing time=1 ms, message=opCode=MSG_SEND, subCode=0, flags=1, messageId=18409, senderId='ignition-ws', targetAddress='_0:0:Ignition-SU-2C8RZ12-WS' gan-remote-connection-id=http://10.10.65.60:8088/main/system … -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=sendShutdownMsg: Sending SHUTDOWN control message across web socket gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=sendControlMsg: opCode=SHUTDOWN, subCode=5000, flags=0, messageId=2450, senderId='ignition-su-2c8rz12-ws', targetAddress='' gan-remote-connection-id=http://10.10.65.60:8088/main/system

• • DEBUG log showing additional detail for the initialization of the connection from the incoming connection’s perspective. Also provides details regarding messages sent between nodes to ensure an active connection:

    <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=onDataReceived: System processing time=7 ms, message=opCode=MSG_SEND, subCode=0, flags=0, messageId=80, senderId='ignition-su-2c8rz12-ws', targetAddress='' gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=onDataReceived: Local system has processed received message [80], routing processing time: 8 ms gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 … <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=onDataReceived: Message [80] receive operation complete gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670

• • TRACE log showing additional details for the initialization of the connection from the outgoing connection’s perspective:

    -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=reserveCapacity: Receive semaphore acquired=true, remaining permits: 99 gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=Metro-RunDownloadThread: Downloading message from remote: opCode=MSG_SEND, subCode=0, flags=1, messageId=9, senderId='ignition-ws', targetAddress='' gan-remote-connection-id=http://10.10.65.60:8088/main/system … --> outgoing local='ignition-su-2c8rz12-ws' remote='ignitionws' method=sendControlMsg: opCode=OK, subCode=0, flags=0, messageId=9, senderId='ignition-su-2c8rz12-ws', targetAddress='' gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=Metro-RunDownloadThread: Routing processing time: 4 ms gan-remote-connection-id=http://10.10.65.60:8088/main/system  -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=releaseCapacity: Receive semaphore released, available permits: 100 gan-remote-connection-id=http://10.10.65.60:8088/main/system ... -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=sendControlMsg: opCode=PING, subCode=268034690, flags=1, messageId=79, senderId='ignition-su-2c8rz12-ws', targetAddress='' gan-remote-connection-id=http://10.10.65.60:8088/main/system … -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=reportPing: Ping result: OK. Ping time: 2 ms, session id=268034690,  gan-remote-connection-id=http://10.10.65.60:8088/main/system … -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=scheduleSend: Available permits:10 gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=doSend: Sending message to remote: opCode=MSG_SEND, subCode=0, flags=0, messageId=80, senderId='ignition-su-2c8rz12-ws', targetAddress='' gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignitionws' method=reserveCapacity: Receive semaphore acquired=true, remaining permits: 99 gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignitionws' method=reserveCapacity: Receive semaphore acquired=true, remaining permits: 98 gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=Metro-RunDownloadThread: Downloading message from remote: opCode=MSG_SEND, subCode=0, flags=1, messageId=10, senderId='ignition-ws', targetAddress='' gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=Metro-RunDownloadThread: Downloading message from remote: opCode=MSG_SEND, subCode=0, flags=1, messageId=11, senderId='ignition-ws', targetAddress='' gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=doSend: Received ACK for send message, responseHeader=opCode=OK, subCode=0, flags=0, messageId=80, senderId='ignition-ws', targetAddress='' gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=reportReply: Message send result: OK. Session id=268034690 gan-remote-connection-id=http://10.10.65.60:8088/main/system … -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=sendControlMsg: opCode=OK, subCode=0, flags=0, messageId=10, senderId='ignition-su-2c8rz12-ws', targetAddress='' gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote=ignition-ws' method=Metro-RunDownloadThread: Routing processing time: 1 ms gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote=ignition-ws' method=releaseCapacity: Receive semaphore released, available permits: 99 gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote=ignition-ws' method=sendControlMsg: opCode=OK, subCode=0, flags=0, messageId=11, senderId='ignition-su-2c8rz12-ws', targetAddress='' gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote=ignition-ws' method=Metro-RunDownloadThread: Routing processing time: 0 ms gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote=ignition-ws' method=releaseCapacity: Receive semaphore released, available permits: 100 gan-remote-connection-id=http://10.10.65.60:8088/main/system

• • TRACE log showing additional details for the initialization of the connection from the incoming connection’s perspective:

    <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=storeForDownload: Storing header: [opCode=MSG_SEND, subCode=0, flags=0, messageId=9, senderId='ignition-ws', targetAddress=''] gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=notifyMessageWaiting: Will send download control message, received header:[opCode=MSG_SEND, subCode=0, flags=0, messageId=9, senderId='ignition-ws', targetAddress=''] header to send:[opCode=MSG_SEND, subCode=0, flags=1, messageId=9, senderId='ignition-ws', targetAddress=''] gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=notifyMessageWaiting.writeSuccess: Write to session web socket was successful, received header:[opCode=MSG_SEND, subCode=0, flags=0, messageId=9, senderId='ignition-ws', targetAddress=''] header to send:[opCode=MSG_SEND, subCode=0, flags=1, messageId=9, senderId='ignition-ws', targetAddress=''] gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=reportReply: Message send result: OK. Session id=100 gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=sendControlMsg: opCode=OK, subCode=0, flags=1, messageId=79, senderId='ignition-ws', targetAddress='' gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 … <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=onDataReceived: Received data message:opCode=MSG_SEND, subCode=0, flags=0, messageId=80, senderId='ignition-su-2c8rz12-ws', targetAddress='' gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 <- incoming local='ignitionws' remote='ignition-su-2c8rz12-ws' method=reserveCapacity: Receive semaphore acquired=true, remaining permits: 99 gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=storeForDownload: Storing header: [opCode=MSG_SEND, subCode=0, flags=0, messageId=10, senderId='ignition-ws', targetAddress=''] gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=storeForDownload: Storing header: [opCode=MSG_SEND, subCode=0, flags=0, messageId=11, senderId='ignition-ws', targetAddress=''] gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=notifyMessageWaiting: Will send download control message, received header: [opCode=MSG_SEND, subCode=0, flags=0, messageId=10, senderId='ignition-ws', targetAddress=''] header to send: [opCode=MSG_SEND, subCode=0, flags=1, messageId=10, senderId='ignition-ws', targetAddress=''] gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=notifyMessageWaiting: Will send download control message, received header:[opCode=MSG_SEND, subCode=0, flags=0, messageId=11, senderId='ignition-ws', targetAddress=''] header to send:[opCode=MSG_SEND, subCode=0, flags=1, messageId=11, senderId='ignition-ws', targetAddress=''] gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=notifyMessageWaiting.writeSuccess: Write to session web socket was successful, received header:[opCode=MSG_SEND, subCode=0, flags=0, messageId=10, senderId='ignition-ws', targetAddress=''] header to send:[opCode=MSG_SEND, subCode=0, flags=1, messageId=10, senderId='ignition-ws', targetAddress=''] gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=notifyMessageWaiting.writeSuccess: Write to session web socket was successful, received header:[opCode=MSG_SEND, subCode=0, flags=0, messageId=11, senderId='ignition-ws', targetAddress=''] header to send:[opCode=MSG_SEND, subCode=0, flags=1, messageId=11, senderId='ignition-ws', targetAddress=''] gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 … <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=releaseCapacity: Receive semaphore released, available permits: 100 gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670 … <- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=reportReply: Message send result: OK. Session id=268034690 gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670

• • TRACE log showing additional details for the GAN ensuring an active connection from the outgoing connection’s perspective. This logger will be reported every second or so:

    -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=sendControlMsg: opCode=PING, subCode=268034690, flags=0, messageId=81, senderId='ignition-su-2c8rz12-ws', targetAddress='' gan-remote-connection-id=http://10.10.65.60:8088/main/system -> outgoing local='ignition-su-2c8rz12-ws' remote='ignition-ws' method=reportPing: Ping result: OK. Ping time: 1 ms, session id=268034690,  gan-remote-connection-id=http://10.10.65.60:8088/main/system

• • TRACE log showing additional details for the GAN ensuring an active connection from the incoming connection’s perspective. This logger will be reported every second or so:

    <<- incoming local='ignition-ws' remote='ignition-su-2c8rz12-ws' method=sendControlMsg: opCode=OK, subCode=0, flags=0, messageId=81, senderId='ignition-ws', targetAddress='' gan-remote-connection-id=72779e17-2502-48f7-a3c9-c901364e0670

Back to top