Skip to content

Instantly share code, notes, and snippets.

@mirosval

mirosval/redshift.log

Created January 8, 2018 12:03
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save mirosval/4d34f703bfed3b057d813eb01db44854 to your computer and use it in GitHub Desktop.
Save mirosval/4d34f703bfed3b057d813eb01db44854 to your computer and use it in GitHub Desktop.
Download ZIP
Doobie Redshift Performance Logs
Raw
redshift.log
==> RedshiftJDBC_connection_0.log <==
Jan 08 08:45:07.790 DEBUG 30 com.amazon.jdbc.communications.InboundMessagesThread.run: Select triggered, no keys given.
Jan 08 08:45:08.205 TRACE 29 com.amazon.redshift.client.PGClient.isValid(): +++++ enter +++++
Jan 08 08:45:08.206 TRACE 29 com.amazon.redshift.client.PGClient.isConnected(): +++++ enter +++++
Jan 08 08:45:08.206 TRACE 29 com.amazon.support.channels.AbstractSocketChannel.isClosed(): +++++ enter +++++
Jan 08 08:45:08.206 TRACE 29 com.amazon.support.channels.AbstractSocketChannel.isClosed(): +++++ enter +++++
Jan 08 08:45:08.206 DEBUG 29 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:WRAP:BEFORE:0:writeBuffer.remaining:50:writeBuffer.pos:0:writeBuffer.limit:50
Jan 08 08:45:08.206 DEBUG 29 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:WRAP:AFTER:0:writeBuffer.remaining:0:writeBuffer.pos:50:writeBuffer.limit:50:produced:133:consumed:50
Jan 08 08:45:08.206 DEBUG 29 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.207 DEBUG 29 com.amazon.support.channels.TLSSocketChannel.writeEncryptedBuffer: WriteMessages:OK:133 bytes sent.
Jan 08 08:45:08.207 DEBUG 29 com.amazon.support.channels.TLSSocketChannel.write: Wrote Messages - NOT_HANDSHAKING
Jan 08 08:45:08.207 TRACE 57 com.amazon.jdbc.common.SConnection.prepareStatement("<REDACTED>"): +++++ enter +++++
Jan 08 08:45:08.207 TRACE 57 com.amazon.jdbc.common.SConnection.getAutoCommit(): +++++ enter +++++
Jan 08 08:45:08.207 TRACE 57 com.amazon.dsi.core.impl.DSIConnection.getProperty(19): +++++ enter +++++
Jan 08 08:45:08.207 TRACE 57 com.amazon.redshift.core.PGJDBCConnection.createStatement(): +++++ enter +++++
Jan 08 08:45:08.208 TRACE 57 com.amazon.dsi.core.impl.DSIStatement.setProperty(102, Variant[type: TYPE_UINT32, value: 2147483647]): +++++ enter +++++
Jan 08 08:45:08.208 TRACE 57 com.amazon.dsi.core.impl.DSIStatement.isValueSupported(102, Variant[type: TYPE_UINT32, value: 2147483647]): +++++ enter +++++
Jan 08 08:45:08.208 TRACE 57 com.amazon.dsi.core.impl.DSIStatement.setProperty(103, Variant[type: TYPE_UINT32, value: 0]): +++++ enter +++++
Jan 08 08:45:08.208 TRACE 57 com.amazon.dsi.core.impl.DSIStatement.isValueSupported(103, Variant[type: TYPE_UINT32, value: 0]): +++++ enter +++++
Jan 08 08:45:08.208 TRACE 57 com.amazon.jdbc.common.SStatement.SStatement(com.amazon.redshift.core.PGJDBCStatement@5af84ee9, com.amazon.redshift.core.jdbc42.S42NotifiedConnection@57df1ac6, 1007): +++++ enter +++++
Jan 08 08:45:08.208 TRACE 57 com.amazon.dsi.core.impl.DSIStatement.registerWarningListener(com.amazon.jdbc.common.SWarningListener@543fabc6): +++++ enter +++++
Jan 08 08:45:08.208 TRACE 57 com.amazon.jdbc.common.SPreparedStatement.SPreparedStatement("<REDACTED>", com.amazon.redshift.core.PGJDBCStatement@5af84ee9, com.amazon.redshift.core.jdbc42.S42NotifiedConnection@57df1ac6): +++++ enter +++++
Jan 08 08:45:08.208 TRACE 57 com.amazon.redshift.core.PGJDBCStatement.createDataEngine(): +++++ enter +++++
Jan 08 08:45:08.208 TRACE 57 com.amazon.dsi.dataengine.impl.DSIDataEngine.DSIDataEngine(com.amazon.redshift.core.PGJDBCStatement@5af84ee9): +++++ enter +++++
Jan 08 08:45:08.208 TRACE 57 com.amazon.jdbc.common.SConnection.nativeSQL("<REDACTED>"): +++++ enter +++++
Jan 08 08:45:08.209 TRACE 57 com.amazon.redshift.core.PGJDBCConnection.toNativeSQL("<REDACTED>"): +++++ enter +++++
Jan 08 08:45:08.209 TRACE 57 com.amazon.redshift.dataengine.PGQueryExecutor.PGQueryExecutor("<REDACTED>", com.amazon.redshift.client.PGClient@6f37437c): +++++ enter +++++
Jan 08 08:45:08.209 TRACE 57 com.amazon.redshift.client.PGClient.prepareStatement("<REDACTED>"): +++++ enter +++++
Jan 08 08:45:08.209 TRACE 57 com.amazon.redshift.client.PGClient.isConnected(): +++++ enter +++++
Jan 08 08:45:08.209 TRACE 57 com.amazon.support.channels.AbstractSocketChannel.isClosed(): +++++ enter +++++
Jan 08 08:45:08.209 TRACE 57 com.amazon.support.channels.AbstractSocketChannel.isClosed(): +++++ enter +++++
Jan 08 08:45:08.209 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:WRAP:BEFORE:0:writeBuffer.remaining:204:writeBuffer.pos:0:writeBuffer.limit:204
Jan 08 08:45:08.209 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:WRAP:AFTER:0:writeBuffer.remaining:0:writeBuffer.pos:204:writeBuffer.limit:204:produced:277:consumed:204
Jan 08 08:45:08.209 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.210 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.writeEncryptedBuffer: WriteMessages:OK:277 bytes sent.
Jan 08 08:45:08.210 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: Wrote Messages - NOT_HANDSHAKING
Jan 08 08:45:08.210 ERROR 57 com.amazon.redshift.client.PGClient.prepareStatement: ReadyForQuery not received.
Jan 08 08:45:08.230 DEBUG 57 com.amazon.jdbc.communications.InboundMessagesPipeline.validateCurrentContainer: InboundMessagePipeline waited
Jan 08 08:45:08.242 TRACE 30 com.amazon.support.channels.TLSSocketChannel.read: 101 bytes read from channel Socket[addr=<AWS_REDSHIFT_URL>,port=5439,localport=57340]
at com.amazon.support.LogUtilities.logTrace(Unknown Source)
at com.amazon.support.channels.TLSSocketChannel.read(Unknown Source)
at com.amazon.jdbc.communications.InboundMessagesThread.run(Unknown Source)
Jan 08 08:45:08.242 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:UNWRAP:Consume:101:Produced:20:Position:101
Jan 08 08:45:08.242 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.243 INFO 30 com.amazon.redshift.client.InboundDataHandler.read: Deserializing # of bytes: 20
Jan 08 08:45:08.243 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 4
Jan 08 08:45:08.243 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Message Type: 1
Jan 08 08:45:08.243 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 4
Jan 08 08:45:08.243 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Message Type: 2
Jan 08 08:45:08.243 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 4
Jan 08 08:45:08.243 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Finally:Message Type: n
Jan 08 08:45:08.247 TRACE 30 com.amazon.support.channels.TLSSocketChannel.read: 85 bytes read from channel Socket[addr=<AWS_REDSHIFT_URL>,port=5439,localport=57340]
at com.amazon.support.LogUtilities.logTrace(Unknown Source)
at com.amazon.support.channels.TLSSocketChannel.read(Unknown Source)
at com.amazon.jdbc.communications.InboundMessagesThread.run(Unknown Source)
Jan 08 08:45:08.248 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:UNWRAP:Consume:85:Produced:6:Position:85
Jan 08 08:45:08.248 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.248 INFO 30 com.amazon.redshift.client.InboundDataHandler.read: Deserializing # of bytes: 11
Jan 08 08:45:08.248 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 4
Jan 08 08:45:08.248 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Message Type: I
Jan 08 08:45:08.248 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 5
Jan 08 08:45:08.248 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Ready for query - idle.
Jan 08 08:45:08.248 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Finally:Message Type: Z
Jan 08 08:45:08.251 DEBUG 57 com.amazon.jdbc.communications.InboundMessagesPipeline.validateCurrentContainer: InboundMessagePipeline waited
Jan 08 08:45:08.257 TRACE 30 com.amazon.support.channels.TLSSocketChannel.read: 218 bytes read from channel Socket[addr=<AWS_REDSHIFT_URL>,port=5439,localport=57340]
at com.amazon.support.LogUtilities.logTrace(Unknown Source)
at com.amazon.support.channels.TLSSocketChannel.read(Unknown Source)
at com.amazon.jdbc.communications.InboundMessagesThread.run(Unknown Source)
Jan 08 08:45:08.257 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:UNWRAP:Consume:133:Produced:53:Position:133
Jan 08 08:45:08.257 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.257 INFO 30 com.amazon.redshift.client.InboundDataHandler.read: Deserializing # of bytes: 53
Jan 08 08:45:08.257 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 4
Jan 08 08:45:08.257 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Message Type: 1
Jan 08 08:45:08.257 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 18
Jan 08 08:45:08.258 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Message Type: t
Jan 08 08:45:08.258 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 28
Jan 08 08:45:08.258 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Finally:Message Type: T
Jan 08 08:45:08.258 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:UNWRAP:Consume:85:Produced:6:Position:85
Jan 08 08:45:08.258 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.258 INFO 30 com.amazon.redshift.client.InboundDataHandler.read: Deserializing # of bytes: 6
Jan 08 08:45:08.258 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 5
Jan 08 08:45:08.258 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Ready for query - idle.
Jan 08 08:45:08.258 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Finally:Message Type: Z
Jan 08 08:45:08.258 TRACE 57 com.amazon.dsi.dataengine.impl.DSIDataEngine.close(): +++++ enter +++++
Jan 08 08:45:08.259 TRACE 57 com.amazon.redshift.dataengine.PGQueryExecutor.getMetadataForParameters(): +++++ enter +++++
Jan 08 08:45:08.259 TRACE 57 com.amazon.redshift.dataengine.PGAbstractQueryExecutor.getMetadataForParameters(): +++++ enter +++++
Jan 08 08:45:08.259 TRACE 57 com.amazon.jdbc.common.SPreparedStatement.clearParameters(): +++++ enter +++++
Jan 08 08:45:08.259 TRACE 57 com.amazon.dsi.core.impl.DSIConnection.getProperty(1006): +++++ enter +++++
Jan 08 08:45:08.260 TRACE 57 com.amazon.jdbc.common.SPreparedStatement.setString(1, "<REDACTED_PARAM_1_VALUE>"): +++++ enter +++++
Jan 08 08:45:08.260 TRACE 57 com.amazon.jdbc.common.SPreparedStatement.setDate(2, <REDACTED_PARAM_2_VALUE>): +++++ enter +++++
Jan 08 08:45:08.260 TRACE 57 com.amazon.jdbc.common.SPreparedStatement.setString(3, "<REDACTED_PARAM_3_VALUE>"): +++++ enter +++++
Jan 08 08:45:08.260 TRACE 57 com.amazon.jdbc.common.SPreparedStatement.executeQuery(): +++++ enter +++++
Jan 08 08:45:08.261 TRACE 57 com.amazon.redshift.dataengine.PGAbstractQueryExecutor.clearCancel(): +++++ enter +++++
Jan 08 08:45:08.261 TRACE 57 com.amazon.redshift.dataengine.PGAbstractQueryExecutor.pushMappedParamTypes(): +++++ enter +++++
Jan 08 08:45:08.261 TRACE 57 com.amazon.redshift.client.PGClient.isConnected(): +++++ enter +++++
Jan 08 08:45:08.261 TRACE 57 com.amazon.support.channels.AbstractSocketChannel.isClosed(): +++++ enter +++++
Jan 08 08:45:08.261 TRACE 57 com.amazon.support.channels.AbstractSocketChannel.isClosed(): +++++ enter +++++
Jan 08 08:45:08.261 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:WRAP:BEFORE:0:writeBuffer.remaining:42:writeBuffer.pos:0:writeBuffer.limit:42
Jan 08 08:45:08.261 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:WRAP:AFTER:0:writeBuffer.remaining:0:writeBuffer.pos:42:writeBuffer.limit:42:produced:117:consumed:42
Jan 08 08:45:08.261 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.261 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.writeEncryptedBuffer: WriteMessages:OK:117 bytes sent.
Jan 08 08:45:08.261 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: Wrote Messages - NOT_HANDSHAKING
Jan 08 08:45:08.261 TRACE 57 com.amazon.support.channels.AbstractSocketChannel.isClosed(): +++++ enter +++++
Jan 08 08:45:08.261 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:WRAP:BEFORE:0:writeBuffer.remaining:216:writeBuffer.pos:0:writeBuffer.limit:216
Jan 08 08:45:08.262 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:WRAP:AFTER:0:writeBuffer.remaining:0:writeBuffer.pos:216:writeBuffer.limit:216:produced:293:consumed:216
Jan 08 08:45:08.262 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.262 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.writeEncryptedBuffer: WriteMessages:OK:293 bytes sent.
Jan 08 08:45:08.262 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: Wrote Messages - NOT_HANDSHAKING
Jan 08 08:45:08.282 DEBUG 57 com.amazon.jdbc.communications.InboundMessagesPipeline.validateCurrentContainer: InboundMessagePipeline waited
Jan 08 08:45:08.295 TRACE 30 com.amazon.support.channels.TLSSocketChannel.read: 85 bytes read from channel Socket[addr=<AWS_REDSHIFT_URL>,port=5439,localport=57340]
at com.amazon.support.LogUtilities.logTrace(Unknown Source)
at com.amazon.support.channels.TLSSocketChannel.read(Unknown Source)
at com.amazon.jdbc.communications.InboundMessagesThread.run(Unknown Source)
Jan 08 08:45:08.296 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:UNWRAP:Consume:85:Produced:11:Position:85
Jan 08 08:45:08.296 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.296 INFO 30 com.amazon.redshift.client.InboundDataHandler.read: Deserializing # of bytes: 11
Jan 08 08:45:08.296 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 4
Jan 08 08:45:08.296 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Message Type: 3
Jan 08 08:45:08.296 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 5
Jan 08 08:45:08.296 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Ready for query - idle.
Jan 08 08:45:08.297 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Finally:Message Type: Z
Jan 08 08:45:08.315 TRACE 30 com.amazon.support.channels.TLSSocketChannel.read: 133 bytes read from channel Socket[addr=<AWS_REDSHIFT_URL>,port=5439,localport=57340]
at com.amazon.support.LogUtilities.logTrace(Unknown Source)
at com.amazon.support.channels.TLSSocketChannel.read(Unknown Source)
at com.amazon.jdbc.communications.InboundMessagesThread.run(Unknown Source)
Jan 08 08:45:08.316 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:UNWRAP:Consume:133:Produced:53:Position:133
Jan 08 08:45:08.316 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.316 INFO 30 com.amazon.redshift.client.InboundDataHandler.read: Deserializing # of bytes: 53
Jan 08 08:45:08.316 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 4
Jan 08 08:45:08.316 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Message Type: 1
Jan 08 08:45:08.316 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 18
Jan 08 08:45:08.317 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Message Type: t
Jan 08 08:45:08.317 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 28
Jan 08 08:45:08.317 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Finally:Message Type: T
Jan 08 08:45:08.317 TRACE 30 com.amazon.support.channels.TLSSocketChannel.read: 85 bytes read from channel Socket[addr=<AWS_REDSHIFT_URL>,port=5439,localport=57340]
at com.amazon.support.LogUtilities.logTrace(Unknown Source)
at com.amazon.support.channels.TLSSocketChannel.read(Unknown Source)
at com.amazon.jdbc.communications.InboundMessagesThread.run(Unknown Source)
Jan 08 08:45:08.317 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:UNWRAP:Consume:85:Produced:6:Position:85
Jan 08 08:45:08.317 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.317 INFO 30 com.amazon.redshift.client.InboundDataHandler.read: Deserializing # of bytes: 6
Jan 08 08:45:08.317 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 5
Jan 08 08:45:08.317 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Ready for query - idle.
Jan 08 08:45:08.317 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Finally:Message Type: Z
Jan 08 08:45:08.318 TRACE 57 com.amazon.redshift.dataengine.PGQueryExecutor.getMetadataForParameters(): +++++ enter +++++
Jan 08 08:45:08.318 TRACE 57 com.amazon.redshift.dataengine.PGAbstractQueryExecutor.getMetadataForParameters(): +++++ enter +++++
Jan 08 08:45:08.318 TRACE 57 com.amazon.jdbc.common.SConnection.getAutoCommit(): +++++ enter +++++
Jan 08 08:45:08.318 TRACE 57 com.amazon.dsi.core.impl.DSIConnection.getProperty(19): +++++ enter +++++
Jan 08 08:45:08.318 TRACE 57 com.amazon.redshift.dataengine.PGAbstractQueryExecutor.finalizePushedParamData(): +++++ enter +++++
Jan 08 08:45:08.318 TRACE 57 com.amazon.redshift.dataengine.PGQueryExecutor.execute(com.amazon.dsi.dataengine.utilities.ExecutionContexts@d952ab2, com.amazon.jdbc.common.SWarningListener@543fabc6): +++++ enter +++++
Jan 08 08:45:08.318 TRACE 57 com.amazon.dsi.core.impl.DSIStatement.getProperty(6): +++++ enter +++++
Jan 08 08:45:08.318 TRACE 57 com.amazon.dsi.core.impl.DSIStatement.getProperty(2): +++++ enter +++++
Jan 08 08:45:08.319 TRACE 57 com.amazon.dsi.core.impl.DSIStatement.getProperty(3): +++++ enter +++++
Jan 08 08:45:08.319 TRACE 57 com.amazon.redshift.client.PGClient.executePreparedStatement("<REDACTED>"): +++++ enter +++++
Jan 08 08:45:08.319 TRACE 57 com.amazon.redshift.client.PGClient.isConnected(): +++++ enter +++++
Jan 08 08:45:08.319 TRACE 57 com.amazon.support.channels.AbstractSocketChannel.isClosed(): +++++ enter +++++
Jan 08 08:45:08.319 DEBUG 57 com.amazon.redshift.client.PGClient.getBlockingRowsMode: getBlockingRowsMode(0, false)=0
Jan 08 08:45:08.319 TRACE 57 com.amazon.jdbc.communications.InboundMessagesPipeline.setNRowMode(0): +++++ enter +++++
Jan 08 08:45:08.319 TRACE 57 com.amazon.support.channels.AbstractSocketChannel.isClosed(): +++++ enter +++++
Jan 08 08:45:08.319 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:WRAP:BEFORE:0:writeBuffer.remaining:227:writeBuffer.pos:0:writeBuffer.limit:227
Jan 08 08:45:08.319 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:WRAP:AFTER:0:writeBuffer.remaining:0:writeBuffer.pos:227:writeBuffer.limit:227:produced:309:consumed:227
Jan 08 08:45:08.319 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.320 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.writeEncryptedBuffer: WriteMessages:OK:309 bytes sent.
Jan 08 08:45:08.320 DEBUG 57 com.amazon.support.channels.TLSSocketChannel.write: Wrote Messages - NOT_HANDSHAKING
Jan 08 08:45:08.341 DEBUG 57 com.amazon.jdbc.communications.InboundMessagesPipeline.validateCurrentContainer: InboundMessagePipeline waited
Jan 08 08:45:08.362 DEBUG 57 com.amazon.jdbc.communications.InboundMessagesPipeline.validateCurrentContainer: InboundMessagePipeline waited
Jan 08 08:45:08.379 TRACE 30 com.amazon.support.channels.TLSSocketChannel.read: 149 bytes read from channel Socket[addr=<AWS_REDSHIFT_URL>,port=5439,localport=57340]
at com.amazon.support.LogUtilities.logTrace(Unknown Source)
at com.amazon.support.channels.TLSSocketChannel.read(Unknown Source)
at com.amazon.jdbc.communications.InboundMessagesThread.run(Unknown Source)
Jan 08 08:45:08.379 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:UNWRAP:Consume:149:Produced:71:Position:149
Jan 08 08:45:08.379 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.379 INFO 30 com.amazon.redshift.client.InboundDataHandler.read: Deserializing # of bytes: 71
Jan 08 08:45:08.379 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 4
Jan 08 08:45:08.379 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Message Type: 2
Jan 08 08:45:08.379 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 28
Jan 08 08:45:08.379 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Message Type: T
Jan 08 08:45:08.379 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 24
Jan 08 08:45:08.379 DEBUG 30 com.amazon.redshift.client.messages.inbound.DataRow.read: DataRow read 18 bytes of expected 18
Jan 08 08:45:08.379 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Data row #0, # of columns: 1, complete:true
Jan 08 08:45:08.380 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Message Type: D
Jan 08 08:45:08.380 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 11
Jan 08 08:45:08.380 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Finally:Message Type: C
Jan 08 08:45:08.380 TRACE 57 com.amazon.redshift.dataengine.PGAbstractQueryExecutor.getResults(): +++++ enter +++++
Jan 08 08:45:08.380 TRACE 57 com.amazon.redshift.dataengine.PGResultSet.getSelectColumns(): +++++ enter +++++
Jan 08 08:45:08.380 TRACE 30 com.amazon.support.channels.TLSSocketChannel.read: 85 bytes read from channel Socket[addr=<AWS_REDSHIFT_URL>,port=5439,localport=57340]
at com.amazon.support.LogUtilities.logTrace(Unknown Source)
at com.amazon.support.channels.TLSSocketChannel.read(Unknown Source)
at com.amazon.jdbc.communications.InboundMessagesThread.run(Unknown Source)
Jan 08 08:45:08.380 TRACE 57 com.amazon.jdbc.common.SResultSetMetaData.SResultSetMetaData(<ColumnMetadata: sum>, com.amazon.dsi.core.impl.DSILogger@729fbcb2): +++++ enter +++++
Jan 08 08:45:08.380 TRACE 57 com.amazon.jdbc.common.SStatement.getFetchSize(): +++++ enter +++++
Jan 08 08:45:08.380 TRACE 57 com.amazon.dsi.core.impl.DSIStatement.getProperty(6): +++++ enter +++++
Jan 08 08:45:08.380 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:UNWRAP:Consume:85:Produced:6:Position:85
Jan 08 08:45:08.380 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.380 INFO 30 com.amazon.redshift.client.InboundDataHandler.read: Deserializing # of bytes: 6
Jan 08 08:45:08.380 TRACE 57 com.amazon.jdbc.common.SForwardResultSet.SForwardResultSet(com.amazon.redshift.core.jdbc42.PGJDBC42PreparedStatement@245dd15d, com.amazon.redshift.dataengine.PGResultSet@752004a2, com.amazon.dsi.core.impl.DSILogger@729fbcb2): +++++ enter +++++
Jan 08 08:45:08.380 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 5
Jan 08 08:45:08.380 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Ready for query - idle.
Jan 08 08:45:08.380 TRACE 57 com.amazon.dsi.core.impl.DSIConnection.getProperty(1007): +++++ enter +++++
Jan 08 08:45:08.380 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Finally:Message Type: Z
Jan 08 08:45:08.380 TRACE 57 com.amazon.jdbc.common.SForwardResultSet.setFetchSize(0): +++++ enter +++++
Jan 08 08:45:08.380 TRACE 57 com.amazon.jdbc.jdbc42.S42ForwardResultSet.getMetaData(): +++++ enter +++++
Jan 08 08:45:08.380 TRACE 57 com.amazon.redshift.dataengine.PGResultSet.getSelectColumns(): +++++ enter +++++
Jan 08 08:45:08.380 TRACE 57 com.amazon.jdbc.common.SResultSetMetaData.SResultSetMetaData(<ColumnMetadata: sum>, com.amazon.dsi.core.impl.DSILogger@729fbcb2): +++++ enter +++++
Jan 08 08:45:08.380 TRACE 57 com.amazon.jdbc.common.SResultSetMetaData.getColumnCount(): +++++ enter +++++
Jan 08 08:45:08.381 TRACE 58 com.amazon.jdbc.common.SForwardResultSet.next(): +++++ enter +++++
Jan 08 08:45:08.382 TRACE 58 com.amazon.redshift.dataengine.PGResultSet.moveToNextRow(): +++++ enter +++++
Jan 08 08:45:08.382 TRACE 58 com.amazon.redshift.dataengine.PGResultSet.hasMoreRows(): +++++ enter +++++
Jan 08 08:45:08.382 TRACE 58 com.amazon.redshift.dataengine.PGResultSet.hasMoreRows(): +++++ enter +++++
Jan 08 08:45:08.383 TRACE 58 com.amazon.jdbc.common.SForwardResultSet.getLong(1): +++++ enter +++++
Jan 08 08:45:08.383 TRACE 58 com.amazon.redshift.dataengine.PGResultSet.getData(): +++++ enter +++++
Jan 08 08:45:08.383 TRACE 58 com.amazon.redshift.dataengine.PGResultSet.getSelectColumns(): +++++ enter +++++
Jan 08 08:45:08.383 TRACE 58 com.amazon.jdbc.common.SForwardResultSet.wasNull(): +++++ enter +++++
Jan 08 08:45:08.383 TRACE 58 com.amazon.jdbc.common.SForwardResultSet.next(): +++++ enter +++++
Jan 08 08:45:08.383 TRACE 58 com.amazon.redshift.dataengine.PGResultSet.moveToNextRow(): +++++ enter +++++
Jan 08 08:45:08.383 TRACE 58 com.amazon.redshift.dataengine.PGResultSet.hasMoreRows(): +++++ enter +++++
Jan 08 08:45:08.385 TRACE 57 com.amazon.jdbc.common.SForwardResultSet.close(): +++++ enter +++++
Jan 08 08:45:08.385 TRACE 57 com.amazon.redshift.dataengine.PGResultSet.close(): +++++ enter +++++
Jan 08 08:45:08.387 TRACE 58 com.amazon.jdbc.common.SPreparedStatement.clearBatch(): +++++ enter +++++
Jan 08 08:45:08.388 TRACE 58 com.amazon.redshift.dataengine.PGAbstractQueryExecutor.clearPushedParamData(): +++++ enter +++++
Jan 08 08:45:08.388 TRACE 58 com.amazon.jdbc.common.SStatement.close(): +++++ enter +++++
Jan 08 08:45:08.388 TRACE 58 com.amazon.redshift.dataengine.PGAbstractQueryExecutor.close(): +++++ enter +++++
Jan 08 08:45:08.388 TRACE 58 com.amazon.redshift.core.PGJDBCStatement.close(): +++++ enter +++++
Jan 08 08:45:08.388 TRACE 58 com.amazon.jdbc.communications.InboundMessagesPipeline.close(): +++++ enter +++++
Jan 08 08:45:08.388 TRACE 58 com.amazon.redshift.client.PGClient.closeServerNamedStatement("<REDACTED>"): +++++ enter +++++
Jan 08 08:45:08.388 TRACE 58 com.amazon.redshift.client.PGClient.isConnected(): +++++ enter +++++
Jan 08 08:45:08.388 TRACE 58 com.amazon.support.channels.AbstractSocketChannel.isClosed(): +++++ enter +++++
Jan 08 08:45:08.388 TRACE 58 com.amazon.support.channels.AbstractSocketChannel.isClosed(): +++++ enter +++++
Jan 08 08:45:08.388 DEBUG 58 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:WRAP:BEFORE:0:writeBuffer.remaining:42:writeBuffer.pos:0:writeBuffer.limit:42
Jan 08 08:45:08.388 DEBUG 58 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:WRAP:AFTER:0:writeBuffer.remaining:0:writeBuffer.pos:42:writeBuffer.limit:42:produced:117:consumed:42
Jan 08 08:45:08.388 DEBUG 58 com.amazon.support.channels.TLSSocketChannel.write: WriteMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.388 DEBUG 58 com.amazon.support.channels.TLSSocketChannel.writeEncryptedBuffer: WriteMessages:OK:117 bytes sent.
Jan 08 08:45:08.388 DEBUG 58 com.amazon.support.channels.TLSSocketChannel.write: Wrote Messages - NOT_HANDSHAKING
Jan 08 08:45:08.409 DEBUG 58 com.amazon.jdbc.communications.InboundMessagesPipeline.validateCurrentContainer: InboundMessagePipeline waited
Jan 08 08:45:08.430 DEBUG 58 com.amazon.jdbc.communications.InboundMessagesPipeline.validateCurrentContainer: InboundMessagePipeline waited
Jan 08 08:45:08.443 TRACE 30 com.amazon.support.channels.TLSSocketChannel.read: 85 bytes read from channel Socket[addr=<AWS_REDSHIFT_URL>,port=5439,localport=57340]
at com.amazon.support.LogUtilities.logTrace(Unknown Source)
at com.amazon.support.channels.TLSSocketChannel.read(Unknown Source)
at com.amazon.jdbc.communications.InboundMessagesThread.run(Unknown Source)
Jan 08 08:45:08.444 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:UNWRAP:Consume:85:Produced:11:Position:85
Jan 08 08:45:08.444 DEBUG 30 com.amazon.support.channels.TLSSocketChannel.read: ReadMessages:OK:NOT_HANDSHAKING
Jan 08 08:45:08.444 INFO 30 com.amazon.redshift.client.InboundDataHandler.read: Deserializing # of bytes: 11
Jan 08 08:45:08.444 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 4
Jan 08 08:45:08.444 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Message Type: 3
Jan 08 08:45:08.444 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Message Length: 5
Jan 08 08:45:08.444 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.read: Ready for query - idle.
Jan 08 08:45:08.444 DEBUG 30 com.amazon.redshift.client.InboundDataHandler.logMessageType: Finally:Message Type: Z
Jan 08 08:45:08.445 TRACE 57 com.amazon.jdbc.common.SConnection.clearWarnings(): +++++ enter +++++
Raw
service.log
08:45:08.170 [hikari-pool housekeeper] DEBUG c.zaxxer.hikari.pool.HikariPool - hikari-pool - Pool stats (total=10, active=0, idle=10, waiting=0)
08:45:08.187 [finagle/netty4-2] DEBUG n.c.s.r.h.FinatraHttpController - Request: REDACTED
08:45:08.386 [db-io-pool-57] INFO doobie.util.log$LogHandler$ - Successful Statement Execution:
<REDACTED>
arguments = [<REDACTED>]
elapsed = 120 ms exec + 4 ms processing (125 ms total)
08:45:08.445 [db-io-pool-58] INFO n.c.s.r.database.SQLDatabase - Fetch Unique for Fragment("<REDACTED>") took 241.1546ms
08:45:08.447 [graphql-interpreter-pool-0] INFO n.c.s.r.service.GraphQLMetrics - Query:
<REDACTED> took List(0.0338) ms
<REDACTED> took List(242.4575) ms
<REDACTED> took List(0.0843) ms
<REDACTED> took List(0.0301) ms
08:45:08.447 [finatra-http-controller-pool-1] DEBUG n.c.s.r.h.FinatraHttpController - Result: <REDACTED>
08:45:08.448 [finatra-http-controller-pool-1] INFO n.c.s.r.h.FinatraHttpController - GraphQL Request completed in 260.7179ms
08:45:08.449 [finatra-http-controller-pool-0] INFO c.t.f.h.f.AccessLoggingFilter - 172.17.0.1 - - [08/Jan/2018:08:45:08 +0000] "POST /v1 HTTP/1.1" 200 141 261 "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_13_2) AppleWebKit/537.36 (KHTML, like Gecko) GraphiQL/0.6.0 Chrome/53.0.2785.143 Electron/1.4.13 Safari/537.36"
08:45:38.171 [hikari-pool housekeeper] DEBUG c.zaxxer.hikari.pool.HikariPool - hikari-pool - Pool stats (total=10, active=0, idle=10, waiting=0)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment