search

waku-org / nwaku

Waku node and protocol.
Other
201 stars 53 forks source link

wakunode2: store rpc query attempt to fetch remote node? #461

Closed D4nte closed 3 years ago

D4nte commented 3 years ago

When using JSON RPC get_waku_v2_store_v1_messages on wakunode2 but I am always getting:

{
  jsonrpc: '2.0',
  id: 1,
  error: {
    code: -32000,
    message: 'get_waku_v2_store_v1_messages raised an exception',
    data: 'No history response received'
  }
}

I start nim-waku with --store=true. I tried to both send messages over waku relay to nim waku and use post_waku_v2_relay_v1_message. In neither cases could get a positive response from get_waku_v2_store_v1_messages.

It looks like it tries to contact a remote node, is that normal?

DBG 2021-04-08 14:31:22.291+10:00 get_waku_v2_store_v1_messages              topics="store api" tid=859446 file=store_api.nim:22
DBG 2021-04-08 14:31:22.291+10:00 Using default query                        topics="wakunode" tid=859446 file=wakunode2.nim:277
ERR 2021-04-08 14:31:22.291+10:00 no suitable remote peers                   topics="wakustore" tid=859446 file=waku_store.nim:377
DBG 2021-04-08 14:31:27.294+10:00 Error occurred within RPC                  tid=859446 file=router.nim:82 methodName=get_waku_v2_store_v1_messages err="No history response received"
TRC 2021-04-08 14:31:27.294+10:00 RPC result has been sent                   topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:186 address=127.0.0.1:52246

Full node:

INF 2021-04-08 14:31:17.636+10:00 Initializing networking                    topics="wakunode" tid=859446 file=wakunode2.nim:126 hostAddress=/ip4/127.0.0.1/tcp/63797 announcedAddresses=@[]
TRC 2021-04-08 14:31:17.637+10:00 registering protocols                      topics="libp2p multistream" tid=859446 file=multistream.nim:183 protos="@[\"/ipfs/id/1.0.0\"]"
TRC 2021-04-08 14:31:17.637+10:00 starting switch for peer                   topics="libp2p switch" tid=859446 file=switch.nim:358 peerInfo="(peerId: \"16Uiu2HAmRTKRCgrBnFc53PPjPRTB8kg1xwcryx5daCxK1PcJViuh\", addrs: @[\"/ip4/127.0.0.1/tcp/63797\"], protocols: @[\"/ipfs/id/1.0.0\"], protoVersion: \"\", agentVersion: \"\")"
TRC 2021-04-08 14:31:17.637+10:00 starting transport                         topics="libp2p transport" tid=859446 file=transport.nim:43 address=/ip4/127.0.0.1/tcp/63797
TRC 2021-04-08 14:31:17.637+10:00 Starting TCP transport                     topics="libp2p tcptransport" tid=859446 file=tcptransport.nim:131
TRC 2021-04-08 14:31:17.637+10:00 Listening on                               topics="libp2p tcptransport" tid=859446 file=tcptransport.nim:142 address=/ip4/127.0.0.1/tcp/63797
DBG 2021-04-08 14:31:17.637+10:00 About to accept incoming connection        topics="libp2p switch" tid=859446 file=switch.nim:318
TRC 2021-04-08 14:31:17.637+10:00 Acquired slot                              topics="libp2p semaphore" tid=859446 file=semaphore.nim:36 available=3 queue=0
TRC 2021-04-08 14:31:17.637+10:00 Tracking incoming connection               topics="libp2p connmanager" tid=859446 file=connmanager.nim:422
TRC 2021-04-08 14:31:17.637+10:00 Acquired slot                              topics="libp2p semaphore" tid=859446 file=semaphore.nim:36 available=49 queue=0
DBG 2021-04-08 14:31:17.637+10:00 Started libp2p node                        topics="libp2p switch" tid=859446 file=switch.nim:368 peer="(peerId: \"16Uiu2HAmRTKRCgrBnFc53PPjPRTB8kg1xwcryx5daCxK1PcJViuh\", addrs: @[\"/ip4/127.0.0.1/tcp/63797\"], protocols: @[\"/ipfs/id/1.0.0\"], protoVersion: \"\", agentVersion: \"\")"
INF 2021-04-08 14:31:17.637+10:00 PeerInfo                                   topics="wakunode" tid=859446 file=wakunode2.nim:160 peerId=16U*cJViuh addrs=@[/ip4/127.0.0.1/tcp/63797]
INF 2021-04-08 14:31:17.637+10:00 Listening on                               topics="wakunode" tid=859446 file=wakunode2.nim:163 full=/ip4/127.0.0.1/tcp/63797/p2p/16Uiu2HAmRTKRCgrBnFc53PPjPRTB8kg1xwcryx5daCxK1PcJViuh
INF 2021-04-08 14:31:17.637+10:00 mounting store                             topics="wakunode" tid=859446 file=wakunode2.nim:319
DBG 2021-04-08 14:31:17.637+10:00 mounting store without swap                topics="wakunode" tid=859446 file=wakunode2.nim:322
TRC 2021-04-08 14:31:17.637+10:00 registering protocols                      topics="libp2p multistream" tid=859446 file=multistream.nim:183 protos="@[\"/vac/waku/store/2.0.0-beta1\"]"
DBG 2021-04-08 14:31:17.637+10:00 initWakuRelay                              topics="wakurelay" tid=859446 file=waku_relay.nim:36
DBG 2021-04-08 14:31:17.637+10:00 init                                       topics="wakurelay" tid=859446 file=waku_relay.nim:21
DBG 2021-04-08 14:31:17.637+10:00 init                                       topics="wakurelay" tid=859446 file=waku_relay.nim:21
TRC 2021-04-08 14:31:17.637+10:00 registering protocols                      topics="libp2p multistream" tid=859446 file=multistream.nim:183 protos="@[\"/vac/waku/relay/2.0.0-beta2\", \"/vac/waku/relay/2.0.0-beta2\"]"
DBG 2021-04-08 14:31:17.637+10:00 Reconnecting peers                         topics="wakupeers" tid=859446 file=peer_manager.nim:190 proto=/vac/waku/relay/2.0.0-beta2
INF 2021-04-08 14:31:17.637+10:00 mounting relay                             topics="wakunode" tid=859446 file=wakunode2.nim:396
DBG 2021-04-08 14:31:17.637+10:00 subscribe                                  topics="wakurelay" tid=859446 file=waku_relay.nim:51 pubSubTopic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:17.637+10:00 subscribing to topic                       topics="libp2p pubsub" tid=859446 file=pubsub.nim:427 name=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:17.637+10:00 rebalancing mesh                           tid=859446 file=behavior.nim:251 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:17.637+10:00 replenishing mesh                          tid=859446 file=behavior.nim:260 peers=0 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:17.637+10:00 grafting                                   tid=859446 file=behavior.nim:284 grafting=0 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:17.637+10:00 mesh balanced                              tid=859446 file=behavior.nim:443 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
INF 2021-04-08 14:31:17.637+10:00 subscribe                                  topics="wakunode" tid=859446 file=wakunode2.nim:177 topic=/waku/2/default-waku/proto
DBG 2021-04-08 14:31:17.637+10:00 subscribe                                  topics="wakurelay" tid=859446 file=waku_relay.nim:51 pubSubTopic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:17.637+10:00 rebalancing mesh                           tid=859446 file=behavior.nim:251 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:17.637+10:00 replenishing mesh                          tid=859446 file=behavior.nim:260 peers=0 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:17.637+10:00 grafting                                   tid=859446 file=behavior.nim:284 grafting=0 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:17.637+10:00 mesh balanced                              tid=859446 file=behavior.nim:443 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
INF 2021-04-08 14:31:17.637+10:00 Starting JSON-RPC HTTP server              topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:211 url=http://127.0.0.1:12342
DBG 2021-04-08 14:31:17.637+10:00 Adding API topic handler for existing subscription topics="relay api" tid=859446 file=relay_api.nim:47 topic=/waku/2/default-waku/proto
INF 2021-04-08 14:31:17.637+10:00 subscribe                                  topics="wakunode" tid=859446 file=wakunode2.nim:177 topic=/waku/2/default-waku/proto
DBG 2021-04-08 14:31:17.637+10:00 subscribe                                  topics="wakurelay" tid=859446 file=waku_relay.nim:51 pubSubTopic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:17.637+10:00 rebalancing mesh                           tid=859446 file=behavior.nim:251 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:17.637+10:00 replenishing mesh                          tid=859446 file=behavior.nim:260 peers=0 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:17.637+10:00 grafting                                   tid=859446 file=behavior.nim:284 grafting=0 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:17.637+10:00 mesh balanced                              tid=859446 file=behavior.nim:443 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
DBG 2021-04-08 14:31:17.637+10:00 MessageCache for topic                     topics="relay api" tid=859446 file=relay_api.nim:52 topic=/waku/2/default-waku/proto
DBG 2021-04-08 14:31:17.637+10:00 HTTP RPC server started                    topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:316 address=127.0.0.1:12342
INF 2021-04-08 14:31:17.637+10:00 RPC Server started                         topics="wakunode" tid=859446 file=wakunode2.nim:513 ta=127.0.0.1:12342
DBG 2021-04-08 14:31:18.186+10:00 Received connection                        topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:92 address=127.0.0.1:52238
TRC 2021-04-08 14:31:18.186+10:00 Received valid RPC request                 topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:142 address=127.0.0.1:52238
DBG 2021-04-08 14:31:18.186+10:00 get_waku_v2_debug_v1_info                  topics="debug api" tid=859446 file=debug_api.nim:14
TRC 2021-04-08 14:31:18.186+10:00 RPC result has been sent                   topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:186 address=127.0.0.1:52238
DBG 2021-04-08 14:31:18.186+10:00 Disconnecting client                       topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:201 address=127.0.0.1:52238
DBG 2021-04-08 14:31:18.186+10:00 Finished connection                        topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:205 address=127.0.0.1:52238
TRC 2021-04-08 14:31:18.196+10:00 Handling tcp connection                    topics="libp2p tcptransport" tid=859446 file=tcptransport.nim:74 address=/ip4/127.0.0.1/tcp/33698 dir=In clients=0
TRC 2021-04-08 14:31:18.196+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e8716d9e88b4b3086a2fd objName=ChronosStream dir=In
TRC 2021-04-08 14:31:18.196+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=606e8716d9e88b4b3086a2fd timeout=10m0ns
TRC 2021-04-08 14:31:18.196+10:00 Got connection                             topics="libp2p connmanager" tid=859446 file=connmanager.nim:393 conn=606e8716d9e88b4b3086a2fd
DBG 2021-04-08 14:31:18.196+10:00 Accepted an incoming connection            topics="libp2p switch" tid=859446 file=switch.nim:344 conn=606e8716d9e88b4b3086a2fd
TRC 2021-04-08 14:31:18.196+10:00 Upgrading incoming connection              tid=859446 file=muxedupgrade.nim:98 incomingConn=606e8716d9e88b4b3086a2fd
TRC 2021-04-08 14:31:18.196+10:00 initiating handshake                       topics="libp2p multistream" tid=859446 file=multistream.nim:53 conn=606e8716d9e88b4b3086a2fd codec="\x13/multistream/1.0.0\n"
DBG 2021-04-08 14:31:18.196+10:00 About to accept incoming connection        topics="libp2p switch" tid=859446 file=switch.nim:318
TRC 2021-04-08 14:31:18.196+10:00 Acquired slot                              topics="libp2p semaphore" tid=859446 file=semaphore.nim:36 available=2 queue=0
TRC 2021-04-08 14:31:18.196+10:00 Tracking incoming connection               topics="libp2p connmanager" tid=859446 file=connmanager.nim:422
TRC 2021-04-08 14:31:18.196+10:00 Acquired slot                              topics="libp2p semaphore" tid=859446 file=semaphore.nim:36 available=48 queue=0
TRC 2021-04-08 14:31:18.200+10:00 multistream handshake success              topics="libp2p multistream" tid=859446 file=multistream.nim:67 conn=606e8716d9e88b4b3086a2fd
TRC 2021-04-08 14:31:18.200+10:00 registering proto handler                  topics="libp2p multistream" tid=859446 file=multistream.nim:199 proto=/noise
TRC 2021-04-08 14:31:18.200+10:00 Starting multistream handler               topics="libp2p multistream" tid=859446 file=multistream.nim:123 conn=606e8716d9e88b4b3086a2fd handshaked=true
TRC 2021-04-08 14:31:18.200+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=606e8716d9e88b4b3086a2fd ms=/noise
TRC 2021-04-08 14:31:18.200+10:00 found handler                              topics="libp2p multistream" tid=859446 file=multistream.nim:164 conn=606e8716d9e88b4b3086a2fd protocol=/noise
TRC 2021-04-08 14:31:18.200+10:00 Starting secure handler                    tid=859446 file=muxedupgrade.nim:105 conn=606e8716d9e88b4b3086a2fd
TRC 2021-04-08 14:31:18.200+10:00 Starting Noise handshake                   topics="libp2p noise" tid=859446 file=noise.nim:482 conn=606e8716d9e88b4b3086a2fd initiator=false
TRC 2021-04-08 14:31:18.200+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=f3d15e6108ed...2e0958dc002d
TRC 2021-04-08 14:31:18.209+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=606e8716d9e88b4b3086a2fd size=32
TRC 2021-04-08 14:31:18.209+10:00 noise read e                               topics="libp2p noise" tid=859446 file=noise.nim:260 size=32
TRC 2021-04-08 14:31:18.209+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=7007279d2e08...acbd09d93637
TRC 2021-04-08 14:31:18.209+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=6fc65190c4dd...699567ecc7aa
TRC 2021-04-08 14:31:18.209+10:00 noise write e                              topics="libp2p noise" tid=859446 file=noise.nim:221
TRC 2021-04-08 14:31:18.209+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=a02e42a24b6d...3f5591c746ca
TRC 2021-04-08 14:31:18.209+10:00 noise dh ee                                topics="libp2p noise" tid=859446 file=noise.nim:233
TRC 2021-04-08 14:31:18.209+10:00 mixKey                                     topics="libp2p noise" tid=859446 file=noise.nim:176 key=ec87895b67a8...e8717dd72a94
TRC 2021-04-08 14:31:18.209+10:00 noise write s                              topics="libp2p noise" tid=859446 file=noise.nim:228
TRC 2021-04-08 14:31:18.209+10:00 encryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:144 tag=5d418a280cba14f2b0e906f548010730 data=3440b83f1534...06f548010730 nonce=0
TRC 2021-04-08 14:31:18.209+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=c5c514ae7a9c...84dd5fa9a264
TRC 2021-04-08 14:31:18.209+10:00 noise dh es                                topics="libp2p noise" tid=859446 file=noise.nim:238
TRC 2021-04-08 14:31:18.210+10:00 mixKey                                     topics="libp2p noise" tid=859446 file=noise.nim:176 key=8f4eda6ea352...bdc4885d07ce
TRC 2021-04-08 14:31:18.210+10:00 encryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:144 tag=d7d0decde193bbc77e0f29477537bb25 data=df466dc3f30b...29477537bb25 nonce=0
TRC 2021-04-08 14:31:18.210+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=d8af95ec8737...5c76ae566863
TRC 2021-04-08 14:31:18.210+10:00 writeFrame                                 topics="libp2p noise" tid=859446 file=noise.nim:307 sconn=606e8716d9e88b4b3086a2fd size=208 data=953bb47a06fe...29477537bb25
TRC 2021-04-08 14:31:18.241+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=606e8716d9e88b4b3086a2fd size=627
TRC 2021-04-08 14:31:18.241+10:00 noise read s                               topics="libp2p noise" tid=859446 file=noise.nim:271 size=627
TRC 2021-04-08 14:31:18.241+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=a714ae0bbbde...c6c120f13491 tagOut=a714ae0bbbde...c6c120f13491 nonce=1
TRC 2021-04-08 14:31:18.241+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=4c72e2f0cf92...ca06c77cb628
TRC 2021-04-08 14:31:18.241+10:00 noise dh se                                topics="libp2p noise" tid=859446 file=noise.nim:246
TRC 2021-04-08 14:31:18.241+10:00 mixKey                                     topics="libp2p noise" tid=859446 file=noise.nim:176 key=c7d3329fb458...0c21262180c1
TRC 2021-04-08 14:31:18.241+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=83df7fc89ab2...d05b36422ebf tagOut=83df7fc89ab2...d05b36422ebf nonce=0
TRC 2021-04-08 14:31:18.241+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=796bbb4995c6...99ba2dca9164
TRC 2021-04-08 14:31:18.241+10:00 Remote signature verified                  topics="libp2p noise" tid=859446 file=noise.nim:529 conn=606e8716d9e88b4b3086a2fd
TRC 2021-04-08 14:31:18.241+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e8716d9e88b4b3086a2fe objName=SecureConn dir=In
TRC 2021-04-08 14:31:18.241+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=QmV*FmBdrt:606e8716d9e88b4b3086a2fe timeout=5m0ns
TRC 2021-04-08 14:31:18.241+10:00 Noise handshake completed!                 topics="libp2p noise" tid=859446 file=noise.nim:563 initiator=false peer="(peerId: \"QmVoYf3pWFh1BazyCuagbtT5bfMDF635JA7y3TrxFmBdrt\", addrs: @[], protocols: @[], protoVersion: \"\", agentVersion: \"\")"
TRC 2021-04-08 14:31:18.241+10:00 registering protocols                      topics="libp2p multistream" tid=859446 file=multistream.nim:183 protos="@[\"/mplex/6.7.0\"]"
TRC 2021-04-08 14:31:18.241+10:00 Starting multistream handler               topics="libp2p multistream" tid=859446 file=multistream.nim:123 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe handshaked=false
TRC 2021-04-08 14:31:18.241+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=50
TRC 2021-04-08 14:31:18.241+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=4be4e7867682...e7316e223bdf tagOut=4be4e7867682...e7316e223bdf nonce=0
TRC 2021-04-08 14:31:18.241+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe ms=/multistream/1.0.0
TRC 2021-04-08 14:31:18.241+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe ms=/mplex/6.7.0
TRC 2021-04-08 14:31:18.241+10:00 found handler                              topics="libp2p multistream" tid=859446 file=multistream.nim:164 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe protocol=/mplex/6.7.0
TRC 2021-04-08 14:31:18.241+10:00 starting muxer handler                     topics="libp2p muxer" tid=859446 file=muxer.nim:55 proto=/mplex/6.7.0 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.241+10:00 Starting mplex handler                     topics="libp2p mplex" tid=859446 file=mplex.nim:126 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.241+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.241+10:00 Stored connection                          topics="libp2p connmanager" tid=859446 file=connmanager.nim:379 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe direction=In connections=1
TRC 2021-04-08 14:31:18.241+10:00 Stored muxer                               topics="libp2p connmanager" tid=859446 file=connmanager.nim:483 muxer=QmV*FmBdrt:606e8716d9e88b4b3086a2fe handle=false connections=1
TRC 2021-04-08 14:31:18.241+10:00 Triggering connect events                  topics="libp2p connmanager" tid=859446 file=connmanager.nim:264 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.241+10:00 About to trigger peer events               topics="libp2p connmanager" tid=859446 file=connmanager.nim:156 peer=QmV*FmBdrt
TRC 2021-04-08 14:31:18.241+10:00 triggering peer events                     topics="libp2p connmanager" tid=859446 file=connmanager.nim:169 peerId=QmV*FmBdrt event="(kind: Joined, initiator: false)"
DBG 2021-04-08 14:31:18.241+10:00 created new pubsub peer                    topics="libp2p pubsub" tid=859446 file=pubsub.nim:290 peer=QmV*FmBdrt
TRC 2021-04-08 14:31:18.241+10:00 Dialing (existing)                         topics="libp2p switch" tid=859446 file=switch.nim:225 peerId=QmV*FmBdrt protos="@[\"/vac/waku/relay/2.0.0-beta2\", \"/vac/waku/relay/2.0.0-beta2\"]"
TRC 2021-04-08 14:31:18.241+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e8716d9e88b4b3086a300 objName=LPChannel dir=Out
TRC 2021-04-08 14:31:18.241+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=606e8716d9e88b4b3086a300 timeout=5m0ns
TRC 2021-04-08 14:31:18.241+10:00 BufferStream created                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:53 s=606e8716d9e88b4b3086a300
TRC 2021-04-08 14:31:18.241+10:00 Created new lpchannel                      topics="libp2p mplex" tid=859446 file=lpchannel.nim:226 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 id=1 initiator=true
TRC 2021-04-08 14:31:18.241+10:00 Creating new channel                       topics="libp2p mplex" tid=859446 file=mplex.nim:100 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a300 id=1 initiator=true name=
TRC 2021-04-08 14:31:18.241+10:00 Opening channel                            topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:62 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.241+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=1 msgType=New data=24 encoded=26
TRC 2021-04-08 14:31:18.242+10:00 About to trigger connection events         topics="libp2p connmanager" tid=859446 file=connmanager.nim:122 peer=QmV*FmBdrt
TRC 2021-04-08 14:31:18.242+10:00 triggering connection events               topics="libp2p connmanager" tid=859446 file=connmanager.nim:124 peer=QmV*FmBdrt event=Connected
TRC 2021-04-08 14:31:18.242+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e8716d9e88b4b3086a301 objName=LPChannel dir=Out
TRC 2021-04-08 14:31:18.242+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=606e8716d9e88b4b3086a301 timeout=5m0ns
TRC 2021-04-08 14:31:18.242+10:00 BufferStream created                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:53 s=606e8716d9e88b4b3086a301
TRC 2021-04-08 14:31:18.242+10:00 Created new lpchannel                      topics="libp2p mplex" tid=859446 file=lpchannel.nim:226 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 id=2 initiator=true
TRC 2021-04-08 14:31:18.242+10:00 Creating new channel                       topics="libp2p mplex" tid=859446 file=mplex.nim:100 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a301 id=2 initiator=true name=
TRC 2021-04-08 14:31:18.242+10:00 Opening channel                            topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:62 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.242+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=2 msgType=New data=24 encoded=26
TRC 2021-04-08 14:31:18.242+10:00 Connection upgrade succeeded               topics="libp2p switch" tid=859446 file=switch.nim:300
TRC 2021-04-08 14:31:18.242+10:00 Releasing slot                             topics="libp2p semaphore" tid=859446 file=semaphore.nim:75 available=2 queue=0
TRC 2021-04-08 14:31:18.242+10:00 Released slot                              topics="libp2p semaphore" tid=859446 file=semaphore.nim:85 available=3 queue=0
TRC 2021-04-08 14:31:18.242+10:00 Negotiating stream                         topics="libp2p switch" tid=859446 file=switch.nim:214 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300 protos="@[\"/vac/waku/relay/2.0.0-beta2\", \"/vac/waku/relay/2.0.0-beta2\"]"
TRC 2021-04-08 14:31:18.242+10:00 initiating handshake                       topics="libp2p multistream" tid=859446 file=multistream.nim:53 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300 codec="\x13/multistream/1.0.0\n"
TRC 2021-04-08 14:31:18.242+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=20
TRC 2021-04-08 14:31:18.242+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=1 msgType=MsgOut data=20 encoded=22
TRC 2021-04-08 14:31:18.242+10:00 initiating handshake                       topics="libp2p multistream" tid=859446 file=multistream.nim:53 conn=QmV*FmBdrt:606e8716d9e88b4b3086a301 codec="\x13/multistream/1.0.0\n"
TRC 2021-04-08 14:31:18.242+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=20
TRC 2021-04-08 14:31:18.242+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=2 msgType=MsgOut data=20 encoded=22
TRC 2021-04-08 14:31:18.242+10:00 selecting proto                            topics="libp2p multistream" tid=859446 file=multistream.nim:57 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300 proto=/vac/waku/relay/2.0.0-beta2
TRC 2021-04-08 14:31:18.242+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=29
TRC 2021-04-08 14:31:18.242+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=1 msgType=MsgOut data=29 encoded=31
TRC 2021-04-08 14:31:18.242+10:00 selecting proto                            topics="libp2p multistream" tid=859446 file=multistream.nim:57 conn=QmV*FmBdrt:606e8716d9e88b4b3086a301 proto=/ipfs/id/1.0.0
TRC 2021-04-08 14:31:18.242+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=16
TRC 2021-04-08 14:31:18.242+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=2 msgType=MsgOut data=16 encoded=18
TRC 2021-04-08 14:31:18.245+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=19
TRC 2021-04-08 14:31:18.245+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=046484773a2b...38b01482c7c3 tagOut=046484773a2b...38b01482c7c3 nonce=1
TRC 2021-04-08 14:31:18.245+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=0 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.245+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=1 data=30 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.245+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=30 msgType=New id=0 initiator=false size=1
TRC 2021-04-08 14:31:18.245+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e8716d9e88b4b3086a302 objName=LPChannel dir=In
TRC 2021-04-08 14:31:18.245+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=606e8716d9e88b4b3086a302 timeout=5m0ns
TRC 2021-04-08 14:31:18.245+10:00 BufferStream created                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:53 s=606e8716d9e88b4b3086a302
TRC 2021-04-08 14:31:18.245+10:00 Created new lpchannel                      topics="libp2p mplex" tid=859446 file=lpchannel.nim:226 s=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 id=0 initiator=false
TRC 2021-04-08 14:31:18.245+10:00 Creating new channel                       topics="libp2p mplex" tid=859446 file=mplex.nim:100 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 id=0 initiator=false name=0
TRC 2021-04-08 14:31:18.245+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 data=30 msgType=New id=0 initiator=false size=1
TRC 2021-04-08 14:31:18.245+10:00 created channel                            topics="libp2p mplex" tid=859446 file=mplex.nim:163 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 msgType=New id=0 initiator=false size=1
TRC 2021-04-08 14:31:18.245+10:00 Starting stream handler                    topics="libp2p switch" tid=859446 file=muxedupgrade.nim:197 conn=QmV*FmBdrt:606e8716d9e88b4b3086a302
TRC 2021-04-08 14:31:18.245+10:00 Starting multistream handler               topics="libp2p multistream" tid=859446 file=multistream.nim:123 conn=QmV*FmBdrt:606e8716d9e88b4b3086a302 handshaked=false
TRC 2021-04-08 14:31:18.245+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.287+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=108
TRC 2021-04-08 14:31:18.287+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=61fb5d3570dd...076654a84689 tagOut=61fb5d3570dd...076654a84689 nonce=2
TRC 2021-04-08 14:31:18.287+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=2 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.287+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=36 data=132f6d756c74...312e302e300a conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.287+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=132f6d756c74...312e302e300a msgType=MsgOut id=0 initiator=false size=36
TRC 2021-04-08 14:31:18.287+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 data=132f6d756c74...312e302e300a msgType=MsgOut id=0 initiator=false size=36
TRC 2021-04-08 14:31:18.287+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 len=36 msgType=MsgOut id=0 initiator=false size=36
TRC 2021-04-08 14:31:18.287+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a302 data=36
TRC 2021-04-08 14:31:18.287+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 len=36 msgType=MsgOut id=0 initiator=false size=36
TRC 2021-04-08 14:31:18.287+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.287+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=8 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.287+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=1 data=31 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.287+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=31 msgType=New id=1 initiator=false size=1
TRC 2021-04-08 14:31:18.287+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e8716d9e88b4b3086a303 objName=LPChannel dir=In
TRC 2021-04-08 14:31:18.287+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=606e8716d9e88b4b3086a303 timeout=5m0ns
TRC 2021-04-08 14:31:18.287+10:00 BufferStream created                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:53 s=606e8716d9e88b4b3086a303
TRC 2021-04-08 14:31:18.287+10:00 Created new lpchannel                      topics="libp2p mplex" tid=859446 file=lpchannel.nim:226 s=QmV*FmBdrt:606e8716d9e88b4b3086a303:1 id=1 initiator=false
TRC 2021-04-08 14:31:18.287+10:00 Creating new channel                       topics="libp2p mplex" tid=859446 file=mplex.nim:100 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a303:1 id=1 initiator=false name=1
TRC 2021-04-08 14:31:18.287+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a303:1 data=31 msgType=New id=1 initiator=false size=1
TRC 2021-04-08 14:31:18.287+10:00 created channel                            topics="libp2p mplex" tid=859446 file=mplex.nim:163 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a303:1 msgType=New id=1 initiator=false size=1
TRC 2021-04-08 14:31:18.287+10:00 Starting stream handler                    topics="libp2p switch" tid=859446 file=muxedupgrade.nim:197 conn=QmV*FmBdrt:606e8716d9e88b4b3086a303
TRC 2021-04-08 14:31:18.287+10:00 Starting multistream handler               topics="libp2p multistream" tid=859446 file=multistream.nim:123 conn=QmV*FmBdrt:606e8716d9e88b4b3086a303 handshaked=false
TRC 2021-04-08 14:31:18.287+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.287+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=10 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.287+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=49 data=132f6d756c74...62657461320a conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.287+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=132f6d756c74...62657461320a msgType=MsgOut id=1 initiator=false size=49
TRC 2021-04-08 14:31:18.288+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a303:1 data=132f6d756c74...62657461320a msgType=MsgOut id=1 initiator=false size=49
TRC 2021-04-08 14:31:18.288+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a303:1 len=49 msgType=MsgOut id=1 initiator=false size=49
TRC 2021-04-08 14:31:18.288+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a303 data=49
TRC 2021-04-08 14:31:18.288+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a303:1 len=49 msgType=MsgOut id=1 initiator=false size=49
TRC 2021-04-08 14:31:18.288+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=38
TRC 2021-04-08 14:31:18.288+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=aa0607f48b0d...58458d79f0ca tagOut=aa0607f48b0d...58458d79f0ca nonce=3
TRC 2021-04-08 14:31:18.288+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=9 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=20 data=132f6d756c74...312e302e300a conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=132f6d756c74...312e302e300a msgType=MsgIn id=1 initiator=true size=20
TRC 2021-04-08 14:31:18.288+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a300 data=132f6d756c74...312e302e300a msgType=MsgIn id=1 initiator=true size=20
TRC 2021-04-08 14:31:18.288+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a300 len=20 msgType=MsgIn id=1 initiator=true size=20
TRC 2021-04-08 14:31:18.288+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 data=20
TRC 2021-04-08 14:31:18.288+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a300 len=20 msgType=MsgIn id=1 initiator=true size=20
TRC 2021-04-08 14:31:18.288+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=38
TRC 2021-04-08 14:31:18.288+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=7918b004ee2f...a67a4f5e6965 tagOut=7918b004ee2f...a67a4f5e6965 nonce=4
TRC 2021-04-08 14:31:18.288+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=17 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=20 data=132f6d756c74...312e302e300a conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=132f6d756c74...312e302e300a msgType=MsgIn id=2 initiator=true size=20
TRC 2021-04-08 14:31:18.288+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a301 data=132f6d756c74...312e302e300a msgType=MsgIn id=2 initiator=true size=20
TRC 2021-04-08 14:31:18.288+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a301 len=20 msgType=MsgIn id=2 initiator=true size=20
TRC 2021-04-08 14:31:18.288+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 data=20
TRC 2021-04-08 14:31:18.288+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a301 len=20 msgType=MsgIn id=2 initiator=true size=20
TRC 2021-04-08 14:31:18.288+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=65
TRC 2021-04-08 14:31:18.288+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=e36d7df8227d...a7567774811f tagOut=e36d7df8227d...a7567774811f nonce=5
TRC 2021-04-08 14:31:18.288+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=9 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=29 data=1c2f7661632f...62657461320a conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=1c2f7661632f...62657461320a msgType=MsgIn id=1 initiator=true size=29
TRC 2021-04-08 14:31:18.288+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a300 data=1c2f7661632f...62657461320a msgType=MsgIn id=1 initiator=true size=29
TRC 2021-04-08 14:31:18.288+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a300 len=29 msgType=MsgIn id=1 initiator=true size=29
TRC 2021-04-08 14:31:18.288+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 data=29
TRC 2021-04-08 14:31:18.288+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmV*FmBdrt:606e8716d9e88b4b3086a302 len=35
TRC 2021-04-08 14:31:18.288+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmV*FmBdrt:606e8716d9e88b4b3086a303 len=48
TRC 2021-04-08 14:31:18.288+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 len=19
TRC 2021-04-08 14:31:18.288+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 len=19
TRC 2021-04-08 14:31:18.288+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 bytes=1
TRC 2021-04-08 14:31:18.288+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a303:1 bytes=1
TRC 2021-04-08 14:31:18.288+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 bytes=1
TRC 2021-04-08 14:31:18.288+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 bytes=1
TRC 2021-04-08 14:31:18.288+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a300 len=29 msgType=MsgIn id=1 initiator=true size=29
TRC 2021-04-08 14:31:18.288+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=17 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=16 data=0f2f69706673...312e302e300a conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=0f2f69706673...312e302e300a msgType=MsgIn id=2 initiator=true size=16
TRC 2021-04-08 14:31:18.288+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a301 data=0f2f69706673...312e302e300a msgType=MsgIn id=2 initiator=true size=16
TRC 2021-04-08 14:31:18.288+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a301 len=16 msgType=MsgIn id=2 initiator=true size=16
TRC 2021-04-08 14:31:18.288+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 data=16
TRC 2021-04-08 14:31:18.288+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a301 len=16 msgType=MsgIn id=2 initiator=true size=16
TRC 2021-04-08 14:31:18.288+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=1229
TRC 2021-04-08 14:31:18.288+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=3d662cdea360...f5afb6df1feb tagOut=3d662cdea360...f5afb6df1feb nonce=6
TRC 2021-04-08 14:31:18.288+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=17 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.288+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=1208 data=b6092a0a6970...1af447c27cee conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.289+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=b6092a0a6970...1af447c27cee msgType=MsgIn id=2 initiator=true size=1208
TRC 2021-04-08 14:31:18.289+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a301 data=b6092a0a6970...1af447c27cee msgType=MsgIn id=2 initiator=true size=1208
TRC 2021-04-08 14:31:18.289+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a301 len=1208 msgType=MsgIn id=2 initiator=true size=1208
TRC 2021-04-08 14:31:18.289+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 data=1208
TRC 2021-04-08 14:31:18.289+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 bytes=19
TRC 2021-04-08 14:31:18.289+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a303:1 bytes=19
TRC 2021-04-08 14:31:18.289+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 bytes=19
TRC 2021-04-08 14:31:18.289+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 bytes=19
TRC 2021-04-08 14:31:18.289+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmV*FmBdrt:606e8716d9e88b4b3086a302 ms=/multistream/1.0.0
TRC 2021-04-08 14:31:18.289+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=20
TRC 2021-04-08 14:31:18.289+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=0 msgType=MsgIn data=20 encoded=22
TRC 2021-04-08 14:31:18.289+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmV*FmBdrt:606e8716d9e88b4b3086a303 ms=/multistream/1.0.0
TRC 2021-04-08 14:31:18.289+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a303:1 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=20
TRC 2021-04-08 14:31:18.289+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=1 msgType=MsgIn data=20 encoded=22
TRC 2021-04-08 14:31:18.289+10:00 multistream handshake success              topics="libp2p multistream" tid=859446 file=multistream.nim:67 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300
TRC 2021-04-08 14:31:18.289+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 len=28
TRC 2021-04-08 14:31:18.289+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 bytes=1
TRC 2021-04-08 14:31:18.289+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 bytes=28
TRC 2021-04-08 14:31:18.289+10:00 reading first requested proto              topics="libp2p multistream" tid=859446 file=multistream.nim:74 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300
TRC 2021-04-08 14:31:18.289+10:00 successfully selected                      topics="libp2p multistream" tid=859446 file=multistream.nim:76 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300 proto=/vac/waku/relay/2.0.0-beta2
TRC 2021-04-08 14:31:18.289+10:00 multistream handshake success              topics="libp2p multistream" tid=859446 file=multistream.nim:67 conn=QmV*FmBdrt:606e8716d9e88b4b3086a301
TRC 2021-04-08 14:31:18.289+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 len=15
TRC 2021-04-08 14:31:18.289+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 bytes=1
TRC 2021-04-08 14:31:18.289+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 bytes=15
TRC 2021-04-08 14:31:18.289+10:00 reading first requested proto              topics="libp2p multistream" tid=859446 file=multistream.nim:74 conn=QmV*FmBdrt:606e8716d9e88b4b3086a301
TRC 2021-04-08 14:31:18.289+10:00 successfully selected                      topics="libp2p multistream" tid=859446 file=multistream.nim:76 conn=QmV*FmBdrt:606e8716d9e88b4b3086a301 proto=/ipfs/id/1.0.0
TRC 2021-04-08 14:31:18.289+10:00 initiating identify                        topics="libp2p identify" tid=859446 file=identify.nim:129 conn=QmV*FmBdrt:606e8716d9e88b4b3086a301
TRC 2021-04-08 14:31:18.289+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 len=1207
TRC 2021-04-08 14:31:18.289+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 bytes=1
TRC 2021-04-08 14:31:18.289+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 bytes=1
TRC 2021-04-08 14:31:18.289+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 bytes=1206
DBG 2021-04-08 14:31:18.289+10:00 decodeMsg: decoded message                 topics="libp2p identify" tid=859446 file=identify.nim:94 pubkey="RSA ke...10001\n" addresses="@[/ip4/127.0.0.1/tcp/41869, /ip4/192.168.178.251/tcp/41869, /ip4/10.20.0.9/tcp/41869]" protocols="@[\"/libp2p/circuit/relay/0.1.0\", \"/ipfs/id/1.0.0\", \"/ipfs/id/push/1.0.0\", \"/ipfs/ping/1.0.0\", \"/vac/waku/store/2.0.0-beta1\", \"/vac/waku/relay/2.0.0-beta2\"]" observable_address=Some(/ip4/127.0.0.1/tcp/63797/p2p/16Uiu2HAmRTKRCgrBnFc53PPjPRTB8kg1xwcryx5daCxK1PcJViuh) proto_version="Some(\"ipfs/0.1.0\")" agent_version="Some(\"js-libp2p/0.30.10\")"
TRC 2021-04-08 14:31:18.289+10:00 identified remote peer                     tid=859446 file=upgrade.nim:80 conn=QmV*FmBdrt:606e8716d9e88b4b3086a301 peerInfo="(peerId: \"QmVoYf3pWFh1BazyCuagbtT5bfMDF635JA7y3TrxFmBdrt\", addrs: @[\"/ip4/127.0.0.1/tcp/41869\", \"/ip4/192.168.178.251/tcp/41869\", \"/ip4/10.20.0.9/tcp/41869\"], protocols: @[\"/libp2p/circuit/relay/0.1.0\", \"/ipfs/id/1.0.0\", \"/ipfs/id/push/1.0.0\", \"/ipfs/ping/1.0.0\", \"/vac/waku/store/2.0.0-beta1\", \"/vac/waku/relay/2.0.0-beta2\"], protoVersion: \"ipfs/0.1.0\", agentVersion: \"js-libp2p/0.30.10\")"
TRC 2021-04-08 14:31:18.289+10:00 Get new send connection                    topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:167 p=QmV*FmBdrt newConn=QmV*FmBdrt:606e8716d9e88b4b3086a300
TRC 2021-04-08 14:31:18.289+10:00 sending pubsub message to peer             topics="libp2p pubsub" tid=859446 file=pubsub.nim:126 peer=QmV*FmBdrt msg="(subscriptions: @[(subscribe: true, topic: \"/waku/2/default-waku/proto\")], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:18.289+10:00 sending msg to peer                        topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:237 peer=QmV*FmBdrt rpcMsg="(subscriptions: @[(subscribe: true, topic: \"/waku/2/default-waku/proto\")], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:18.289+10:00 encodeRpcMsg: encoding message             topics="pubsubprotobuf" tid=859446 file=protobuf.nim:308 msg="(subscriptions: @[(subscribe: true, topic: \"/waku/2/default-waku/proto\")], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:18.289+10:00 sending encoded msgs to peer               topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:209 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300 encoded=0a1e0801121a...2f70726f746f
TRC 2021-04-08 14:31:18.290+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=33
TRC 2021-04-08 14:31:18.290+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=1 msgType=MsgOut data=33 encoded=35
DBG 2021-04-08 14:31:18.290+10:00 starting pubsub read loop                  topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:112 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300 peer=QmV*FmBdrt closed=false
TRC 2021-04-08 14:31:18.290+10:00 waiting for data                           topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:117 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300 peer=QmV*FmBdrt closed=false
TRC 2021-04-08 14:31:18.290+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a301 len=1208 msgType=MsgIn id=2 initiator=true size=1208
TRC 2021-04-08 14:31:18.290+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.290+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=19 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.290+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=0 data= conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.290+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data= msgType=CloseIn id=2 initiator=true size=0
TRC 2021-04-08 14:31:18.290+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a301 data= msgType=CloseIn id=2 initiator=true size=0
TRC 2021-04-08 14:31:18.290+10:00 Pushing EOF                                topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:98 s=QmV*FmBdrt:606e8716d9e88b4b3086a301
TRC 2021-04-08 14:31:18.290+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.290+10:00 Closing with EOF                           topics="libp2p lpstream" tid=859446 file=lpstream.nim:289 s=606e8716d9e88b4b3086a301
TRC 2021-04-08 14:31:18.290+10:00 Closing channel                            topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:117 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=0
TRC 2021-04-08 14:31:18.290+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=2 msgType=CloseOut data=0 encoded=2
TRC 2021-04-08 14:31:18.290+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 bytes=1
TRC 2021-04-08 14:31:18.290+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 bytes=15
TRC 2021-04-08 14:31:18.290+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmV*FmBdrt:606e8716d9e88b4b3086a302 ms=/ipfs/id/1.0.0
TRC 2021-04-08 14:31:18.290+10:00 found handler                              topics="libp2p multistream" tid=859446 file=multistream.nim:164 conn=QmV*FmBdrt:606e8716d9e88b4b3086a302 protocol=/ipfs/id/1.0.0
TRC 2021-04-08 14:31:18.290+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=16
TRC 2021-04-08 14:31:18.290+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=0 msgType=MsgIn data=16 encoded=18
TRC 2021-04-08 14:31:18.290+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a303:1 bytes=1
TRC 2021-04-08 14:31:18.290+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a303:1 bytes=28
TRC 2021-04-08 14:31:18.290+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmV*FmBdrt:606e8716d9e88b4b3086a303 ms=/vac/waku/relay/2.0.0-beta2
TRC 2021-04-08 14:31:18.290+10:00 found handler                              topics="libp2p multistream" tid=859446 file=multistream.nim:164 conn=QmV*FmBdrt:606e8716d9e88b4b3086a303 protocol=/vac/waku/relay/2.0.0-beta2
TRC 2021-04-08 14:31:18.290+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a303:1 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=29
TRC 2021-04-08 14:31:18.290+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=1 msgType=MsgIn data=29 encoded=31
TRC 2021-04-08 14:31:18.290+10:00 sent pubsub message to remote              topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:211 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300
TRC 2021-04-08 14:31:18.290+10:00 Closed channel                             topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:133 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 len=0
TRC 2021-04-08 14:31:18.290+10:00 EOF                                        topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:141 s=QmV*FmBdrt:606e8716d9e88b4b3086a301
TRC 2021-04-08 14:31:18.290+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 bytes=0
TRC 2021-04-08 14:31:18.290+10:00 Closing BufferStream                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:169 s=QmV*FmBdrt:606e8716d9e88b4b3086a301 len=0
TRC 2021-04-08 14:31:18.290+10:00 Closed BufferStream                        topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:202 s=QmV*FmBdrt:606e8716d9e88b4b3086a301
TRC 2021-04-08 14:31:18.290+10:00 Closing connection                         topics="libp2p connection" tid=859446 file=connection.nim:84 s=QmV*FmBdrt:606e8716d9e88b4b3086a301
TRC 2021-04-08 14:31:18.290+10:00 Closed connection                          topics="libp2p connection" tid=859446 file=connection.nim:94 s=QmV*FmBdrt:606e8716d9e88b4b3086a301
TRC 2021-04-08 14:31:18.290+10:00 Closing stream                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:257 s=606e8716d9e88b4b3086a301 objName=LPChannel dir=Out
TRC 2021-04-08 14:31:18.290+10:00 Closed stream                              topics="libp2p lpstream" tid=859446 file=lpstream.nim:261 s=606e8716d9e88b4b3086a301 objName=LPChannel dir=Out
TRC 2021-04-08 14:31:18.290+10:00 cleaned up channel                         topics="libp2p mplex" tid=859446 file=mplex.nim:62 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe chann=QmV*FmBdrt:606e8716d9e88b4b3086a301
TRC 2021-04-08 14:31:18.290+10:00 handling identify request                  topics="libp2p identify" tid=859446 file=identify.nim:112 conn=QmV*FmBdrt:606e8716d9e88b4b3086a302
TRC 2021-04-08 14:31:18.291+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=165
TRC 2021-04-08 14:31:18.291+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=0 msgType=MsgIn data=165 encoded=168
DBG 2021-04-08 14:31:18.291+10:00 Incoming WakuRelay connection              topics="wakurelay" tid=859446 file=waku_relay.nim:28
DBG 2021-04-08 14:31:18.291+10:00 starting pubsub read loop                  topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:112 conn=QmV*FmBdrt:606e8716d9e88b4b3086a303 peer=QmV*FmBdrt closed=false
TRC 2021-04-08 14:31:18.291+10:00 waiting for data                           topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:117 conn=QmV*FmBdrt:606e8716d9e88b4b3086a303 peer=QmV*FmBdrt closed=false
TRC 2021-04-08 14:31:18.291+10:00 exiting identify handler                   topics="libp2p identify" tid=859446 file=identify.nim:120 conn=QmV*FmBdrt:606e8716d9e88b4b3086a302
TRC 2021-04-08 14:31:18.291+10:00 Closing with EOF                           topics="libp2p lpstream" tid=859446 file=lpstream.nim:289 s=606e8716d9e88b4b3086a302
TRC 2021-04-08 14:31:18.291+10:00 Closing channel                            topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:117 s=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=0
TRC 2021-04-08 14:31:18.291+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=0 msgType=CloseIn data=0 encoded=2
TRC 2021-04-08 14:31:18.291+10:00 Closed channel                             topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:133 s=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 len=0
TRC 2021-04-08 14:31:18.293+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=18
TRC 2021-04-08 14:31:18.293+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=335e40d3e6d9...95fd62c2c537 tagOut=335e40d3e6d9...95fd62c2c537 nonce=7
TRC 2021-04-08 14:31:18.293+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=4 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.293+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=0 data= conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.293+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data= msgType=CloseOut id=0 initiator=false size=0
TRC 2021-04-08 14:31:18.293+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 data= msgType=CloseOut id=0 initiator=false size=0
TRC 2021-04-08 14:31:18.293+10:00 Pushing EOF                                topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:98 s=QmV*FmBdrt:606e8716d9e88b4b3086a302
TRC 2021-04-08 14:31:18.293+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.293+10:00 EOF                                        topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:141 s=QmV*FmBdrt:606e8716d9e88b4b3086a302
TRC 2021-04-08 14:31:18.293+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a302:0 bytes=0
TRC 2021-04-08 14:31:18.293+10:00 Closing BufferStream                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:169 s=QmV*FmBdrt:606e8716d9e88b4b3086a302 len=0
TRC 2021-04-08 14:31:18.293+10:00 Closed BufferStream                        topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:202 s=QmV*FmBdrt:606e8716d9e88b4b3086a302
TRC 2021-04-08 14:31:18.293+10:00 Closing connection                         topics="libp2p connection" tid=859446 file=connection.nim:84 s=QmV*FmBdrt:606e8716d9e88b4b3086a302
TRC 2021-04-08 14:31:18.293+10:00 Closed connection                          topics="libp2p connection" tid=859446 file=connection.nim:94 s=QmV*FmBdrt:606e8716d9e88b4b3086a302
TRC 2021-04-08 14:31:18.293+10:00 Closing stream                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:257 s=606e8716d9e88b4b3086a302 objName=LPChannel dir=In
TRC 2021-04-08 14:31:18.293+10:00 Closed stream                              topics="libp2p lpstream" tid=859446 file=lpstream.nim:261 s=606e8716d9e88b4b3086a302 objName=LPChannel dir=In
TRC 2021-04-08 14:31:18.293+10:00 cleaned up channel                         topics="libp2p mplex" tid=859446 file=mplex.nim:62 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe chann=QmV*FmBdrt:606e8716d9e88b4b3086a302:0
TRC 2021-04-08 14:31:18.293+10:00 Already closed                             topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:113 s=QmV*FmBdrt:606e8716d9e88b4b3086a302:0
TRC 2021-04-08 14:31:18.293+10:00 Closing with EOF                           topics="libp2p lpstream" tid=859446 file=lpstream.nim:289 s=606e8716d9e88b4b3086a302
TRC 2021-04-08 14:31:18.293+10:00 Already closed                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:291
TRC 2021-04-08 14:31:18.293+10:00 Stream handler done                        topics="libp2p switch" tid=859446 file=muxedupgrade.nim:206 conn=QmV*FmBdrt:606e8716d9e88b4b3086a302
TRC 2021-04-08 14:31:18.294+10:00 finished handling stream                   topics="libp2p mplex" tid=859446 file=mplex.nim:117 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe chann=QmV*FmBdrt:606e8716d9e88b4b3086a302:0
TRC 2021-04-08 14:31:18.295+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=19
TRC 2021-04-08 14:31:18.295+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=161b8543dad8...b73fed5f3e2e tagOut=161b8543dad8...b73fed5f3e2e nonce=8
TRC 2021-04-08 14:31:18.295+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=16 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.295+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=1 data=32 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.295+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=32 msgType=New id=2 initiator=false size=1
TRC 2021-04-08 14:31:18.295+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e8716d9e88b4b3086a304 objName=LPChannel dir=In
TRC 2021-04-08 14:31:18.295+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=606e8716d9e88b4b3086a304 timeout=5m0ns
TRC 2021-04-08 14:31:18.295+10:00 BufferStream created                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:53 s=606e8716d9e88b4b3086a304
TRC 2021-04-08 14:31:18.295+10:00 Created new lpchannel                      topics="libp2p mplex" tid=859446 file=lpchannel.nim:226 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 id=2 initiator=false
TRC 2021-04-08 14:31:18.295+10:00 Creating new channel                       topics="libp2p mplex" tid=859446 file=mplex.nim:100 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 id=2 initiator=false name=2
TRC 2021-04-08 14:31:18.295+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 data=32 msgType=New id=2 initiator=false size=1
TRC 2021-04-08 14:31:18.295+10:00 created channel                            topics="libp2p mplex" tid=859446 file=mplex.nim:163 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 msgType=New id=2 initiator=false size=1
TRC 2021-04-08 14:31:18.295+10:00 Starting stream handler                    topics="libp2p switch" tid=859446 file=muxedupgrade.nim:197 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304
TRC 2021-04-08 14:31:18.295+10:00 Starting multistream handler               topics="libp2p multistream" tid=859446 file=multistream.nim:123 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 handshaked=false
TRC 2021-04-08 14:31:18.295+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.295+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=67
TRC 2021-04-08 14:31:18.295+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=d977cc62152f...f985c1df27f7 tagOut=d977cc62152f...f985c1df27f7 nonce=9
TRC 2021-04-08 14:31:18.295+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=18 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.295+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=49 data=132f6d756c74...62657461320a conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.295+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=132f6d756c74...62657461320a msgType=MsgOut id=2 initiator=false size=49
TRC 2021-04-08 14:31:18.295+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 data=132f6d756c74...62657461320a msgType=MsgOut id=2 initiator=false size=49
TRC 2021-04-08 14:31:18.295+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=49 msgType=MsgOut id=2 initiator=false size=49
TRC 2021-04-08 14:31:18.295+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a304 data=49
TRC 2021-04-08 14:31:18.295+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=49 msgType=MsgOut id=2 initiator=false size=49
TRC 2021-04-08 14:31:18.295+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.295+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmV*FmBdrt:606e8716d9e88b4b3086a304 len=48
TRC 2021-04-08 14:31:18.295+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=1
TRC 2021-04-08 14:31:18.295+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=19
TRC 2021-04-08 14:31:18.295+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 ms=/multistream/1.0.0
TRC 2021-04-08 14:31:18.295+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=20
TRC 2021-04-08 14:31:18.295+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=2 msgType=MsgIn data=20 encoded=22
TRC 2021-04-08 14:31:18.295+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=1
TRC 2021-04-08 14:31:18.295+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=28
TRC 2021-04-08 14:31:18.295+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 ms=/vac/waku/relay/2.0.0-beta2
TRC 2021-04-08 14:31:18.295+10:00 found handler                              topics="libp2p multistream" tid=859446 file=multistream.nim:164 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 protocol=/vac/waku/relay/2.0.0-beta2
TRC 2021-04-08 14:31:18.295+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=29
TRC 2021-04-08 14:31:18.295+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=2 msgType=MsgIn data=29 encoded=31
DBG 2021-04-08 14:31:18.295+10:00 Incoming WakuRelay connection              topics="wakurelay" tid=859446 file=waku_relay.nim:28
DBG 2021-04-08 14:31:18.295+10:00 starting pubsub read loop                  topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:112 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false
TRC 2021-04-08 14:31:18.295+10:00 waiting for data                           topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:117 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false
TRC 2021-04-08 14:31:18.297+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=19
TRC 2021-04-08 14:31:18.297+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=639dc44454a1...55a58cd2dcc5 tagOut=639dc44454a1...55a58cd2dcc5 nonce=10
TRC 2021-04-08 14:31:18.297+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=18 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.297+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=1 data=00 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.297+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=00 msgType=MsgOut id=2 initiator=false size=1
TRC 2021-04-08 14:31:18.297+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 data=00 msgType=MsgOut id=2 initiator=false size=1
TRC 2021-04-08 14:31:18.297+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=1 msgType=MsgOut id=2 initiator=false size=1
TRC 2021-04-08 14:31:18.297+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a304 data=1
TRC 2021-04-08 14:31:18.297+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=1 msgType=MsgOut id=2 initiator=false size=1
TRC 2021-04-08 14:31:18.297+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:18.297+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=1
TRC 2021-04-08 14:31:18.297+10:00 read data from peer                        topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:120 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false data=
TRC 2021-04-08 14:31:18.297+10:00 decodeRpcMsg: decoding message             topics="pubsubprotobuf" tid=859446 file=protobuf.nim:321 msg=
TRC 2021-04-08 14:31:18.297+10:00 decodeMessages: decoding message           topics="pubsubprotobuf" tid=859446 file=protobuf.nim:296
TRC 2021-04-08 14:31:18.297+10:00 decodeMessages: no messages found          topics="pubsubprotobuf" tid=859446 file=protobuf.nim:304
TRC 2021-04-08 14:31:18.297+10:00 decodeSubscriptions: decoding message      topics="pubsubprotobuf" tid=859446 file=protobuf.nim:251
TRC 2021-04-08 14:31:18.297+10:00 decodeControl: decoding message            topics="pubsubprotobuf" tid=859446 file=protobuf.nim:202
TRC 2021-04-08 14:31:18.297+10:00 decoded msg from peer                      topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:131 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false msg="(subscriptions: @[], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:18.297+10:00 processing RPC message                     topics="libp2p pubsub" tid=859446 file=pubsub.nim:210 msg="(subscriptions: @[], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))" peer=QmV*FmBdrt
TRC 2021-04-08 14:31:18.297+10:00 waiting for data                           topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:117 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false
TRC 2021-04-08 14:31:19.286+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=51
TRC 2021-04-08 14:31:19.286+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=b7bc5448f614...413d8a3fc1e0 tagOut=b7bc5448f614...413d8a3fc1e0 nonce=11
TRC 2021-04-08 14:31:19.286+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=18 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:19.286+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=33 data=200a1e080112...2f70726f746f conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:19.286+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=200a1e080112...2f70726f746f msgType=MsgOut id=2 initiator=false size=33
TRC 2021-04-08 14:31:19.287+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 data=200a1e080112...2f70726f746f msgType=MsgOut id=2 initiator=false size=33
TRC 2021-04-08 14:31:19.287+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=33 msgType=MsgOut id=2 initiator=false size=33
TRC 2021-04-08 14:31:19.287+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a304 data=33
TRC 2021-04-08 14:31:19.287+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=33 msgType=MsgOut id=2 initiator=false size=33
TRC 2021-04-08 14:31:19.287+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:19.287+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmV*FmBdrt:606e8716d9e88b4b3086a304 len=32
TRC 2021-04-08 14:31:19.287+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=1
TRC 2021-04-08 14:31:19.287+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=51
TRC 2021-04-08 14:31:19.287+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=32
TRC 2021-04-08 14:31:19.287+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=c95eba13bc51...f9cb6e8b6575 tagOut=c95eba13bc51...f9cb6e8b6575 nonce=12
TRC 2021-04-08 14:31:19.287+10:00 read data from peer                        topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:120 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false data=0a1e0801121a...2f70726f746f
TRC 2021-04-08 14:31:19.287+10:00 decodeRpcMsg: decoding message             topics="pubsubprotobuf" tid=859446 file=protobuf.nim:321 msg=0a1e0801121a...2f70726f746f
TRC 2021-04-08 14:31:19.288+10:00 decodeMessages: decoding message           topics="pubsubprotobuf" tid=859446 file=protobuf.nim:296
TRC 2021-04-08 14:31:19.288+10:00 decodeMessages: no messages found          topics="pubsubprotobuf" tid=859446 file=protobuf.nim:304
TRC 2021-04-08 14:31:19.288+10:00 decodeSubscriptions: decoding message      topics="pubsubprotobuf" tid=859446 file=protobuf.nim:251
TRC 2021-04-08 14:31:19.288+10:00 decodeSubscriptions: read subscriptions    topics="pubsubprotobuf" tid=859446 file=protobuf.nim:256 count=1
TRC 2021-04-08 14:31:19.288+10:00 decodeSubscription: decoding message       topics="pubsubprotobuf" tid=859446 file=protobuf.nim:235
TRC 2021-04-08 14:31:19.288+10:00 decodeSubscription: read subscribe         topics="pubsubprotobuf" tid=859446 file=protobuf.nim:240 subscribe=1
TRC 2021-04-08 14:31:19.288+10:00 decodeSubscription: read topic             topics="pubsubprotobuf" tid=859446 file=protobuf.nim:244 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:19.288+10:00 decodeControl: decoding message            topics="pubsubprotobuf" tid=859446 file=protobuf.nim:202
TRC 2021-04-08 14:31:19.288+10:00 decoded msg from peer                      topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:131 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false msg="(subscriptions: @[(subscribe: true, topic: \"/waku/2/default-waku/proto\")], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:19.288+10:00 processing RPC message                     topics="libp2p pubsub" tid=859446 file=pubsub.nim:210 msg="(subscriptions: @[(subscribe: true, topic: \"/waku/2/default-waku/proto\")], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))" peer=QmV*FmBdrt
TRC 2021-04-08 14:31:19.288+10:00 about to subscribe to topic                topics="libp2p pubsub" tid=859446 file=pubsub.nim:213 topicId=/waku/2/default-waku/proto peer=QmV*FmBdrt subscribe=true
TRC 2021-04-08 14:31:19.288+10:00 peer subscribed to topic                   topics="libp2p gossipsub" tid=859446 file=gossipsub.nim:247 peer=QmV*FmBdrt topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:19.288+10:00 gossip peers                               topics="libp2p gossipsub" tid=859446 file=gossipsub.nim:263 peers=1 topic=/waku/2/default-waku/proto peer=QmV*FmBdrt
TRC 2021-04-08 14:31:19.288+10:00 waiting for data                           topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:117 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false
TRC 2021-04-08 14:31:19.288+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=18 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:19.289+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=33 data=201a1e1a1c0a...2f70726f746f conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:19.289+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=201a1e1a1c0a...2f70726f746f msgType=MsgOut id=2 initiator=false size=33
TRC 2021-04-08 14:31:19.289+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 data=201a1e1a1c0a...2f70726f746f msgType=MsgOut id=2 initiator=false size=33
TRC 2021-04-08 14:31:19.289+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=33 msgType=MsgOut id=2 initiator=false size=33
TRC 2021-04-08 14:31:19.289+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a304 data=33
TRC 2021-04-08 14:31:19.289+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=33 msgType=MsgOut id=2 initiator=false size=33
TRC 2021-04-08 14:31:19.289+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:19.289+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmV*FmBdrt:606e8716d9e88b4b3086a304 len=32
TRC 2021-04-08 14:31:19.289+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=1
TRC 2021-04-08 14:31:19.289+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=32
TRC 2021-04-08 14:31:19.289+10:00 read data from peer                        topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:120 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false data=1a1e1a1c0a1a...2f70726f746f
TRC 2021-04-08 14:31:19.289+10:00 decodeRpcMsg: decoding message             topics="pubsubprotobuf" tid=859446 file=protobuf.nim:321 msg=1a1e1a1c0a1a...2f70726f746f
TRC 2021-04-08 14:31:19.289+10:00 decodeMessages: decoding message           topics="pubsubprotobuf" tid=859446 file=protobuf.nim:296
TRC 2021-04-08 14:31:19.289+10:00 decodeMessages: no messages found          topics="pubsubprotobuf" tid=859446 file=protobuf.nim:304
TRC 2021-04-08 14:31:19.290+10:00 decodeSubscriptions: decoding message      topics="pubsubprotobuf" tid=859446 file=protobuf.nim:251
TRC 2021-04-08 14:31:19.290+10:00 decodeControl: decoding message            topics="pubsubprotobuf" tid=859446 file=protobuf.nim:202
TRC 2021-04-08 14:31:19.290+10:00 decodeGraft: decoding message              topics="pubsubprotobuf" tid=859446 file=protobuf.nim:130
TRC 2021-04-08 14:31:19.290+10:00 decodeGraft: read topicId                  topics="pubsubprotobuf" tid=859446 file=protobuf.nim:133 topic_id=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:19.290+10:00 decodeControl: message statistics          topics="pubsubprotobuf" tid=859446 file=protobuf.nim:223 graft_count=1 prune_count=0 ihave_count=0 iwant_count=0
TRC 2021-04-08 14:31:19.290+10:00 decoded msg from peer                      topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:131 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false msg="(subscriptions: @[], messages: @[], control: (ihave: @[], iwant: @[], graft: @[(topicID: \"/waku/.../proto\")], prune: @[]))"
TRC 2021-04-08 14:31:19.290+10:00 processing RPC message                     topics="libp2p pubsub" tid=859446 file=pubsub.nim:210 msg="(subscriptions: @[], messages: @[], control: (ihave: @[], iwant: @[], graft: @[(topicID: \"/waku/.../proto\")], prune: @[]))" peer=QmV*FmBdrt
TRC 2021-04-08 14:31:19.290+10:00 peer grafted topic                         tid=859446 file=behavior.nim:85 peer=QmV*FmBdrt topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:19.290+10:00 grafted                                    tid=859446 file=behavior.nim:40 peer=QmV*FmBdrt topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:19.290+10:00 waiting for data                           topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:117 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false
TRC 2021-04-08 14:31:20.283+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=71
TRC 2021-04-08 14:31:20.283+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=c1c1c33871a0...5af42fa5c078 tagOut=c1c1c33871a0...5af42fa5c078 nonce=13
TRC 2021-04-08 14:31:20.283+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=18 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:20.283+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=53 data=34123012120a...726f746f1a00 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:20.283+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=34123012120a...726f746f1a00 msgType=MsgOut id=2 initiator=false size=53
TRC 2021-04-08 14:31:20.283+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 data=34123012120a...726f746f1a00 msgType=MsgOut id=2 initiator=false size=53
TRC 2021-04-08 14:31:20.283+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=53 msgType=MsgOut id=2 initiator=false size=53
TRC 2021-04-08 14:31:20.283+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a304 data=53
TRC 2021-04-08 14:31:20.283+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=53 msgType=MsgOut id=2 initiator=false size=53
TRC 2021-04-08 14:31:20.283+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
DBG 2021-04-08 14:31:20.283+10:00 Received connection                        topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:92 address=127.0.0.1:52242
TRC 2021-04-08 14:31:20.283+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmV*FmBdrt:606e8716d9e88b4b3086a304 len=52
TRC 2021-04-08 14:31:20.283+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=1
TRC 2021-04-08 14:31:20.283+10:00 Received valid RPC request                 topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:142 address=127.0.0.1:52242
DBG 2021-04-08 14:31:20.283+10:00 post_waku_v2_relay_v1_message              topics="relay api" tid=859446 file=relay_api.nim:59
DBG 2021-04-08 14:31:20.283+10:00 publish                                    topics="wakunode" tid=859446 file=wakunode2.nim:254 topic=/waku/2/default-waku/proto contentTopic=1
DBG 2021-04-08 14:31:20.283+10:00 publish                                    topics="wakurelay" tid=859446 file=waku_relay.nim:59 pubSubTopic=/waku/2/default-waku/proto message="@[10, 14, 70, 105, 114, 115, 116, 32, 109, 101, 115, 115, 97, 103, 101, 46, 16, 1, 24, 0, 34, 0]"
DBG 2021-04-08 14:31:20.284+10:00 Hit handler                                topics="wakunode" tid=859446 file=wakunode2.nim:408 topic=/waku/2/default-waku/proto data="@[10, 14, 70, 105, 114, 115, 116, 32, 109, 101, 115, 115, 97, 103, 101, 46, 16, 1, 24, 0, 34, 0]"
TRC 2021-04-08 14:31:20.284+10:00 Topic handler triggered                    topics="relay api" tid=859446 file=relay_api.nim:23 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.284+10:00 WakuMessage received                       topics="relay api" tid=859446 file=relay_api.nim:27 msg="Ok((payload: @[70, 105, 114, 115, 116, 32, 109, 101, 115, 115, 97, 103, 101, 46], contentTopic: 1, version: 0, proof: @[]))" topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.284+10:00 Publishing message on topic                topics="libp2p gossipsub" tid=859446 file=gossipsub.nim:485 data=0a0e46697273...100118002200 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.284+10:00 publish: including flood/high score peer   topics="libp2p gossipsub" tid=859446 file=gossipsub.nim:498 peer=QmV*FmBdrt topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.284+10:00 Created new message                        topics="libp2p gossipsub" tid=859446 file=gossipsub.nim:541 msg="(fromPeer: \"\", data: \"0a0e46697273...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")" peers=1 msgId=363039303332...393730383533 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.284+10:00 broadcasting messages to peers             topics="libp2p pubsub" tid=859446 file=pubsub.nim:175 peers=1 msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0e46697273...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.284+10:00 sending pubsub message to peer             topics="libp2p pubsub" tid=859446 file=pubsub.nim:126 peer=QmV*FmBdrt msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0e46697273...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.284+10:00 sending msg to peer                        topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:237 peer=QmV*FmBdrt rpcMsg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0e46697273...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.284+10:00 encodeRpcMsg: encoding message             topics="pubsubprotobuf" tid=859446 file=protobuf.nim:308 msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0e46697273...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.284+10:00 sending encoded msgs to peer               topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:209 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300 encoded=123412160a0e...2f70726f746f
TRC 2021-04-08 14:31:20.284+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=55
TRC 2021-04-08 14:31:20.284+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=1 msgType=MsgOut data=55 encoded=57
TRC 2021-04-08 14:31:20.284+10:00 Published message to peers                 topics="libp2p gossipsub" tid=859446 file=gossipsub.nim:557 msgId=363039303332...393730383533 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.284+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=52
TRC 2021-04-08 14:31:20.284+10:00 read data from peer                        topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:120 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false data=123012120a0c...726f746f1a00
TRC 2021-04-08 14:31:20.284+10:00 decodeRpcMsg: decoding message             topics="pubsubprotobuf" tid=859446 file=protobuf.nim:321 msg=123012120a0c...726f746f1a00
TRC 2021-04-08 14:31:20.284+10:00 decodeMessages: decoding message           topics="pubsubprotobuf" tid=859446 file=protobuf.nim:296
TRC 2021-04-08 14:31:20.284+10:00 decodeMessages: read messages              topics="pubsubprotobuf" tid=859446 file=protobuf.nim:300 count=1
TRC 2021-04-08 14:31:20.284+10:00 decodeMessage: decoding message            topics="pubsubprotobuf" tid=859446 file=protobuf.nim:267
TRC 2021-04-08 14:31:20.284+10:00 decodeMessage: fromPeer is missing         topics="pubsubprotobuf" tid=859446 file=protobuf.nim:272
TRC 2021-04-08 14:31:20.284+10:00 decodeMessage: read data                   topics="pubsubprotobuf" tid=859446 file=protobuf.nim:274 data=0a0c4f6e6520...652e10011800
TRC 2021-04-08 14:31:20.284+10:00 decodeMessage: seqno is missing            topics="pubsubprotobuf" tid=859446 file=protobuf.nim:280
TRC 2021-04-08 14:31:20.284+10:00 decodeMessage: read topics                 topics="pubsubprotobuf" tid=859446 file=protobuf.nim:282 topic_ids="@[\"/waku/2/default-waku/proto\"]"
TRC 2021-04-08 14:31:20.284+10:00 decodeMessage: signature is missing        topics="pubsubprotobuf" tid=859446 file=protobuf.nim:288
TRC 2021-04-08 14:31:20.284+10:00 decodeMessage: public key is missing       topics="pubsubprotobuf" tid=859446 file=protobuf.nim:292
TRC 2021-04-08 14:31:20.284+10:00 decodeSubscriptions: decoding message      topics="pubsubprotobuf" tid=859446 file=protobuf.nim:251
TRC 2021-04-08 14:31:20.284+10:00 decodeControl: decoding message            topics="pubsubprotobuf" tid=859446 file=protobuf.nim:202
TRC 2021-04-08 14:31:20.284+10:00 decodeControl: message statistics          topics="pubsubprotobuf" tid=859446 file=protobuf.nim:223 graft_count=0 prune_count=0 ihave_count=0 iwant_count=0
TRC 2021-04-08 14:31:20.284+10:00 decoded msg from peer                      topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:131 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0c4f6e6520...652e10011800\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.284+10:00 processing RPC message                     topics="libp2p pubsub" tid=859446 file=pubsub.nim:210 msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0c4f6e6520...652e10011800\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))" peer=QmV*FmBdrt
TRC 2021-04-08 14:31:20.284+10:00 about to validate message                  topics="libp2p pubsub" tid=859446 file=pubsub.nim:485
TRC 2021-04-08 14:31:20.284+10:00 looking for validators on topic            topics="libp2p pubsub" tid=859446 file=pubsub.nim:487 topicID=/waku/2/default-waku/proto registered=@[]
DBG 2021-04-08 14:31:20.284+10:00 Hit handler                                topics="wakunode" tid=859446 file=wakunode2.nim:408 topic=/waku/2/default-waku/proto data="@[10, 12, 79, 110, 101, 32, 109, 101, 115, 115, 97, 103, 101, 46, 16, 1, 24, 0]"
TRC 2021-04-08 14:31:20.284+10:00 Topic handler triggered                    topics="relay api" tid=859446 file=relay_api.nim:23 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.284+10:00 WakuMessage received                       topics="relay api" tid=859446 file=relay_api.nim:27 msg="Ok((payload: @[79, 110, 101, 32, 109, 101, 115, 115, 97, 103, 101, 46], contentTopic: 1, version: 0, proof: @[]))" topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.284+10:00 broadcasting messages to peers             topics="libp2p pubsub" tid=859446 file=pubsub.nim:175 peers=1 msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0c4f6e6520...652e10011800\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.284+10:00 sending pubsub message to peer             topics="libp2p pubsub" tid=859446 file=pubsub.nim:126 peer=QmV*FmBdrt msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0c4f6e6520...652e10011800\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.284+10:00 sending msg to peer                        topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:237 peer=QmV*FmBdrt rpcMsg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0c4f6e6520...652e10011800\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.284+10:00 encodeRpcMsg: encoding message             topics="pubsubprotobuf" tid=859446 file=protobuf.nim:308 msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0c4f6e6520...652e10011800\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.284+10:00 sending encoded msgs to peer               topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:209 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300 encoded=123012120a0c...2f70726f746f
TRC 2021-04-08 14:31:20.284+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=51
TRC 2021-04-08 14:31:20.284+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=1 msgType=MsgOut data=51 encoded=53
TRC 2021-04-08 14:31:20.284+10:00 forwared message to peers                  topics="libp2p gossipsub" tid=859446 file=gossipsub.nim:380 peers=1 msgId="@[51, 51, 51, 51, 51, 48, 56, 57, 52, 50, 49, 48, 52, 57, 48, 53, 54, 53, 55, 50, 48, 49, 48, 57, 55, 48, 56, 53, 51]" peer=QmV*FmBdrt
TRC 2021-04-08 14:31:20.284+10:00 waiting for data                           topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:117 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false
TRC 2021-04-08 14:31:20.284+10:00 RPC result has been sent                   topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:186 address=127.0.0.1:52242
DBG 2021-04-08 14:31:20.284+10:00 Disconnecting client                       topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:201 address=127.0.0.1:52242
TRC 2021-04-08 14:31:20.284+10:00 sent pubsub message to remote              topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:211 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300
DBG 2021-04-08 14:31:20.284+10:00 Finished connection                        topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:205 address=127.0.0.1:52242
TRC 2021-04-08 14:31:20.284+10:00 sent pubsub message to remote              topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:211 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300
TRC 2021-04-08 14:31:20.287+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=75
TRC 2021-04-08 14:31:20.287+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=f86432a9e551...e380951fe189 tagOut=f86432a9e551...e380951fe189 nonce=14
TRC 2021-04-08 14:31:20.287+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=18 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:20.287+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=57 data=38123412160a...726f746f1a00 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:20.287+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=38123412160a...726f746f1a00 msgType=MsgOut id=2 initiator=false size=57
TRC 2021-04-08 14:31:20.287+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 data=38123412160a...726f746f1a00 msgType=MsgOut id=2 initiator=false size=57
TRC 2021-04-08 14:31:20.287+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=57 msgType=MsgOut id=2 initiator=false size=57
TRC 2021-04-08 14:31:20.287+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a304 data=57
TRC 2021-04-08 14:31:20.287+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=57 msgType=MsgOut id=2 initiator=false size=57
TRC 2021-04-08 14:31:20.287+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:20.287+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmV*FmBdrt:606e8716d9e88b4b3086a304 len=56
TRC 2021-04-08 14:31:20.287+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=1
TRC 2021-04-08 14:31:20.287+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=56
TRC 2021-04-08 14:31:20.287+10:00 read data from peer                        topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:120 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false data=123412160a0e...726f746f1a00
TRC 2021-04-08 14:31:20.287+10:00 decodeRpcMsg: decoding message             topics="pubsubprotobuf" tid=859446 file=protobuf.nim:321 msg=123412160a0e...726f746f1a00
TRC 2021-04-08 14:31:20.287+10:00 decodeMessages: decoding message           topics="pubsubprotobuf" tid=859446 file=protobuf.nim:296
TRC 2021-04-08 14:31:20.287+10:00 decodeMessages: read messages              topics="pubsubprotobuf" tid=859446 file=protobuf.nim:300 count=1
TRC 2021-04-08 14:31:20.287+10:00 decodeMessage: decoding message            topics="pubsubprotobuf" tid=859446 file=protobuf.nim:267
TRC 2021-04-08 14:31:20.287+10:00 decodeMessage: fromPeer is missing         topics="pubsubprotobuf" tid=859446 file=protobuf.nim:272
TRC 2021-04-08 14:31:20.287+10:00 decodeMessage: read data                   topics="pubsubprotobuf" tid=859446 file=protobuf.nim:274 data=0a0e46697273...100118002200
TRC 2021-04-08 14:31:20.287+10:00 decodeMessage: seqno is missing            topics="pubsubprotobuf" tid=859446 file=protobuf.nim:280
TRC 2021-04-08 14:31:20.287+10:00 decodeMessage: read topics                 topics="pubsubprotobuf" tid=859446 file=protobuf.nim:282 topic_ids="@[\"/waku/2/default-waku/proto\"]"
TRC 2021-04-08 14:31:20.287+10:00 decodeMessage: signature is missing        topics="pubsubprotobuf" tid=859446 file=protobuf.nim:288
TRC 2021-04-08 14:31:20.287+10:00 decodeMessage: public key is missing       topics="pubsubprotobuf" tid=859446 file=protobuf.nim:292
TRC 2021-04-08 14:31:20.287+10:00 decodeSubscriptions: decoding message      topics="pubsubprotobuf" tid=859446 file=protobuf.nim:251
TRC 2021-04-08 14:31:20.287+10:00 decodeControl: decoding message            topics="pubsubprotobuf" tid=859446 file=protobuf.nim:202
TRC 2021-04-08 14:31:20.287+10:00 decodeControl: message statistics          topics="pubsubprotobuf" tid=859446 file=protobuf.nim:223 graft_count=0 prune_count=0 ihave_count=0 iwant_count=0
TRC 2021-04-08 14:31:20.287+10:00 decoded msg from peer                      topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:131 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0e46697273...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.287+10:00 processing RPC message                     topics="libp2p pubsub" tid=859446 file=pubsub.nim:210 msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0e46697273...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))" peer=QmV*FmBdrt
TRC 2021-04-08 14:31:20.287+10:00 Dropping already-seen message              topics="libp2p gossipsub" tid=859446 file=gossipsub.nim:287 msgId=363039303332...393730383533 peer=QmV*FmBdrt
TRC 2021-04-08 14:31:20.287+10:00 waiting for data                           topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:117 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false
DBG 2021-04-08 14:31:20.288+10:00 Received connection                        topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:92 address=127.0.0.1:52244
TRC 2021-04-08 14:31:20.288+10:00 Received valid RPC request                 topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:142 address=127.0.0.1:52244
DBG 2021-04-08 14:31:20.288+10:00 post_waku_v2_relay_v1_message              topics="relay api" tid=859446 file=relay_api.nim:59
DBG 2021-04-08 14:31:20.289+10:00 publish                                    topics="wakunode" tid=859446 file=wakunode2.nim:254 topic=/waku/2/default-waku/proto contentTopic=1
DBG 2021-04-08 14:31:20.289+10:00 publish                                    topics="wakurelay" tid=859446 file=waku_relay.nim:59 pubSubTopic=/waku/2/default-waku/proto message="@[10, 15, 83, 101, 99, 111, 110, 100, 32, 109, 101, 115, 115, 97, 103, 101, 46, 16, 1, 24, 0, 34, 0]"
DBG 2021-04-08 14:31:20.289+10:00 Hit handler                                topics="wakunode" tid=859446 file=wakunode2.nim:408 topic=/waku/2/default-waku/proto data="@[10, 15, 83, 101, 99, 111, 110, 100, 32, 109, 101, 115, 115, 97, 103, 101, 46, 16, 1, 24, 0, 34, 0]"
TRC 2021-04-08 14:31:20.289+10:00 Topic handler triggered                    topics="relay api" tid=859446 file=relay_api.nim:23 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.289+10:00 WakuMessage received                       topics="relay api" tid=859446 file=relay_api.nim:27 msg="Ok((payload: @[83, 101, 99, 111, 110, 100, 32, 109, 101, 115, 115, 97, 103, 101, 46], contentTopic: 1, version: 0, proof: @[]))" topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.289+10:00 Publishing message on topic                topics="libp2p gossipsub" tid=859446 file=gossipsub.nim:485 data=0a0f5365636f...100118002200 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.289+10:00 publish: including flood/high score peer   topics="libp2p gossipsub" tid=859446 file=gossipsub.nim:498 peer=QmV*FmBdrt topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.289+10:00 Created new message                        topics="libp2p gossipsub" tid=859446 file=gossipsub.nim:541 msg="(fromPeer: \"\", data: \"0a0f5365636f...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")" peers=1 msgId=313930343738...393730383533 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.289+10:00 broadcasting messages to peers             topics="libp2p pubsub" tid=859446 file=pubsub.nim:175 peers=1 msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0f5365636f...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.289+10:00 sending pubsub message to peer             topics="libp2p pubsub" tid=859446 file=pubsub.nim:126 peer=QmV*FmBdrt msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0f5365636f...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.289+10:00 sending msg to peer                        topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:237 peer=QmV*FmBdrt rpcMsg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0f5365636f...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.289+10:00 encodeRpcMsg: encoding message             topics="pubsubprotobuf" tid=859446 file=protobuf.nim:308 msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0f5365636f...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.289+10:00 sending encoded msgs to peer               topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:209 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300 encoded=123512170a0f...2f70726f746f
TRC 2021-04-08 14:31:20.289+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmV*FmBdrt:606e8716d9e88b4b3086a300 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe len=56
TRC 2021-04-08 14:31:20.289+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe id=1 msgType=MsgOut data=56 encoded=58
TRC 2021-04-08 14:31:20.289+10:00 Published message to peers                 topics="libp2p gossipsub" tid=859446 file=gossipsub.nim:557 msgId=313930343738...393730383533 topic=/waku/2/default-waku/proto
TRC 2021-04-08 14:31:20.289+10:00 RPC result has been sent                   topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:186 address=127.0.0.1:52244
DBG 2021-04-08 14:31:20.289+10:00 Disconnecting client                       topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:201 address=127.0.0.1:52244
TRC 2021-04-08 14:31:20.289+10:00 sent pubsub message to remote              topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:211 conn=QmV*FmBdrt:606e8716d9e88b4b3086a300
DBG 2021-04-08 14:31:20.289+10:00 Finished connection                        topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:205 address=127.0.0.1:52244
TRC 2021-04-08 14:31:20.290+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmV*FmBdrt:606e8716d9e88b4b3086a2fd size=76
TRC 2021-04-08 14:31:20.290+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=fd699368933d...a01ba38f38f8 tagOut=fd699368933d...a01ba38f38f8 nonce=15
TRC 2021-04-08 14:31:20.290+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=18 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:20.290+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=58 data=39123512170a...726f746f1a00 conn=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:20.290+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe data=39123512170a...726f746f1a00 msgType=MsgOut id=2 initiator=false size=58
TRC 2021-04-08 14:31:20.290+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 data=39123512170a...726f746f1a00 msgType=MsgOut id=2 initiator=false size=58
TRC 2021-04-08 14:31:20.290+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=58 msgType=MsgOut id=2 initiator=false size=58
TRC 2021-04-08 14:31:20.290+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmV*FmBdrt:606e8716d9e88b4b3086a304 data=58
TRC 2021-04-08 14:31:20.290+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe channel=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 len=58 msgType=MsgOut id=2 initiator=false size=58
TRC 2021-04-08 14:31:20.290+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmV*FmBdrt:606e8716d9e88b4b3086a2fe
TRC 2021-04-08 14:31:20.290+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmV*FmBdrt:606e8716d9e88b4b3086a304 len=57
TRC 2021-04-08 14:31:20.290+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=1
TRC 2021-04-08 14:31:20.290+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmV*FmBdrt:606e8716d9e88b4b3086a304:2 bytes=57
TRC 2021-04-08 14:31:20.290+10:00 read data from peer                        topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:120 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false data=123512170a0f...726f746f1a00
TRC 2021-04-08 14:31:20.290+10:00 decodeRpcMsg: decoding message             topics="pubsubprotobuf" tid=859446 file=protobuf.nim:321 msg=123512170a0f...726f746f1a00
TRC 2021-04-08 14:31:20.290+10:00 decodeMessages: decoding message           topics="pubsubprotobuf" tid=859446 file=protobuf.nim:296
TRC 2021-04-08 14:31:20.290+10:00 decodeMessages: read messages              topics="pubsubprotobuf" tid=859446 file=protobuf.nim:300 count=1
TRC 2021-04-08 14:31:20.290+10:00 decodeMessage: decoding message            topics="pubsubprotobuf" tid=859446 file=protobuf.nim:267
TRC 2021-04-08 14:31:20.290+10:00 decodeMessage: fromPeer is missing         topics="pubsubprotobuf" tid=859446 file=protobuf.nim:272
TRC 2021-04-08 14:31:20.290+10:00 decodeMessage: read data                   topics="pubsubprotobuf" tid=859446 file=protobuf.nim:274 data=0a0f5365636f...100118002200
TRC 2021-04-08 14:31:20.290+10:00 decodeMessage: seqno is missing            topics="pubsubprotobuf" tid=859446 file=protobuf.nim:280
TRC 2021-04-08 14:31:20.290+10:00 decodeMessage: read topics                 topics="pubsubprotobuf" tid=859446 file=protobuf.nim:282 topic_ids="@[\"/waku/2/default-waku/proto\"]"
TRC 2021-04-08 14:31:20.290+10:00 decodeMessage: signature is missing        topics="pubsubprotobuf" tid=859446 file=protobuf.nim:288
TRC 2021-04-08 14:31:20.290+10:00 decodeMessage: public key is missing       topics="pubsubprotobuf" tid=859446 file=protobuf.nim:292
TRC 2021-04-08 14:31:20.290+10:00 decodeSubscriptions: decoding message      topics="pubsubprotobuf" tid=859446 file=protobuf.nim:251
TRC 2021-04-08 14:31:20.290+10:00 decodeControl: decoding message            topics="pubsubprotobuf" tid=859446 file=protobuf.nim:202
TRC 2021-04-08 14:31:20.290+10:00 decodeControl: message statistics          topics="pubsubprotobuf" tid=859446 file=protobuf.nim:223 graft_count=0 prune_count=0 ihave_count=0 iwant_count=0
TRC 2021-04-08 14:31:20.290+10:00 decoded msg from peer                      topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:131 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0f5365636f...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:20.290+10:00 processing RPC message                     topics="libp2p pubsub" tid=859446 file=pubsub.nim:210 msg="(subscriptions: @[], messages: @[(fromPeer: \"\", data: \"0a0f5365636f...100118002200\", seqno: \"\", topicIDs: \"@[\\\"/waku/2/default-waku/proto\\\"]\", signature: \"\", key: \"\")], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))" peer=QmV*FmBdrt
TRC 2021-04-08 14:31:20.290+10:00 Dropping already-seen message              topics="libp2p gossipsub" tid=859446 file=gossipsub.nim:287 msgId=313930343738...393730383533 peer=QmV*FmBdrt
TRC 2021-04-08 14:31:20.290+10:00 waiting for data                           topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:117 conn=QmV*FmBdrt:606e8716d9e88b4b3086a304 peer=QmV*FmBdrt closed=false
DBG 2021-04-08 14:31:22.291+10:00 Received connection                        topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:92 address=127.0.0.1:52246
TRC 2021-04-08 14:31:22.291+10:00 Received valid RPC request                 topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:142 address=127.0.0.1:52246
DBG 2021-04-08 14:31:22.291+10:00 get_waku_v2_store_v1_messages              topics="store api" tid=859446 file=store_api.nim:22
DBG 2021-04-08 14:31:22.291+10:00 Using default query                        topics="wakunode" tid=859446 file=wakunode2.nim:277
ERR 2021-04-08 14:31:22.291+10:00 no suitable remote peers                   topics="wakustore" tid=859446 file=waku_store.nim:377
DBG 2021-04-08 14:31:27.294+10:00 Error occurred within RPC                  tid=859446 file=router.nim:82 methodName=get_waku_v2_store_v1_messages err="No history response received"
TRC 2021-04-08 14:31:27.294+10:00 RPC result has been sent                   topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:186 address=127.0.0.1:52246
DBG 2021-04-08 14:31:27.295+10:00 Disconnecting client                       topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:201 address=127.0.0.1:52246
DBG 2021-04-08 14:31:27.295+10:00 Finished connection                        topics="JSONRPC-HTTP-SERVER" tid=859446 file=httpserver.nim:205 address=127.0.0.1:52246
TRC 2021-04-08 14:31:27.351+10:00 Handling tcp connection                    topics="libp2p tcptransport" tid=859446 file=tcptransport.nim:74 address=/ip4/127.0.0.1/tcp/33708 dir=In clients=1
TRC 2021-04-08 14:31:27.351+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e871fd9e88b4b3086a305 objName=ChronosStream dir=In
TRC 2021-04-08 14:31:27.351+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=606e871fd9e88b4b3086a305 timeout=10m0ns
TRC 2021-04-08 14:31:27.351+10:00 Got connection                             topics="libp2p connmanager" tid=859446 file=connmanager.nim:393 conn=606e871fd9e88b4b3086a305
DBG 2021-04-08 14:31:27.351+10:00 Accepted an incoming connection            topics="libp2p switch" tid=859446 file=switch.nim:344 conn=606e871fd9e88b4b3086a305
TRC 2021-04-08 14:31:27.351+10:00 Upgrading incoming connection              tid=859446 file=muxedupgrade.nim:98 incomingConn=606e871fd9e88b4b3086a305
TRC 2021-04-08 14:31:27.351+10:00 initiating handshake                       topics="libp2p multistream" tid=859446 file=multistream.nim:53 conn=606e871fd9e88b4b3086a305 codec="\x13/multistream/1.0.0\n"
DBG 2021-04-08 14:31:27.351+10:00 About to accept incoming connection        topics="libp2p switch" tid=859446 file=switch.nim:318
TRC 2021-04-08 14:31:27.351+10:00 Acquired slot                              topics="libp2p semaphore" tid=859446 file=semaphore.nim:36 available=2 queue=0
TRC 2021-04-08 14:31:27.351+10:00 Tracking incoming connection               topics="libp2p connmanager" tid=859446 file=connmanager.nim:422
TRC 2021-04-08 14:31:27.351+10:00 Acquired slot                              topics="libp2p semaphore" tid=859446 file=semaphore.nim:36 available=47 queue=0
TRC 2021-04-08 14:31:27.352+10:00 multistream handshake success              topics="libp2p multistream" tid=859446 file=multistream.nim:67 conn=606e871fd9e88b4b3086a305
TRC 2021-04-08 14:31:27.352+10:00 registering proto handler                  topics="libp2p multistream" tid=859446 file=multistream.nim:199 proto=/noise
TRC 2021-04-08 14:31:27.352+10:00 Starting multistream handler               topics="libp2p multistream" tid=859446 file=multistream.nim:123 conn=606e871fd9e88b4b3086a305 handshaked=true
TRC 2021-04-08 14:31:27.352+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=606e871fd9e88b4b3086a305 ms=/noise
TRC 2021-04-08 14:31:27.352+10:00 found handler                              topics="libp2p multistream" tid=859446 file=multistream.nim:164 conn=606e871fd9e88b4b3086a305 protocol=/noise
TRC 2021-04-08 14:31:27.352+10:00 Starting secure handler                    tid=859446 file=muxedupgrade.nim:105 conn=606e871fd9e88b4b3086a305
TRC 2021-04-08 14:31:27.352+10:00 Starting Noise handshake                   topics="libp2p noise" tid=859446 file=noise.nim:482 conn=606e871fd9e88b4b3086a305 initiator=false
TRC 2021-04-08 14:31:27.352+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=f3d15e6108ed...2e0958dc002d
TRC 2021-04-08 14:31:27.357+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=606e871fd9e88b4b3086a305 size=32
TRC 2021-04-08 14:31:27.357+10:00 noise read e                               topics="libp2p noise" tid=859446 file=noise.nim:260 size=32
TRC 2021-04-08 14:31:27.357+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=0f846bee5915...bd6c0ca177e4
TRC 2021-04-08 14:31:27.357+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=c6b3a01d72dd...3f116651fca5
TRC 2021-04-08 14:31:27.357+10:00 noise write e                              topics="libp2p noise" tid=859446 file=noise.nim:221
TRC 2021-04-08 14:31:27.357+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=3792e6746ce6...bf1a3ccadfea
TRC 2021-04-08 14:31:27.357+10:00 noise dh ee                                topics="libp2p noise" tid=859446 file=noise.nim:233
TRC 2021-04-08 14:31:27.357+10:00 mixKey                                     topics="libp2p noise" tid=859446 file=noise.nim:176 key=6b2149231ff3...639ffb945168
TRC 2021-04-08 14:31:27.357+10:00 noise write s                              topics="libp2p noise" tid=859446 file=noise.nim:228
TRC 2021-04-08 14:31:27.357+10:00 encryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:144 tag=7b1d46be917b593aac7768f51f474df7 data=9d47bfaf3f65...68f51f474df7 nonce=0
TRC 2021-04-08 14:31:27.357+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=9d94c10a531d...ce14a59546cd
TRC 2021-04-08 14:31:27.357+10:00 noise dh es                                topics="libp2p noise" tid=859446 file=noise.nim:238
TRC 2021-04-08 14:31:27.357+10:00 mixKey                                     topics="libp2p noise" tid=859446 file=noise.nim:176 key=dcf5a678cb19...974fe5006618
TRC 2021-04-08 14:31:27.357+10:00 encryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:144 tag=b39026ad474b55d48b3af3b9db3cb2f9 data=475eb0ea4306...f3b9db3cb2f9 nonce=0
TRC 2021-04-08 14:31:27.357+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=ba0804aa6bb3...0c82d797d69f
TRC 2021-04-08 14:31:27.357+10:00 writeFrame                                 topics="libp2p noise" tid=859446 file=noise.nim:307 sconn=606e871fd9e88b4b3086a305 size=208 data=0c1ff18441e6...f3b9db3cb2f9
TRC 2021-04-08 14:31:27.374+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=606e871fd9e88b4b3086a305 size=627
TRC 2021-04-08 14:31:27.374+10:00 noise read s                               topics="libp2p noise" tid=859446 file=noise.nim:271 size=627
TRC 2021-04-08 14:31:27.374+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=6d8da59c2142...4df967108ee2 tagOut=6d8da59c2142...4df967108ee2 nonce=1
TRC 2021-04-08 14:31:27.374+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=d9849d2fbeea...92646b418162
TRC 2021-04-08 14:31:27.374+10:00 noise dh se                                topics="libp2p noise" tid=859446 file=noise.nim:246
TRC 2021-04-08 14:31:27.374+10:00 mixKey                                     topics="libp2p noise" tid=859446 file=noise.nim:176 key=370c8e30e7bb...13ea7f32f9da
TRC 2021-04-08 14:31:27.374+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=0c74b27976ea...8c5af6633503 tagOut=0c74b27976ea...8c5af6633503 nonce=0
TRC 2021-04-08 14:31:27.374+10:00 mixHash                                    topics="libp2p noise" tid=859446 file=noise.nim:184 hash=b9daba51e162...9c7367ab9b4d
TRC 2021-04-08 14:31:27.375+10:00 Remote signature verified                  topics="libp2p noise" tid=859446 file=noise.nim:529 conn=606e871fd9e88b4b3086a305
TRC 2021-04-08 14:31:27.375+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e871fd9e88b4b3086a306 objName=SecureConn dir=In
TRC 2021-04-08 14:31:27.375+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=QmQ*GPX9kC:606e871fd9e88b4b3086a306 timeout=5m0ns
TRC 2021-04-08 14:31:27.375+10:00 Noise handshake completed!                 topics="libp2p noise" tid=859446 file=noise.nim:563 initiator=false peer="(peerId: \"QmQTHyAqRdZaarDhn9gCWMfEJxV7PHjnNECBLiRCGPX9kC\", addrs: @[], protocols: @[], protoVersion: \"\", agentVersion: \"\")"
TRC 2021-04-08 14:31:27.375+10:00 registering protocols                      topics="libp2p multistream" tid=859446 file=multistream.nim:183 protos="@[\"/mplex/6.7.0\"]"
TRC 2021-04-08 14:31:27.375+10:00 Starting multistream handler               topics="libp2p multistream" tid=859446 file=multistream.nim:123 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 handshaked=false
TRC 2021-04-08 14:31:27.375+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=50
TRC 2021-04-08 14:31:27.375+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=4f4515cbd155...629a44b8188f tagOut=4f4515cbd155...629a44b8188f nonce=0
TRC 2021-04-08 14:31:27.375+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 ms=/multistream/1.0.0
TRC 2021-04-08 14:31:27.375+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 ms=/mplex/6.7.0
TRC 2021-04-08 14:31:27.375+10:00 found handler                              topics="libp2p multistream" tid=859446 file=multistream.nim:164 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 protocol=/mplex/6.7.0
TRC 2021-04-08 14:31:27.375+10:00 starting muxer handler                     topics="libp2p muxer" tid=859446 file=muxer.nim:55 proto=/mplex/6.7.0 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.375+10:00 Starting mplex handler                     topics="libp2p mplex" tid=859446 file=mplex.nim:126 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.375+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.375+10:00 Stored connection                          topics="libp2p connmanager" tid=859446 file=connmanager.nim:379 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 direction=In connections=2
TRC 2021-04-08 14:31:27.375+10:00 Stored muxer                               topics="libp2p connmanager" tid=859446 file=connmanager.nim:483 muxer=QmQ*GPX9kC:606e871fd9e88b4b3086a306 handle=false connections=2
TRC 2021-04-08 14:31:27.375+10:00 Triggering connect events                  topics="libp2p connmanager" tid=859446 file=connmanager.nim:264 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.375+10:00 About to trigger peer events               topics="libp2p connmanager" tid=859446 file=connmanager.nim:156 peer=QmQ*GPX9kC
TRC 2021-04-08 14:31:27.375+10:00 triggering peer events                     topics="libp2p connmanager" tid=859446 file=connmanager.nim:169 peerId=QmQ*GPX9kC event="(kind: Joined, initiator: false)"
DBG 2021-04-08 14:31:27.375+10:00 created new pubsub peer                    topics="libp2p pubsub" tid=859446 file=pubsub.nim:290 peer=QmQ*GPX9kC
TRC 2021-04-08 14:31:27.375+10:00 Dialing (existing)                         topics="libp2p switch" tid=859446 file=switch.nim:225 peerId=QmQ*GPX9kC protos="@[\"/vac/waku/relay/2.0.0-beta2\", \"/vac/waku/relay/2.0.0-beta2\"]"
TRC 2021-04-08 14:31:27.375+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e871fd9e88b4b3086a308 objName=LPChannel dir=Out
TRC 2021-04-08 14:31:27.375+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=606e871fd9e88b4b3086a308 timeout=5m0ns
TRC 2021-04-08 14:31:27.375+10:00 BufferStream created                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:53 s=606e871fd9e88b4b3086a308
TRC 2021-04-08 14:31:27.375+10:00 Created new lpchannel                      topics="libp2p mplex" tid=859446 file=lpchannel.nim:226 s=QmQ*GPX9kC:606e871fd9e88b4b3086a308 id=1 initiator=true
TRC 2021-04-08 14:31:27.375+10:00 Creating new channel                       topics="libp2p mplex" tid=859446 file=mplex.nim:100 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a308 id=1 initiator=true name=
TRC 2021-04-08 14:31:27.375+10:00 Opening channel                            topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:62 s=QmQ*GPX9kC:606e871fd9e88b4b3086a308 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.375+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=1 msgType=New data=24 encoded=26
TRC 2021-04-08 14:31:27.375+10:00 About to trigger connection events         topics="libp2p connmanager" tid=859446 file=connmanager.nim:122 peer=QmQ*GPX9kC
TRC 2021-04-08 14:31:27.375+10:00 triggering connection events               topics="libp2p connmanager" tid=859446 file=connmanager.nim:124 peer=QmQ*GPX9kC event=Connected
TRC 2021-04-08 14:31:27.375+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e871fd9e88b4b3086a309 objName=LPChannel dir=Out
TRC 2021-04-08 14:31:27.375+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=606e871fd9e88b4b3086a309 timeout=5m0ns
TRC 2021-04-08 14:31:27.375+10:00 BufferStream created                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:53 s=606e871fd9e88b4b3086a309
TRC 2021-04-08 14:31:27.375+10:00 Created new lpchannel                      topics="libp2p mplex" tid=859446 file=lpchannel.nim:226 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 id=2 initiator=true
TRC 2021-04-08 14:31:27.375+10:00 Creating new channel                       topics="libp2p mplex" tid=859446 file=mplex.nim:100 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a309 id=2 initiator=true name=
TRC 2021-04-08 14:31:27.375+10:00 Opening channel                            topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:62 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.375+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=2 msgType=New data=24 encoded=26
TRC 2021-04-08 14:31:27.375+10:00 Connection upgrade succeeded               topics="libp2p switch" tid=859446 file=switch.nim:300
TRC 2021-04-08 14:31:27.375+10:00 Releasing slot                             topics="libp2p semaphore" tid=859446 file=semaphore.nim:75 available=2 queue=0
TRC 2021-04-08 14:31:27.375+10:00 Released slot                              topics="libp2p semaphore" tid=859446 file=semaphore.nim:85 available=3 queue=0
TRC 2021-04-08 14:31:27.375+10:00 Negotiating stream                         topics="libp2p switch" tid=859446 file=switch.nim:214 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a308 protos="@[\"/vac/waku/relay/2.0.0-beta2\", \"/vac/waku/relay/2.0.0-beta2\"]"
TRC 2021-04-08 14:31:27.375+10:00 initiating handshake                       topics="libp2p multistream" tid=859446 file=multistream.nim:53 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a308 codec="\x13/multistream/1.0.0\n"
TRC 2021-04-08 14:31:27.375+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e871fd9e88b4b3086a308 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=20
TRC 2021-04-08 14:31:27.375+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=1 msgType=MsgOut data=20 encoded=22
TRC 2021-04-08 14:31:27.375+10:00 initiating handshake                       topics="libp2p multistream" tid=859446 file=multistream.nim:53 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a309 codec="\x13/multistream/1.0.0\n"
TRC 2021-04-08 14:31:27.375+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=20
TRC 2021-04-08 14:31:27.375+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=2 msgType=MsgOut data=20 encoded=22
TRC 2021-04-08 14:31:27.375+10:00 selecting proto                            topics="libp2p multistream" tid=859446 file=multistream.nim:57 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a308 proto=/vac/waku/relay/2.0.0-beta2
TRC 2021-04-08 14:31:27.375+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e871fd9e88b4b3086a308 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=29
TRC 2021-04-08 14:31:27.375+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=1 msgType=MsgOut data=29 encoded=31
TRC 2021-04-08 14:31:27.375+10:00 selecting proto                            topics="libp2p multistream" tid=859446 file=multistream.nim:57 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a309 proto=/ipfs/id/1.0.0
TRC 2021-04-08 14:31:27.376+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=16
TRC 2021-04-08 14:31:27.376+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=2 msgType=MsgOut data=16 encoded=18
TRC 2021-04-08 14:31:27.376+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=19
TRC 2021-04-08 14:31:27.376+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=a6877f0a5ea9...0a6676786d91 tagOut=a6877f0a5ea9...0a6676786d91 nonce=1
TRC 2021-04-08 14:31:27.376+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=0 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.376+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=1 data=30 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.376+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=30 msgType=New id=0 initiator=false size=1
TRC 2021-04-08 14:31:27.376+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e871fd9e88b4b3086a30a objName=LPChannel dir=In
TRC 2021-04-08 14:31:27.376+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=606e871fd9e88b4b3086a30a timeout=5m0ns
TRC 2021-04-08 14:31:27.376+10:00 BufferStream created                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:53 s=606e871fd9e88b4b3086a30a
TRC 2021-04-08 14:31:27.376+10:00 Created new lpchannel                      topics="libp2p mplex" tid=859446 file=lpchannel.nim:226 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 id=0 initiator=false
TRC 2021-04-08 14:31:27.376+10:00 Creating new channel                       topics="libp2p mplex" tid=859446 file=mplex.nim:100 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 id=0 initiator=false name=0
TRC 2021-04-08 14:31:27.376+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 data=30 msgType=New id=0 initiator=false size=1
TRC 2021-04-08 14:31:27.376+10:00 created channel                            topics="libp2p mplex" tid=859446 file=mplex.nim:163 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 msgType=New id=0 initiator=false size=1
TRC 2021-04-08 14:31:27.376+10:00 Starting stream handler                    topics="libp2p switch" tid=859446 file=muxedupgrade.nim:197 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30a
TRC 2021-04-08 14:31:27.376+10:00 Starting multistream handler               topics="libp2p multistream" tid=859446 file=multistream.nim:123 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30a handshaked=false
TRC 2021-04-08 14:31:27.376+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.376+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=108
TRC 2021-04-08 14:31:27.376+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=5cbc7e249951...51c87847db35 tagOut=5cbc7e249951...51c87847db35 nonce=2
TRC 2021-04-08 14:31:27.376+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=2 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.376+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=36 data=132f6d756c74...312e302e300a conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.376+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=132f6d756c74...312e302e300a msgType=MsgOut id=0 initiator=false size=36
TRC 2021-04-08 14:31:27.376+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 data=132f6d756c74...312e302e300a msgType=MsgOut id=0 initiator=false size=36
TRC 2021-04-08 14:31:27.377+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 len=36 msgType=MsgOut id=0 initiator=false size=36
TRC 2021-04-08 14:31:27.377+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a data=36
TRC 2021-04-08 14:31:27.377+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 len=36 msgType=MsgOut id=0 initiator=false size=36
TRC 2021-04-08 14:31:27.377+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.377+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=8 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.377+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=1 data=31 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.377+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=31 msgType=New id=1 initiator=false size=1
TRC 2021-04-08 14:31:27.377+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e871fd9e88b4b3086a30b objName=LPChannel dir=In
TRC 2021-04-08 14:31:27.377+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=606e871fd9e88b4b3086a30b timeout=5m0ns
TRC 2021-04-08 14:31:27.377+10:00 BufferStream created                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:53 s=606e871fd9e88b4b3086a30b
TRC 2021-04-08 14:31:27.377+10:00 Created new lpchannel                      topics="libp2p mplex" tid=859446 file=lpchannel.nim:226 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30b:1 id=1 initiator=false
TRC 2021-04-08 14:31:27.377+10:00 Creating new channel                       topics="libp2p mplex" tid=859446 file=mplex.nim:100 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30b:1 id=1 initiator=false name=1
TRC 2021-04-08 14:31:27.377+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30b:1 data=31 msgType=New id=1 initiator=false size=1
TRC 2021-04-08 14:31:27.377+10:00 created channel                            topics="libp2p mplex" tid=859446 file=mplex.nim:163 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30b:1 msgType=New id=1 initiator=false size=1
TRC 2021-04-08 14:31:27.377+10:00 Starting stream handler                    topics="libp2p switch" tid=859446 file=muxedupgrade.nim:197 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30b
TRC 2021-04-08 14:31:27.377+10:00 Starting multistream handler               topics="libp2p multistream" tid=859446 file=multistream.nim:123 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30b handshaked=false
TRC 2021-04-08 14:31:27.377+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.377+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=10 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.377+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=49 data=132f6d756c74...62657461320a conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.377+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=132f6d756c74...62657461320a msgType=MsgOut id=1 initiator=false size=49
TRC 2021-04-08 14:31:27.377+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30b:1 data=132f6d756c74...62657461320a msgType=MsgOut id=1 initiator=false size=49
TRC 2021-04-08 14:31:27.377+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30b:1 len=49 msgType=MsgOut id=1 initiator=false size=49
TRC 2021-04-08 14:31:27.377+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30b data=49
TRC 2021-04-08 14:31:27.377+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30b:1 len=49 msgType=MsgOut id=1 initiator=false size=49
TRC 2021-04-08 14:31:27.377+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.377+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a len=35
TRC 2021-04-08 14:31:27.377+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30b len=48
TRC 2021-04-08 14:31:27.377+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 bytes=1
TRC 2021-04-08 14:31:27.377+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30b:1 bytes=1
TRC 2021-04-08 14:31:27.377+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 bytes=19
TRC 2021-04-08 14:31:27.377+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30b:1 bytes=19
TRC 2021-04-08 14:31:27.377+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30a ms=/multistream/1.0.0
TRC 2021-04-08 14:31:27.377+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=20
TRC 2021-04-08 14:31:27.377+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=0 msgType=MsgIn data=20 encoded=22
TRC 2021-04-08 14:31:27.377+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30b ms=/multistream/1.0.0
TRC 2021-04-08 14:31:27.377+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30b:1 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=20
TRC 2021-04-08 14:31:27.377+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=1 msgType=MsgIn data=20 encoded=22
TRC 2021-04-08 14:31:27.377+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 bytes=1
TRC 2021-04-08 14:31:27.377+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 bytes=15
TRC 2021-04-08 14:31:27.377+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30a ms=/ipfs/id/1.0.0
TRC 2021-04-08 14:31:27.377+10:00 found handler                              topics="libp2p multistream" tid=859446 file=multistream.nim:164 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30a protocol=/ipfs/id/1.0.0
TRC 2021-04-08 14:31:27.377+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=16
TRC 2021-04-08 14:31:27.377+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=0 msgType=MsgIn data=16 encoded=18
TRC 2021-04-08 14:31:27.377+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30b:1 bytes=1
TRC 2021-04-08 14:31:27.377+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30b:1 bytes=28
TRC 2021-04-08 14:31:27.377+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30b ms=/vac/waku/relay/2.0.0-beta2
TRC 2021-04-08 14:31:27.377+10:00 found handler                              topics="libp2p multistream" tid=859446 file=multistream.nim:164 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30b protocol=/vac/waku/relay/2.0.0-beta2
TRC 2021-04-08 14:31:27.377+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30b:1 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=29
TRC 2021-04-08 14:31:27.377+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=1 msgType=MsgIn data=29 encoded=31
TRC 2021-04-08 14:31:27.377+10:00 handling identify request                  topics="libp2p identify" tid=859446 file=identify.nim:112 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30a
TRC 2021-04-08 14:31:27.377+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=165
TRC 2021-04-08 14:31:27.377+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=0 msgType=MsgIn data=165 encoded=168
DBG 2021-04-08 14:31:27.377+10:00 Incoming WakuRelay connection              topics="wakurelay" tid=859446 file=waku_relay.nim:28
DBG 2021-04-08 14:31:27.377+10:00 starting pubsub read loop                  topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:112 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30b peer=QmQ*GPX9kC closed=false
TRC 2021-04-08 14:31:27.377+10:00 waiting for data                           topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:117 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30b peer=QmQ*GPX9kC closed=false
TRC 2021-04-08 14:31:27.377+10:00 exiting identify handler                   topics="libp2p identify" tid=859446 file=identify.nim:120 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30a
TRC 2021-04-08 14:31:27.377+10:00 Closing with EOF                           topics="libp2p lpstream" tid=859446 file=lpstream.nim:289 s=606e871fd9e88b4b3086a30a
TRC 2021-04-08 14:31:27.377+10:00 Closing channel                            topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:117 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=0
TRC 2021-04-08 14:31:27.378+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=0 msgType=CloseIn data=0 encoded=2
TRC 2021-04-08 14:31:27.378+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=38
TRC 2021-04-08 14:31:27.378+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=0803e10bea3a...8091630d6ddd tagOut=0803e10bea3a...8091630d6ddd nonce=3
TRC 2021-04-08 14:31:27.378+10:00 Closed channel                             topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:133 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 len=0
TRC 2021-04-08 14:31:27.378+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=9 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.378+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=20 data=132f6d756c74...312e302e300a conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.378+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=132f6d756c74...312e302e300a msgType=MsgIn id=1 initiator=true size=20
TRC 2021-04-08 14:31:27.378+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a308 data=132f6d756c74...312e302e300a msgType=MsgIn id=1 initiator=true size=20
TRC 2021-04-08 14:31:27.378+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a308 len=20 msgType=MsgIn id=1 initiator=true size=20
TRC 2021-04-08 14:31:27.378+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmQ*GPX9kC:606e871fd9e88b4b3086a308 data=20
TRC 2021-04-08 14:31:27.378+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a308 len=20 msgType=MsgIn id=1 initiator=true size=20
TRC 2021-04-08 14:31:27.378+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.378+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmQ*GPX9kC:606e871fd9e88b4b3086a308 len=19
TRC 2021-04-08 14:31:27.378+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a308 bytes=1
TRC 2021-04-08 14:31:27.378+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a308 bytes=19
TRC 2021-04-08 14:31:27.378+10:00 multistream handshake success              topics="libp2p multistream" tid=859446 file=multistream.nim:67 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a308
TRC 2021-04-08 14:31:27.379+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=38
TRC 2021-04-08 14:31:27.379+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=77edfc583ec6...7c6be84f2f1e tagOut=77edfc583ec6...7c6be84f2f1e nonce=4
TRC 2021-04-08 14:31:27.379+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=17 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.379+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=20 data=132f6d756c74...312e302e300a conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.379+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=132f6d756c74...312e302e300a msgType=MsgIn id=2 initiator=true size=20
TRC 2021-04-08 14:31:27.379+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a309 data=132f6d756c74...312e302e300a msgType=MsgIn id=2 initiator=true size=20
TRC 2021-04-08 14:31:27.379+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a309 len=20 msgType=MsgIn id=2 initiator=true size=20
TRC 2021-04-08 14:31:27.379+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 data=20
TRC 2021-04-08 14:31:27.379+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a309 len=20 msgType=MsgIn id=2 initiator=true size=20
TRC 2021-04-08 14:31:27.379+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.379+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 len=19
TRC 2021-04-08 14:31:27.379+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 bytes=1
TRC 2021-04-08 14:31:27.379+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=65
TRC 2021-04-08 14:31:27.379+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 bytes=19
TRC 2021-04-08 14:31:27.379+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=0c82357eec07...5ab3516fdc1c tagOut=0c82357eec07...5ab3516fdc1c nonce=5
TRC 2021-04-08 14:31:27.379+10:00 multistream handshake success              topics="libp2p multistream" tid=859446 file=multistream.nim:67 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a309
TRC 2021-04-08 14:31:27.379+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=9 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.379+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=29 data=1c2f7661632f...62657461320a conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.379+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=1c2f7661632f...62657461320a msgType=MsgIn id=1 initiator=true size=29
TRC 2021-04-08 14:31:27.379+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a308 data=1c2f7661632f...62657461320a msgType=MsgIn id=1 initiator=true size=29
TRC 2021-04-08 14:31:27.379+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a308 len=29 msgType=MsgIn id=1 initiator=true size=29
TRC 2021-04-08 14:31:27.379+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmQ*GPX9kC:606e871fd9e88b4b3086a308 data=29
TRC 2021-04-08 14:31:27.379+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a308 len=29 msgType=MsgIn id=1 initiator=true size=29
TRC 2021-04-08 14:31:27.379+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.379+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=17 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.379+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=16 data=0f2f69706673...312e302e300a conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.379+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=0f2f69706673...312e302e300a msgType=MsgIn id=2 initiator=true size=16
TRC 2021-04-08 14:31:27.379+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a309 data=0f2f69706673...312e302e300a msgType=MsgIn id=2 initiator=true size=16
TRC 2021-04-08 14:31:27.379+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a309 len=16 msgType=MsgIn id=2 initiator=true size=16
TRC 2021-04-08 14:31:27.379+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 data=16
TRC 2021-04-08 14:31:27.379+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a309 len=16 msgType=MsgIn id=2 initiator=true size=16
TRC 2021-04-08 14:31:27.379+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.379+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=1229
TRC 2021-04-08 14:31:27.379+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=5749c6af2aac...d6ded3293e40 tagOut=5749c6af2aac...d6ded3293e40 nonce=6
TRC 2021-04-08 14:31:27.379+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=17 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.379+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=1208 data=b6092a0a6970...a1e6b182315c conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.380+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=b6092a0a6970...a1e6b182315c msgType=MsgIn id=2 initiator=true size=1208
TRC 2021-04-08 14:31:27.380+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a309 data=b6092a0a6970...a1e6b182315c msgType=MsgIn id=2 initiator=true size=1208
TRC 2021-04-08 14:31:27.380+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a309 len=1208 msgType=MsgIn id=2 initiator=true size=1208
TRC 2021-04-08 14:31:27.380+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 data=1208
TRC 2021-04-08 14:31:27.380+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmQ*GPX9kC:606e871fd9e88b4b3086a308 len=28
TRC 2021-04-08 14:31:27.380+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 len=15
TRC 2021-04-08 14:31:27.380+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a308 bytes=1
TRC 2021-04-08 14:31:27.380+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 bytes=1
TRC 2021-04-08 14:31:27.380+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a309 len=1208 msgType=MsgIn id=2 initiator=true size=1208
TRC 2021-04-08 14:31:27.380+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.380+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=19 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.380+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=0 data= conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.380+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data= msgType=CloseIn id=2 initiator=true size=0
TRC 2021-04-08 14:31:27.380+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a309 data= msgType=CloseIn id=2 initiator=true size=0
TRC 2021-04-08 14:31:27.380+10:00 Pushing EOF                                topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:98 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309
TRC 2021-04-08 14:31:27.380+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a308 bytes=28
TRC 2021-04-08 14:31:27.380+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 bytes=15
TRC 2021-04-08 14:31:27.380+10:00 reading first requested proto              topics="libp2p multistream" tid=859446 file=multistream.nim:74 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a308
TRC 2021-04-08 14:31:27.380+10:00 successfully selected                      topics="libp2p multistream" tid=859446 file=multistream.nim:76 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a308 proto=/vac/waku/relay/2.0.0-beta2
TRC 2021-04-08 14:31:27.380+10:00 reading first requested proto              topics="libp2p multistream" tid=859446 file=multistream.nim:74 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a309
TRC 2021-04-08 14:31:27.380+10:00 successfully selected                      topics="libp2p multistream" tid=859446 file=multistream.nim:76 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a309 proto=/ipfs/id/1.0.0
TRC 2021-04-08 14:31:27.380+10:00 initiating identify                        topics="libp2p identify" tid=859446 file=identify.nim:129 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a309
TRC 2021-04-08 14:31:27.380+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 len=1207
TRC 2021-04-08 14:31:27.380+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 bytes=1
TRC 2021-04-08 14:31:27.380+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 bytes=1
TRC 2021-04-08 14:31:27.380+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 bytes=1206
DBG 2021-04-08 14:31:27.380+10:00 decodeMsg: decoded message                 topics="libp2p identify" tid=859446 file=identify.nim:94 pubkey="RSA ke...10001\n" addresses="@[/ip4/127.0.0.1/tcp/43219, /ip4/192.168.178.251/tcp/43219, /ip4/10.20.0.9/tcp/43219]" protocols="@[\"/libp2p/circuit/relay/0.1.0\", \"/ipfs/id/1.0.0\", \"/ipfs/id/push/1.0.0\", \"/ipfs/ping/1.0.0\", \"/vac/waku/store/2.0.0-beta1\", \"/vac/waku/relay/2.0.0-beta2\"]" observable_address=Some(/ip4/127.0.0.1/tcp/63797/p2p/16Uiu2HAmRTKRCgrBnFc53PPjPRTB8kg1xwcryx5daCxK1PcJViuh) proto_version="Some(\"ipfs/0.1.0\")" agent_version="Some(\"js-libp2p/0.30.10\")"
TRC 2021-04-08 14:31:27.380+10:00 identified remote peer                     tid=859446 file=upgrade.nim:80 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a309 peerInfo="(peerId: \"QmQTHyAqRdZaarDhn9gCWMfEJxV7PHjnNECBLiRCGPX9kC\", addrs: @[\"/ip4/127.0.0.1/tcp/43219\", \"/ip4/192.168.178.251/tcp/43219\", \"/ip4/10.20.0.9/tcp/43219\"], protocols: @[\"/libp2p/circuit/relay/0.1.0\", \"/ipfs/id/1.0.0\", \"/ipfs/id/push/1.0.0\", \"/ipfs/ping/1.0.0\", \"/vac/waku/store/2.0.0-beta1\", \"/vac/waku/relay/2.0.0-beta2\"], protoVersion: \"ipfs/0.1.0\", agentVersion: \"js-libp2p/0.30.10\")"
TRC 2021-04-08 14:31:27.380+10:00 Get new send connection                    topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:167 p=QmQ*GPX9kC newConn=QmQ*GPX9kC:606e871fd9e88b4b3086a308
TRC 2021-04-08 14:31:27.380+10:00 sending pubsub message to peer             topics="libp2p pubsub" tid=859446 file=pubsub.nim:126 peer=QmQ*GPX9kC msg="(subscriptions: @[(subscribe: true, topic: \"/waku/2/default-waku/proto\")], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:27.380+10:00 sending msg to peer                        topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:237 peer=QmQ*GPX9kC rpcMsg="(subscriptions: @[(subscribe: true, topic: \"/waku/2/default-waku/proto\")], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:27.380+10:00 encodeRpcMsg: encoding message             topics="pubsubprotobuf" tid=859446 file=protobuf.nim:308 msg="(subscriptions: @[(subscribe: true, topic: \"/waku/2/default-waku/proto\")], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:27.380+10:00 sending encoded msgs to peer               topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:209 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a308 encoded=0a1e0801121a...2f70726f746f
TRC 2021-04-08 14:31:27.380+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e871fd9e88b4b3086a308 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=33
TRC 2021-04-08 14:31:27.380+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=1 msgType=MsgOut data=33 encoded=35
DBG 2021-04-08 14:31:27.380+10:00 starting pubsub read loop                  topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:112 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a308 peer=QmQ*GPX9kC closed=false
TRC 2021-04-08 14:31:27.380+10:00 waiting for data                           topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:117 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a308 peer=QmQ*GPX9kC closed=false
TRC 2021-04-08 14:31:27.380+10:00 Closing with EOF                           topics="libp2p lpstream" tid=859446 file=lpstream.nim:289 s=606e871fd9e88b4b3086a309
TRC 2021-04-08 14:31:27.380+10:00 Closing channel                            topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:117 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=0
TRC 2021-04-08 14:31:27.380+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=2 msgType=CloseOut data=0 encoded=2
TRC 2021-04-08 14:31:27.380+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.380+10:00 sent pubsub message to remote              topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:211 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a308
TRC 2021-04-08 14:31:27.380+10:00 Closed channel                             topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:133 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 len=0
TRC 2021-04-08 14:31:27.380+10:00 EOF                                        topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:141 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309
TRC 2021-04-08 14:31:27.380+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 bytes=0
TRC 2021-04-08 14:31:27.380+10:00 Closing BufferStream                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:169 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309 len=0
TRC 2021-04-08 14:31:27.380+10:00 Closed BufferStream                        topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:202 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309
TRC 2021-04-08 14:31:27.380+10:00 Closing connection                         topics="libp2p connection" tid=859446 file=connection.nim:84 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309
TRC 2021-04-08 14:31:27.380+10:00 Closed connection                          topics="libp2p connection" tid=859446 file=connection.nim:94 s=QmQ*GPX9kC:606e871fd9e88b4b3086a309
TRC 2021-04-08 14:31:27.380+10:00 Closing stream                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:257 s=606e871fd9e88b4b3086a309 objName=LPChannel dir=Out
TRC 2021-04-08 14:31:27.380+10:00 Closed stream                              topics="libp2p lpstream" tid=859446 file=lpstream.nim:261 s=606e871fd9e88b4b3086a309 objName=LPChannel dir=Out
TRC 2021-04-08 14:31:27.380+10:00 cleaned up channel                         topics="libp2p mplex" tid=859446 file=mplex.nim:62 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 chann=QmQ*GPX9kC:606e871fd9e88b4b3086a309
TRC 2021-04-08 14:31:27.380+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=18
TRC 2021-04-08 14:31:27.380+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=b07ae8e72808...24a2114b5046 tagOut=b07ae8e72808...24a2114b5046 nonce=7
TRC 2021-04-08 14:31:27.380+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=4 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.380+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=0 data= conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.381+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data= msgType=CloseOut id=0 initiator=false size=0
TRC 2021-04-08 14:31:27.381+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 data= msgType=CloseOut id=0 initiator=false size=0
TRC 2021-04-08 14:31:27.381+10:00 Pushing EOF                                topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:98 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a
TRC 2021-04-08 14:31:27.381+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.381+10:00 EOF                                        topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:141 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a
TRC 2021-04-08 14:31:27.381+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0 bytes=0
TRC 2021-04-08 14:31:27.381+10:00 Closing BufferStream                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:169 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a len=0
TRC 2021-04-08 14:31:27.381+10:00 Closed BufferStream                        topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:202 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a
TRC 2021-04-08 14:31:27.381+10:00 Closing connection                         topics="libp2p connection" tid=859446 file=connection.nim:84 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a
TRC 2021-04-08 14:31:27.381+10:00 Closed connection                          topics="libp2p connection" tid=859446 file=connection.nim:94 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a
TRC 2021-04-08 14:31:27.381+10:00 Closing stream                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:257 s=606e871fd9e88b4b3086a30a objName=LPChannel dir=In
TRC 2021-04-08 14:31:27.381+10:00 Closed stream                              topics="libp2p lpstream" tid=859446 file=lpstream.nim:261 s=606e871fd9e88b4b3086a30a objName=LPChannel dir=In
TRC 2021-04-08 14:31:27.381+10:00 cleaned up channel                         topics="libp2p mplex" tid=859446 file=mplex.nim:62 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 chann=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0
TRC 2021-04-08 14:31:27.381+10:00 Already closed                             topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:113 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0
TRC 2021-04-08 14:31:27.381+10:00 Closing with EOF                           topics="libp2p lpstream" tid=859446 file=lpstream.nim:289 s=606e871fd9e88b4b3086a30a
TRC 2021-04-08 14:31:27.381+10:00 Already closed                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:291
TRC 2021-04-08 14:31:27.381+10:00 Stream handler done                        topics="libp2p switch" tid=859446 file=muxedupgrade.nim:206 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30a
TRC 2021-04-08 14:31:27.381+10:00 finished handling stream                   topics="libp2p mplex" tid=859446 file=mplex.nim:117 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 chann=QmQ*GPX9kC:606e871fd9e88b4b3086a30a:0
TRC 2021-04-08 14:31:27.381+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=19
TRC 2021-04-08 14:31:27.381+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=15a26e61984e...59859dbf22f7 tagOut=15a26e61984e...59859dbf22f7 nonce=8
TRC 2021-04-08 14:31:27.381+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=16 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.382+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=1 data=32 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.382+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=32 msgType=New id=2 initiator=false size=1
TRC 2021-04-08 14:31:27.382+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e871fd9e88b4b3086a30c objName=LPChannel dir=In
TRC 2021-04-08 14:31:27.382+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=606e871fd9e88b4b3086a30c timeout=5m0ns
TRC 2021-04-08 14:31:27.382+10:00 BufferStream created                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:53 s=606e871fd9e88b4b3086a30c
TRC 2021-04-08 14:31:27.382+10:00 Created new lpchannel                      topics="libp2p mplex" tid=859446 file=lpchannel.nim:226 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 id=2 initiator=false
TRC 2021-04-08 14:31:27.382+10:00 Creating new channel                       topics="libp2p mplex" tid=859446 file=mplex.nim:100 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 id=2 initiator=false name=2
TRC 2021-04-08 14:31:27.382+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 data=32 msgType=New id=2 initiator=false size=1
TRC 2021-04-08 14:31:27.382+10:00 created channel                            topics="libp2p mplex" tid=859446 file=mplex.nim:163 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 msgType=New id=2 initiator=false size=1
TRC 2021-04-08 14:31:27.382+10:00 Starting stream handler                    topics="libp2p switch" tid=859446 file=muxedupgrade.nim:197 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30c
TRC 2021-04-08 14:31:27.382+10:00 Starting multistream handler               topics="libp2p multistream" tid=859446 file=multistream.nim:123 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30c handshaked=false
TRC 2021-04-08 14:31:27.382+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.382+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=67
TRC 2021-04-08 14:31:27.382+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=0f442b6917dc...2611cd823090 tagOut=0f442b6917dc...2611cd823090 nonce=9
TRC 2021-04-08 14:31:27.382+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=18 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.382+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=49 data=132f6d756c74...62657461320a conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.382+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=132f6d756c74...62657461320a msgType=MsgOut id=2 initiator=false size=49
TRC 2021-04-08 14:31:27.382+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 data=132f6d756c74...62657461320a msgType=MsgOut id=2 initiator=false size=49
TRC 2021-04-08 14:31:27.382+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 len=49 msgType=MsgOut id=2 initiator=false size=49
TRC 2021-04-08 14:31:27.382+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30c data=49
TRC 2021-04-08 14:31:27.382+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 len=49 msgType=MsgOut id=2 initiator=false size=49
TRC 2021-04-08 14:31:27.382+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.382+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30c len=48
TRC 2021-04-08 14:31:27.382+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 bytes=1
TRC 2021-04-08 14:31:27.382+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 bytes=19
TRC 2021-04-08 14:31:27.382+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30c ms=/multistream/1.0.0
TRC 2021-04-08 14:31:27.382+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=20
TRC 2021-04-08 14:31:27.382+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=2 msgType=MsgIn data=20 encoded=22
TRC 2021-04-08 14:31:27.382+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 bytes=1
TRC 2021-04-08 14:31:27.382+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 bytes=28
TRC 2021-04-08 14:31:27.382+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30c ms=/vac/waku/relay/2.0.0-beta2
TRC 2021-04-08 14:31:27.382+10:00 found handler                              topics="libp2p multistream" tid=859446 file=multistream.nim:164 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30c protocol=/vac/waku/relay/2.0.0-beta2
TRC 2021-04-08 14:31:27.382+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=29
TRC 2021-04-08 14:31:27.382+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=2 msgType=MsgIn data=29 encoded=31
DBG 2021-04-08 14:31:27.382+10:00 Incoming WakuRelay connection              topics="wakurelay" tid=859446 file=waku_relay.nim:28
DBG 2021-04-08 14:31:27.382+10:00 starting pubsub read loop                  topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:112 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30c peer=QmQ*GPX9kC closed=false
TRC 2021-04-08 14:31:27.382+10:00 waiting for data                           topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:117 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30c peer=QmQ*GPX9kC closed=false
TRC 2021-04-08 14:31:27.428+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=19
TRC 2021-04-08 14:31:27.428+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=69d0e4f92b4d...778173bbfbe2 tagOut=69d0e4f92b4d...778173bbfbe2 nonce=10
TRC 2021-04-08 14:31:27.428+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=18 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.428+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=1 data=00 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.428+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=00 msgType=MsgOut id=2 initiator=false size=1
TRC 2021-04-08 14:31:27.428+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 data=00 msgType=MsgOut id=2 initiator=false size=1
TRC 2021-04-08 14:31:27.428+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 len=1 msgType=MsgOut id=2 initiator=false size=1
TRC 2021-04-08 14:31:27.428+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30c data=1
TRC 2021-04-08 14:31:27.428+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 len=1 msgType=MsgOut id=2 initiator=false size=1
TRC 2021-04-08 14:31:27.428+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:27.428+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e871fd9e88b4b3086a30c:2 bytes=1
TRC 2021-04-08 14:31:27.428+10:00 read data from peer                        topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:120 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30c peer=QmQ*GPX9kC closed=false data=
TRC 2021-04-08 14:31:27.428+10:00 decodeRpcMsg: decoding message             topics="pubsubprotobuf" tid=859446 file=protobuf.nim:321 msg=
TRC 2021-04-08 14:31:27.428+10:00 decodeMessages: decoding message           topics="pubsubprotobuf" tid=859446 file=protobuf.nim:296
TRC 2021-04-08 14:31:27.428+10:00 decodeMessages: no messages found          topics="pubsubprotobuf" tid=859446 file=protobuf.nim:304
TRC 2021-04-08 14:31:27.428+10:00 decodeSubscriptions: decoding message      topics="pubsubprotobuf" tid=859446 file=protobuf.nim:251
TRC 2021-04-08 14:31:27.428+10:00 decodeControl: decoding message            topics="pubsubprotobuf" tid=859446 file=protobuf.nim:202
TRC 2021-04-08 14:31:27.428+10:00 decoded msg from peer                      topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:131 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30c peer=QmQ*GPX9kC closed=false msg="(subscriptions: @[], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-04-08 14:31:27.428+10:00 processing RPC message                     topics="libp2p pubsub" tid=859446 file=pubsub.nim:210 msg="(subscriptions: @[], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))" peer=QmQ*GPX9kC
TRC 2021-04-08 14:31:27.428+10:00 waiting for data                           topics="libp2p pubsubpeer" tid=859446 file=pubsubpeer.nim:117 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a30c peer=QmQ*GPX9kC closed=false
TRC 2021-04-08 14:31:29.381+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=19
TRC 2021-04-08 14:31:29.381+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=2a514f046da1...b23ef56575d4 tagOut=2a514f046da1...b23ef56575d4 nonce=11
TRC 2021-04-08 14:31:29.381+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=24 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:29.381+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=1 data=33 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:29.381+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=33 msgType=New id=3 initiator=false size=1
TRC 2021-04-08 14:31:29.381+10:00 Stream created                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:128 s=606e8721d9e88b4b3086a30d objName=LPChannel dir=In
TRC 2021-04-08 14:31:29.381+10:00 Monitoring for timeout                     topics="libp2p connection" tid=859446 file=connection.nim:74 s=606e8721d9e88b4b3086a30d timeout=5m0ns
TRC 2021-04-08 14:31:29.381+10:00 BufferStream created                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:53 s=606e8721d9e88b4b3086a30d
TRC 2021-04-08 14:31:29.381+10:00 Created new lpchannel                      topics="libp2p mplex" tid=859446 file=lpchannel.nim:226 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 id=3 initiator=false
TRC 2021-04-08 14:31:29.381+10:00 Creating new channel                       topics="libp2p mplex" tid=859446 file=mplex.nim:100 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 id=3 initiator=false name=3
TRC 2021-04-08 14:31:29.381+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 data=33 msgType=New id=3 initiator=false size=1
TRC 2021-04-08 14:31:29.381+10:00 created channel                            topics="libp2p mplex" tid=859446 file=mplex.nim:163 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 msgType=New id=3 initiator=false size=1
TRC 2021-04-08 14:31:29.381+10:00 Starting stream handler                    topics="libp2p switch" tid=859446 file=muxedupgrade.nim:197 conn=QmQ*GPX9kC:606e8721d9e88b4b3086a30d
TRC 2021-04-08 14:31:29.381+10:00 Starting multistream handler               topics="libp2p multistream" tid=859446 file=multistream.nim:123 conn=QmQ*GPX9kC:606e8721d9e88b4b3086a30d handshaked=false
TRC 2021-04-08 14:31:29.381+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:29.381+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=67
TRC 2021-04-08 14:31:29.381+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=16485e46ca70...3375b62b7638 tagOut=16485e46ca70...3375b62b7638 nonce=12
TRC 2021-04-08 14:31:29.381+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=26 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:29.381+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=49 data=132f6d756c74...62657461310a conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:29.381+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=132f6d756c74...62657461310a msgType=MsgOut id=3 initiator=false size=49
TRC 2021-04-08 14:31:29.381+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 data=132f6d756c74...62657461310a msgType=MsgOut id=3 initiator=false size=49
TRC 2021-04-08 14:31:29.381+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 len=49 msgType=MsgOut id=3 initiator=false size=49
TRC 2021-04-08 14:31:29.381+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d data=49
TRC 2021-04-08 14:31:29.381+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 len=49 msgType=MsgOut id=3 initiator=false size=49
TRC 2021-04-08 14:31:29.381+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:29.381+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d len=48
TRC 2021-04-08 14:31:29.381+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 bytes=1
TRC 2021-04-08 14:31:29.381+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 bytes=19
TRC 2021-04-08 14:31:29.381+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmQ*GPX9kC:606e8721d9e88b4b3086a30d ms=/multistream/1.0.0
TRC 2021-04-08 14:31:29.381+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=20
TRC 2021-04-08 14:31:29.381+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=3 msgType=MsgIn data=20 encoded=22
TRC 2021-04-08 14:31:29.381+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 bytes=1
TRC 2021-04-08 14:31:29.382+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 bytes=28
TRC 2021-04-08 14:31:29.382+10:00 handle: got request                        topics="libp2p multistream" tid=859446 file=multistream.nim:135 conn=QmQ*GPX9kC:606e8721d9e88b4b3086a30d ms=/vac/waku/store/2.0.0-beta1
TRC 2021-04-08 14:31:29.382+10:00 found handler                              topics="libp2p multistream" tid=859446 file=multistream.nim:164 conn=QmQ*GPX9kC:606e8721d9e88b4b3086a30d protocol=/vac/waku/store/2.0.0-beta1
TRC 2021-04-08 14:31:29.382+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=29
TRC 2021-04-08 14:31:29.382+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=3 msgType=MsgIn data=29 encoded=31
TRC 2021-04-08 14:31:29.383+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=64
TRC 2021-04-08 14:31:29.383+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=1163819e94be...0b48e503cc1e tagOut=1163819e94be...0b48e503cc1e nonce=13
TRC 2021-04-08 14:31:29.383+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=26 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:29.383+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=46 data=2d0a24613933...050a01011200 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:29.383+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data=2d0a24613933...050a01011200 msgType=MsgOut id=3 initiator=false size=46
TRC 2021-04-08 14:31:29.383+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 data=2d0a24613933...050a01011200 msgType=MsgOut id=3 initiator=false size=46
TRC 2021-04-08 14:31:29.383+10:00 pushing data to channel                    topics="libp2p mplex" tid=859446 file=mplex.nim:176 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 len=46 msgType=MsgOut id=3 initiator=false size=46
TRC 2021-04-08 14:31:29.383+10:00 Pushing data                               topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:80 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d data=46
TRC 2021-04-08 14:31:29.383+10:00 pushed data to channel                     topics="libp2p mplex" tid=859446 file=mplex.nim:178 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 len=46 msgType=MsgOut id=3 initiator=false size=46
TRC 2021-04-08 14:31:29.383+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:29.383+10:00 add leftovers                              topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:150 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d len=45
TRC 2021-04-08 14:31:29.383+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 bytes=1
TRC 2021-04-08 14:31:29.383+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 bytes=45
INF 2021-04-08 14:31:29.383+10:00 received query                             topics="wakustore" tid=859446 file=waku_store.nim:298
INF 2021-04-08 14:31:29.383+10:00 handle store swap is nil                   topics="wakustore" tid=859446 file=waku_store.nim:312
TRC 2021-04-08 14:31:29.383+10:00 write msg                                  topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:189 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=146
TRC 2021-04-08 14:31:29.383+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=3 msgType=MsgIn data=146 encoded=149
TRC 2021-04-08 14:31:29.383+10:00 Closing channel                            topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:117 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 len=0
TRC 2021-04-08 14:31:29.383+10:00 writing mplex message                      topics="libp2p mplexcoder" tid=859446 file=coder.nim:78 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306 id=3 msgType=CloseIn data=0 encoded=2
TRC 2021-04-08 14:31:29.384+10:00 readFrame                                  topics="libp2p noise" tid=859446 file=noise.nim:293 sconn=QmQ*GPX9kC:606e871fd9e88b4b3086a305 size=18
TRC 2021-04-08 14:31:29.384+10:00 Closing with EOF                           topics="libp2p lpstream" tid=859446 file=lpstream.nim:289 s=606e8721d9e88b4b3086a30d
TRC 2021-04-08 14:31:29.384+10:00 Already closed                             topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:113 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3
TRC 2021-04-08 14:31:29.384+10:00 decryptWithAd                              topics="libp2p noise" tid=859446 file=noise.nim:155 tagIn=289897f70c6b...1dc305ef782c tagOut=289897f70c6b...1dc305ef782c nonce=14
TRC 2021-04-08 14:31:29.384+10:00 Closed channel                             topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:133 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 len=0
TRC 2021-04-08 14:31:29.384+10:00 read header varint                         topics="libp2p mplexcoder" tid=859446 file=coder.nim:45 varint=28 conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:29.384+10:00 read data                                  topics="libp2p mplexcoder" tid=859446 file=coder.nim:48 dataLen=0 data= conn=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:29.384+10:00 read message from connection               topics="libp2p mplex" tid=859446 file=mplex.nim:140 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 data= msgType=CloseOut id=3 initiator=false size=0
TRC 2021-04-08 14:31:29.384+10:00 Processing channel message                 topics="libp2p mplex" tid=859446 file=mplex.nim:159 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 channel=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 data= msgType=CloseOut id=3 initiator=false size=0
TRC 2021-04-08 14:31:29.384+10:00 Pushing EOF                                topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:98 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d
TRC 2021-04-08 14:31:29.384+10:00 waiting for data                           topics="libp2p mplex" tid=859446 file=mplex.nim:129 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306
TRC 2021-04-08 14:31:29.384+10:00 EOF                                        topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:141 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d
TRC 2021-04-08 14:31:29.384+10:00 readOnce                                   topics="libp2p mplexchannel" tid=859446 file=lpchannel.nim:155 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3 bytes=0
TRC 2021-04-08 14:31:29.384+10:00 Closing BufferStream                       topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:169 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d len=0
TRC 2021-04-08 14:31:29.384+10:00 Closed BufferStream                        topics="libp2p bufferstream" tid=859446 file=bufferstream.nim:202 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d
TRC 2021-04-08 14:31:29.384+10:00 Closing connection                         topics="libp2p connection" tid=859446 file=connection.nim:84 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d
TRC 2021-04-08 14:31:29.384+10:00 Closed connection                          topics="libp2p connection" tid=859446 file=connection.nim:94 s=QmQ*GPX9kC:606e8721d9e88b4b3086a30d
TRC 2021-04-08 14:31:29.384+10:00 Closing stream                             topics="libp2p lpstream" tid=859446 file=lpstream.nim:257 s=606e8721d9e88b4b3086a30d objName=LPChannel dir=In
TRC 2021-04-08 14:31:29.384+10:00 Closed stream                              topics="libp2p lpstream" tid=859446 file=lpstream.nim:261 s=606e8721d9e88b4b3086a30d objName=LPChannel dir=In
TRC 2021-04-08 14:31:29.384+10:00 cleaned up channel                         topics="libp2p mplex" tid=859446 file=mplex.nim:62 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 chann=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3
TRC 2021-04-08 14:31:29.384+10:00 Stream handler done                        topics="libp2p switch" tid=859446 file=muxedupgrade.nim:206 conn=QmQ*GPX9kC:606e8721d9e88b4b3086a30d
TRC 2021-04-08 14:31:29.384+10:00 finished handling stream                   topics="libp2p mplex" tid=859446 file=mplex.nim:117 m=QmQ*GPX9kC:606e871fd9e88b4b3086a306 chann=QmQ*GPX9kC:606e8721d9e88b4b3086a30d:3
libbacktrace error: no debugging symbols available. Compile with '--debugger:native'.
Traceback (most recent call last, using override)
/home/froyer/src/status-im/js-waku/nim-waku/waku/v2/node/wakunode2.nim(623) wakunode2
/home/froyer/src/status-im/js-waku/nim-waku/vendor/nim-chronos/chronos/asyncloop.nim(1086) runForever
/home/froyer/src/status-im/js-waku/nim-waku/vendor/nim-chronos/chronos/asyncloop.nim(718) poll
/home/froyer/src/status-im/js-waku/nim-waku/vendor/nim-chronos/chronos/ioselects/ioselectors_epoll.nim(379) selectInto
/home/froyer/src/status-im/js-waku/nim-waku/vendor/nimbus-build-system/vendor/Nim/lib/system/excpt.nim(610) signalHandler
SIGINT: Interrupted by Ctrl-C.
jm-clius commented 3 years ago

Ah, yes, sorry that this probably wasn't clear: Store protocol currently assumes that the history is served from somewhere. You therefore need to set a --storenode in order for RPC history queries to work. The node will then create an ad-hoc, short-lived connection to that remote store node to download historical messages.