Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

nim-waku dies upon connection #439

Closed
D4nte opened this issue Mar 29, 2021 · 0 comments · Fixed by #441
Closed

nim-waku dies upon connection #439

D4nte opened this issue Mar 29, 2021 · 0 comments · Fixed by #441
Assignees
@jm-clius
Labels
bug Something isn't working

Comments

@D4nte
Copy link
Contributor

D4nte commented Mar 29, 2021

On commit d1c1a0c

The following command starts wakunode2 that proceed to connect to a remote and then die:

./build/wakunode2 --storenode:/ip4/134.209.139.210/tcp/30303/p2p/16Uiu2HAmPLe7Mzm8TsYUubgCAW1aJoeFScxrLj8ppHFivPo97bUZ --staticnode:/ip4/134.209.139.210/tcp/30303/p2p/16Uiu2HAmPLe7Mzm8TsYUubgCAW1aJoeFScxrLj8ppHFivPo97bUZ

Logs:

DBG 2021-03-29 16:29:32.521+11:00 UPnP                                       topics="nat" tid=4069991 file=nat.nim:67 msg="Internet Gateway Device found."
INF 2021-03-29 16:29:32.535+11:00 UPnP: added port mapping                   topics="nat" tid=4069991 file=nat.nim:126 externalPort=60000 internalPort=60000 protocol=TCP
INF 2021-03-29 16:29:32.544+11:00 UPnP: added port mapping                   topics="nat" tid=4069991 file=nat.nim:126 externalPort=60000 internalPort=60000 protocol=UDP
INF 2021-03-29 16:29:32.550+11:00 Initializing networking                    topics="wakunode" tid=4069991 file=wakunode2.nim:126 hostAddress=/ip4/0.0.0.0/tcp/60000 announcedAddresses=@[/ip4/122.151.214.125/tcp/60000]
TRC 2021-03-29 16:29:32.550+11:00 registering protocols                      topics="libp2p multistream" tid=4069991 file=multistream.nim:183 protos="@[\"/ipfs/id/1.0.0\"]"
TRC 2021-03-29 16:29:32.550+11:00 starting switch for peer                   topics="libp2p switch" tid=4069991 file=switch.nim:358 peerInfo="(peerId: \"16Uiu2HAm5k28UiiR3KhfdGt6VMiw1AiTqc9CUkoaYLJeyx3zCzbi\", addrs: @[\"/ip4/0.0.0.0/tcp/60000\"], protocols: @[\"/ipfs/id/1.0.0\"], protoVersion: \"\", agentVersion: \"\")"
TRC 2021-03-29 16:29:32.550+11:00 starting transport                         topics="libp2p transport" tid=4069991 file=transport.nim:43 address=/ip4/0.0.0.0/tcp/60000
TRC 2021-03-29 16:29:32.551+11:00 Starting TCP transport                     topics="libp2p tcptransport" tid=4069991 file=tcptransport.nim:131
TRC 2021-03-29 16:29:32.551+11:00 Listening on                               topics="libp2p tcptransport" tid=4069991 file=tcptransport.nim:142 address=/ip4/0.0.0.0/tcp/60000
DBG 2021-03-29 16:29:32.551+11:00 About to accept incoming connection        topics="libp2p switch" tid=4069991 file=switch.nim:318
TRC 2021-03-29 16:29:32.551+11:00 Acquired slot                              topics="libp2p semaphore" tid=4069991 file=semaphore.nim:36 available=3 queue=0
TRC 2021-03-29 16:29:32.551+11:00 Tracking incoming connection               topics="libp2p connmanager" tid=4069991 file=connmanager.nim:422
TRC 2021-03-29 16:29:32.551+11:00 Acquired slot                              topics="libp2p semaphore" tid=4069991 file=semaphore.nim:36 available=49 queue=0
DBG 2021-03-29 16:29:32.551+11:00 Started libp2p node                        topics="libp2p switch" tid=4069991 file=switch.nim:368 peer="(peerId: \"16Uiu2HAm5k28UiiR3KhfdGt6VMiw1AiTqc9CUkoaYLJeyx3zCzbi\", addrs: @[\"/ip4/0.0.0.0/tcp/60000\"], protocols: @[\"/ipfs/id/1.0.0\"], protoVersion: \"\", agentVersion: \"\")"
INF 2021-03-29 16:29:32.551+11:00 PeerInfo                                   topics="wakunode" tid=4069991 file=wakunode2.nim:160 peerId=16U*3zCzbi addrs="@[/ip4/0.0.0.0/tcp/60000, /ip4/122.151.214.125/tcp/60000]"
INF 2021-03-29 16:29:32.551+11:00 Listening on                               topics="wakunode" tid=4069991 file=wakunode2.nim:163 full=/ip4/122.151.214.125/tcp/60000/p2p/16Uiu2HAm5k28UiiR3KhfdGt6VMiw1AiTqc9CUkoaYLJeyx3zCzbi
DBG 2021-03-29 16:29:32.551+11:00 initWakuRelay                              topics="wakurelay" tid=4069991 file=waku_relay.nim:36
DBG 2021-03-29 16:29:32.551+11:00 init                                       topics="wakurelay" tid=4069991 file=waku_relay.nim:21
DBG 2021-03-29 16:29:32.551+11:00 init                                       topics="wakurelay" tid=4069991 file=waku_relay.nim:21
TRC 2021-03-29 16:29:32.551+11:00 registering protocols                      topics="libp2p multistream" tid=4069991 file=multistream.nim:183 protos="@[\"/vac/waku/relay/2.0.0-beta2\", \"/vac/waku/relay/2.0.0-beta2\"]"
DBG 2021-03-29 16:29:32.551+11:00 Reconnecting peers                         topics="wakupeers" tid=4069991 file=peer_manager.nim:190 proto=/vac/waku/relay/2.0.0-beta2
INF 2021-03-29 16:29:32.551+11:00 mounting relay                             topics="wakunode" tid=4069991 file=wakunode2.nim:396
DBG 2021-03-29 16:29:32.551+11:00 subscribe                                  topics="wakurelay" tid=4069991 file=waku_relay.nim:51 pubSubTopic=/waku/2/default-waku/proto
TRC 2021-03-29 16:29:32.551+11:00 subscribing to topic                       topics="libp2p pubsub" tid=4069991 file=pubsub.nim:427 name=/waku/2/default-waku/proto
TRC 2021-03-29 16:29:32.551+11:00 rebalancing mesh                           tid=4069991 file=behavior.nim:251 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-03-29 16:29:32.551+11:00 replenishing mesh                          tid=4069991 file=behavior.nim:260 peers=0 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-03-29 16:29:32.551+11:00 grafting                                   tid=4069991 file=behavior.nim:284 grafting=0 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-03-29 16:29:32.551+11:00 mesh balanced                              tid=4069991 file=behavior.nim:443 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
INF 2021-03-29 16:29:32.551+11:00 subscribe                                  topics="wakunode" tid=4069991 file=wakunode2.nim:177 topic=/waku/2/default-waku/proto
DBG 2021-03-29 16:29:32.551+11:00 subscribe                                  topics="wakurelay" tid=4069991 file=waku_relay.nim:51 pubSubTopic=/waku/2/default-waku/proto
TRC 2021-03-29 16:29:32.551+11:00 rebalancing mesh                           tid=4069991 file=behavior.nim:251 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-03-29 16:29:32.551+11:00 replenishing mesh                          tid=4069991 file=behavior.nim:260 peers=0 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-03-29 16:29:32.551+11:00 grafting                                   tid=4069991 file=behavior.nim:284 grafting=0 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
TRC 2021-03-29 16:29:32.551+11:00 mesh balanced                              tid=4069991 file=behavior.nim:443 mesh=0 gossipsub=0 topic=/waku/2/default-waku/proto
INF 2021-03-29 16:29:32.551+11:00 connectToNodes                             topics="wakunode" tid=4069991 file=wakunode2.nim:448 node=/ip4/134.209.139.210/tcp/30303/p2p/16Uiu2HAmPLe7Mzm8TsYUubgCAW1aJoeFScxrLj8ppHFivPo97bUZ
INF 2021-03-29 16:29:32.551+11:00 dialPeer                                   topics="wakunode" tid=4069991 file=wakunode2.nim:423 address=/ip4/134.209.139.210/tcp/30303/p2p/16Uiu2HAmPLe7Mzm8TsYUubgCAW1aJoeFScxrLj8ppHFivPo97bUZ
INF 2021-03-29 16:29:32.551+11:00 Dialing peer                               topics="wakunode" tid=4069991 file=wakunode2.nim:427 wireAddr=/ip4/134.209.139.210/tcp/30303 peerId=16U*o97bUZ
TRC 2021-03-29 16:29:32.551+11:00 Adding newly dialed peer to manager        topics="wakupeers" tid=4069991 file=peer_manager.nim:206 peerId=16U*o97bUZ addr=/ip4/134.209.139.210/tcp/30303 proto=/vac/waku/relay/2.0.0-beta2
DBG 2021-03-29 16:29:32.552+11:00 Adding peer to manager                     topics="wakupeers" tid=4069991 file=peer_manager.nim:155 peerId=16U*o97bUZ addr=/ip4/134.209.139.210/tcp/30303 proto=/vac/waku/relay/2.0.0-beta2
INF 2021-03-29 16:29:32.552+11:00 Dialing peer from manager                  topics="wakupeers" tid=4069991 file=peer_manager.nim:45 wireAddr=/ip4/134.209.139.210/tcp/30303 peerId=16U*o97bUZ
TRC 2021-03-29 16:29:32.552+11:00 Dialing (new)                              topics="libp2p switch" tid=4069991 file=switch.nim:254 peerId=16U*o97bUZ protos="@[\"/vac/waku/relay/2.0.0-beta2\"]"
TRC 2021-03-29 16:29:32.552+11:00 connection not found                       topics="libp2p connmanager" tid=4069991 file=connmanager.nim:333 peerId=16U*o97bUZ
DBG 2021-03-29 16:29:32.552+11:00 Dialing peer                               topics="libp2p switch" tid=4069991 file=switch.nim:102 peerId=16U*o97bUZ
TRC 2021-03-29 16:29:32.552+11:00 Dialing address                            topics="libp2p switch" tid=4069991 file=switch.nim:114 address=/ip4/134.209.139.210/tcp/30303 peerId=16U*o97bUZ
TRC 2021-03-29 16:29:32.552+11:00 Tracking outgoing connection               topics="libp2p connmanager" tid=4069991 file=connmanager.nim:443 count=49 max=50
TRC 2021-03-29 16:29:32.552+11:00 Acquired slot                              topics="libp2p semaphore" tid=4069991 file=semaphore.nim:36 available=48 queue=0
TRC 2021-03-29 16:29:32.552+11:00 Dialing remote peer                        topics="libp2p tcptransport" tid=4069991 file=tcptransport.nim:199 address=/ip4/134.209.139.210/tcp/30303
TRC 2021-03-29 16:29:32.847+11:00 Handling tcp connection                    topics="libp2p tcptransport" tid=4069991 file=tcptransport.nim:74 address=/ip4/134.209.139.210/tcp/30303 dir=Out clients=0
TRC 2021-03-29 16:29:32.847+11:00 Stream created                             topics="libp2p lpstream" tid=4069991 file=lpstream.nim:128 s=606165bca0cc017a3e6f68e9 objName=ChronosStream dir=Out
TRC 2021-03-29 16:29:32.847+11:00 Monitoring for timeout                     topics="libp2p connection" tid=4069991 file=connection.nim:74 s=606165bca0cc017a3e6f68e9 timeout=10m0ns
TRC 2021-03-29 16:29:32.847+11:00 Got connection                             topics="libp2p connmanager" tid=4069991 file=connmanager.nim:393 conn=606165bca0cc017a3e6f68e9
TRC 2021-03-29 16:29:32.847+11:00 Upgrading outgoing connection              tid=4069991 file=muxedupgrade.nim:71 conn=16U*o97bUZ:606165bca0cc017a3e6f68e9
TRC 2021-03-29 16:29:32.847+11:00 initiating handshake                       topics="libp2p multistream" tid=4069991 file=multistream.nim:53 conn=16U*o97bUZ:606165bca0cc017a3e6f68e9 codec="\x13/multistream/1.0.0\n"
TRC 2021-03-29 16:29:32.847+11:00 selecting proto                            topics="libp2p multistream" tid=4069991 file=multistream.nim:57 conn=16U*o97bUZ:606165bca0cc017a3e6f68e9 proto=/noise
TRC 2021-03-29 16:29:33.171+11:00 multistream handshake success              topics="libp2p multistream" tid=4069991 file=multistream.nim:67 conn=16U*o97bUZ:606165bca0cc017a3e6f68e9
TRC 2021-03-29 16:29:33.501+11:00 reading first requested proto              topics="libp2p multistream" tid=4069991 file=multistream.nim:74 conn=16U*o97bUZ:606165bca0cc017a3e6f68e9
TRC 2021-03-29 16:29:33.501+11:00 successfully selected                      topics="libp2p multistream" tid=4069991 file=multistream.nim:76 conn=16U*o97bUZ:606165bca0cc017a3e6f68e9 proto=/noise
TRC 2021-03-29 16:29:33.501+11:00 Securing connection                        tid=4069991 file=upgrade.nim:46 conn=16U*o97bUZ:606165bca0cc017a3e6f68e9 codec=/noise
TRC 2021-03-29 16:29:33.501+11:00 Starting Noise handshake                   topics="libp2p noise" tid=4069991 file=noise.nim:482 conn=16U*o97bUZ:606165bca0cc017a3e6f68e9 initiator=true
TRC 2021-03-29 16:29:33.501+11:00 mixHash                                    topics="libp2p noise" tid=4069991 file=noise.nim:184 hash=f3d15e6108ed...2e0958dc002d
TRC 2021-03-29 16:29:33.501+11:00 noise write e                              topics="libp2p noise" tid=4069991 file=noise.nim:221
TRC 2021-03-29 16:29:33.502+11:00 mixHash                                    topics="libp2p noise" tid=4069991 file=noise.nim:184 hash=de4d5df9ce98...28dbb5da4931
TRC 2021-03-29 16:29:33.502+11:00 mixHash                                    topics="libp2p noise" tid=4069991 file=noise.nim:184 hash=564c5d4107c1...21e9bae2e708
TRC 2021-03-29 16:29:33.502+11:00 writeFrame                                 topics="libp2p noise" tid=4069991 file=noise.nim:307 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=32 data=45ddb910fe9e...4b2ebab11051
TRC 2021-03-29 16:29:33.801+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=207
TRC 2021-03-29 16:29:33.801+11:00 noise read e                               topics="libp2p noise" tid=4069991 file=noise.nim:260 size=207
TRC 2021-03-29 16:29:33.801+11:00 mixHash                                    topics="libp2p noise" tid=4069991 file=noise.nim:184 hash=516c0de0a3cb...15d3b81c58c3
TRC 2021-03-29 16:29:33.801+11:00 noise dh ee                                topics="libp2p noise" tid=4069991 file=noise.nim:233
TRC 2021-03-29 16:29:33.802+11:00 mixKey                                     topics="libp2p noise" tid=4069991 file=noise.nim:176 key=576100170f82...66e0746ca438
TRC 2021-03-29 16:29:33.802+11:00 noise read s                               topics="libp2p noise" tid=4069991 file=noise.nim:271 size=175
TRC 2021-03-29 16:29:33.802+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=a1ed27e31bc2...cc3df3ad6db8 tagOut=a1ed27e31bc2...cc3df3ad6db8 nonce=0
TRC 2021-03-29 16:29:33.802+11:00 mixHash                                    topics="libp2p noise" tid=4069991 file=noise.nim:184 hash=a7323b1ee90f...818089080f21
TRC 2021-03-29 16:29:33.802+11:00 noise dh es                                topics="libp2p noise" tid=4069991 file=noise.nim:238
TRC 2021-03-29 16:29:33.802+11:00 mixKey                                     topics="libp2p noise" tid=4069991 file=noise.nim:176 key=a2878204ad6b...96bc82d98b4e
TRC 2021-03-29 16:29:33.802+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=ae83d06892cc...336b7b2bb6c7 tagOut=ae83d06892cc...336b7b2bb6c7 nonce=0
TRC 2021-03-29 16:29:33.802+11:00 mixHash                                    topics="libp2p noise" tid=4069991 file=noise.nim:184 hash=1e92c7ead6ca...6d142cc752bb
TRC 2021-03-29 16:29:33.803+11:00 noise write s                              topics="libp2p noise" tid=4069991 file=noise.nim:228
TRC 2021-03-29 16:29:33.803+11:00 encryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:144 tag=c1fad2f37a187e0e1fe34a6b50ba974b data=697c709d4c8a...4a6b50ba974b nonce=1
TRC 2021-03-29 16:29:33.803+11:00 mixHash                                    topics="libp2p noise" tid=4069991 file=noise.nim:184 hash=08bf82e33965...69c92886156c
TRC 2021-03-29 16:29:33.803+11:00 noise dh se                                topics="libp2p noise" tid=4069991 file=noise.nim:246
TRC 2021-03-29 16:29:33.803+11:00 mixKey                                     topics="libp2p noise" tid=4069991 file=noise.nim:176 key=e46c1a71683e...676ade042fb2
TRC 2021-03-29 16:29:33.803+11:00 encryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:144 tag=754d1e92bfb8c5735091657e24cee89a data=842bae3e36c4...657e24cee89a nonce=0
TRC 2021-03-29 16:29:33.803+11:00 mixHash                                    topics="libp2p noise" tid=4069991 file=noise.nim:184 hash=bb5d2f6a32ea...d2b7edfc29ec
TRC 2021-03-29 16:29:33.803+11:00 writeFrame                                 topics="libp2p noise" tid=4069991 file=noise.nim:307 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=175 data=697c709d4c8a...657e24cee89a
TRC 2021-03-29 16:29:33.804+11:00 Remote signature verified                  topics="libp2p noise" tid=4069991 file=noise.nim:529 conn=16U*o97bUZ:606165bca0cc017a3e6f68e9
TRC 2021-03-29 16:29:33.804+11:00 Stream created                             topics="libp2p lpstream" tid=4069991 file=lpstream.nim:128 s=606165bda0cc017a3e6f68ea objName=SecureConn dir=Out
TRC 2021-03-29 16:29:33.804+11:00 Monitoring for timeout                     topics="libp2p connection" tid=4069991 file=connection.nim:74 s=16U*o97bUZ:606165bda0cc017a3e6f68ea timeout=5m0ns
TRC 2021-03-29 16:29:33.804+11:00 Noise handshake completed!                 topics="libp2p noise" tid=4069991 file=noise.nim:563 initiator=true peer="(peerId: \"16Uiu2HAmPLe7Mzm8TsYUubgCAW1aJoeFScxrLj8ppHFivPo97bUZ\", addrs: @[\"/ip4/134.209.139.210/tcp/30303\"], protocols: @[], protoVersion: \"\", agentVersion: \"\")"
TRC 2021-03-29 16:29:33.804+11:00 Muxing connection                          tid=4069991 file=muxedupgrade.nim:37 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:33.804+11:00 initiating handshake                       topics="libp2p multistream" tid=4069991 file=multistream.nim:53 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea codec="\x13/multistream/1.0.0\n"
TRC 2021-03-29 16:29:33.804+11:00 selecting proto                            topics="libp2p multistream" tid=4069991 file=multistream.nim:57 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea proto=/mplex/6.7.0
TRC 2021-03-29 16:29:34.400+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=36
TRC 2021-03-29 16:29:34.400+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=3eb6882bd2c7...8b606cf63dde tagOut=3eb6882bd2c7...8b606cf63dde nonce=0
TRC 2021-03-29 16:29:34.400+11:00 multistream handshake success              topics="libp2p multistream" tid=4069991 file=multistream.nim:67 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.733+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=30
TRC 2021-03-29 16:29:34.733+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=9ecccde52235...20d45fe5c5a4 tagOut=9ecccde52235...20d45fe5c5a4 nonce=1
TRC 2021-03-29 16:29:34.733+11:00 reading first requested proto              topics="libp2p multistream" tid=4069991 file=multistream.nim:74 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.733+11:00 successfully selected                      topics="libp2p multistream" tid=4069991 file=multistream.nim:76 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea proto=/mplex/6.7.0
TRC 2021-03-29 16:29:34.733+11:00 Found a muxer                              tid=4069991 file=muxedupgrade.nim:47 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea muxerName=/mplex/6.7.0
TRC 2021-03-29 16:29:34.733+11:00 Stored connection                          topics="libp2p connmanager" tid=4069991 file=connmanager.nim:379 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea direction=Out connections=1
TRC 2021-03-29 16:29:34.734+11:00 Starting mplex handler                     topics="libp2p mplex" tid=4069991 file=mplex.nim:126 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.734+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.734+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=42
TRC 2021-03-29 16:29:34.734+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=dcaa3a5bebcf...782ff8d512dd tagOut=dcaa3a5bebcf...782ff8d512dd nonce=2
TRC 2021-03-29 16:29:34.734+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=8 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.734+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=24 data=363036313635...356237636661 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.734+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=363036313635...356237636661 msgType=New id=1 initiator=false size=24
TRC 2021-03-29 16:29:34.734+11:00 Stream created                             topics="libp2p lpstream" tid=4069991 file=lpstream.nim:128 s=606165bea0cc017a3e6f68ec objName=LPChannel dir=In
TRC 2021-03-29 16:29:34.734+11:00 Monitoring for timeout                     topics="libp2p connection" tid=4069991 file=connection.nim:74 s=606165bea0cc017a3e6f68ec timeout=5m0ns
TRC 2021-03-29 16:29:34.734+11:00 BufferStream created                       topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:53 s=606165bea0cc017a3e6f68ec
TRC 2021-03-29 16:29:34.734+11:00 Created new lpchannel                      topics="libp2p mplex" tid=4069991 file=lpchannel.nim:226 s=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa id=1 initiator=false
TRC 2021-03-29 16:29:34.734+11:00 Creating new channel                       topics="libp2p mplex" tid=4069991 file=mplex.nim:100 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa id=1 initiator=false name=606165be1f3fee26335b7cfa
TRC 2021-03-29 16:29:34.735+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa data=363036313635...356237636661 msgType=New id=1 initiator=false size=24
TRC 2021-03-29 16:29:34.735+11:00 created channel                            topics="libp2p mplex" tid=4069991 file=mplex.nim:163 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa msgType=New id=1 initiator=false size=24
TRC 2021-03-29 16:29:34.735+11:00 Starting stream handler                    topics="libp2p switch" tid=4069991 file=muxedupgrade.nim:197 conn=16U*o97bUZ:606165bea0cc017a3e6f68ec
TRC 2021-03-29 16:29:34.735+11:00 Starting multistream handler               topics="libp2p multistream" tid=4069991 file=multistream.nim:123 conn=16U*o97bUZ:606165bea0cc017a3e6f68ec handshaked=false
TRC 2021-03-29 16:29:34.735+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.735+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=42
TRC 2021-03-29 16:29:34.735+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=0847202641bf...2b49c5fdf6c0 tagOut=0847202641bf...2b49c5fdf6c0 nonce=3
TRC 2021-03-29 16:29:34.735+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=16 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.735+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=24 data=363036313635...356237636662 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.735+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=363036313635...356237636662 msgType=New id=2 initiator=false size=24
TRC 2021-03-29 16:29:34.735+11:00 Stream created                             topics="libp2p lpstream" tid=4069991 file=lpstream.nim:128 s=606165bea0cc017a3e6f68ed objName=LPChannel dir=In
TRC 2021-03-29 16:29:34.735+11:00 Monitoring for timeout                     topics="libp2p connection" tid=4069991 file=connection.nim:74 s=606165bea0cc017a3e6f68ed timeout=5m0ns
TRC 2021-03-29 16:29:34.736+11:00 BufferStream created                       topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:53 s=606165bea0cc017a3e6f68ed
TRC 2021-03-29 16:29:34.736+11:00 Created new lpchannel                      topics="libp2p mplex" tid=4069991 file=lpchannel.nim:226 s=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb id=2 initiator=false
TRC 2021-03-29 16:29:34.736+11:00 Creating new channel                       topics="libp2p mplex" tid=4069991 file=mplex.nim:100 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb id=2 initiator=false name=606165be1f3fee26335b7cfb
TRC 2021-03-29 16:29:34.736+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb data=363036313635...356237636662 msgType=New id=2 initiator=false size=24
TRC 2021-03-29 16:29:34.736+11:00 created channel                            topics="libp2p mplex" tid=4069991 file=mplex.nim:163 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb msgType=New id=2 initiator=false size=24
TRC 2021-03-29 16:29:34.736+11:00 Starting stream handler                    topics="libp2p switch" tid=4069991 file=muxedupgrade.nim:197 conn=16U*o97bUZ:606165bea0cc017a3e6f68ed
TRC 2021-03-29 16:29:34.736+11:00 Starting multistream handler               topics="libp2p multistream" tid=4069991 file=multistream.nim:123 conn=16U*o97bUZ:606165bea0cc017a3e6f68ed handshaked=false
TRC 2021-03-29 16:29:34.736+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.736+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=38
TRC 2021-03-29 16:29:34.736+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=d07f4c609a57...293c66579bc9 tagOut=d07f4c609a57...293c66579bc9 nonce=4
TRC 2021-03-29 16:29:34.736+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=10 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.736+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=20 data=132f6d756c74...312e302e300a conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.736+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=132f6d756c74...312e302e300a msgType=MsgOut id=1 initiator=false size=20
TRC 2021-03-29 16:29:34.736+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa data=132f6d756c74...312e302e300a msgType=MsgOut id=1 initiator=false size=20
TRC 2021-03-29 16:29:34.736+11:00 pushing data to channel                    topics="libp2p mplex" tid=4069991 file=mplex.nim:176 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa len=20 msgType=MsgOut id=1 initiator=false size=20
TRC 2021-03-29 16:29:34.736+11:00 Pushing data                               topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:80 s=16U*o97bUZ:606165bea0cc017a3e6f68ec data=20
TRC 2021-03-29 16:29:34.736+11:00 pushed data to channel                     topics="libp2p mplex" tid=4069991 file=mplex.nim:178 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa len=20 msgType=MsgOut id=1 initiator=false size=20
TRC 2021-03-29 16:29:34.736+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.736+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=38
TRC 2021-03-29 16:29:34.736+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=7718792175d6...d8a6bf534900 tagOut=7718792175d6...d8a6bf534900 nonce=5
TRC 2021-03-29 16:29:34.736+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=18 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.736+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=20 data=132f6d756c74...312e302e300a conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.737+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=132f6d756c74...312e302e300a msgType=MsgOut id=2 initiator=false size=20
TRC 2021-03-29 16:29:34.737+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb data=132f6d756c74...312e302e300a msgType=MsgOut id=2 initiator=false size=20
TRC 2021-03-29 16:29:34.737+11:00 pushing data to channel                    topics="libp2p mplex" tid=4069991 file=mplex.nim:176 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb len=20 msgType=MsgOut id=2 initiator=false size=20
TRC 2021-03-29 16:29:34.737+11:00 Pushing data                               topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:80 s=16U*o97bUZ:606165bea0cc017a3e6f68ed data=20
TRC 2021-03-29 16:29:34.737+11:00 pushed data to channel                     topics="libp2p mplex" tid=4069991 file=mplex.nim:178 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb len=20 msgType=MsgOut id=2 initiator=false size=20
TRC 2021-03-29 16:29:34.737+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.737+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=47
TRC 2021-03-29 16:29:34.737+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=8434dafdaa74...26c0540bc024 tagOut=8434dafdaa74...26c0540bc024 nonce=6
TRC 2021-03-29 16:29:34.737+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=10 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.737+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=29 data=1c2f7661632f...62657461320a conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.737+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=1c2f7661632f...62657461320a msgType=MsgOut id=1 initiator=false size=29
TRC 2021-03-29 16:29:34.737+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa data=1c2f7661632f...62657461320a msgType=MsgOut id=1 initiator=false size=29
TRC 2021-03-29 16:29:34.737+11:00 pushing data to channel                    topics="libp2p mplex" tid=4069991 file=mplex.nim:176 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa len=29 msgType=MsgOut id=1 initiator=false size=29
TRC 2021-03-29 16:29:34.737+11:00 Pushing data                               topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:80 s=16U*o97bUZ:606165bea0cc017a3e6f68ec data=29
TRC 2021-03-29 16:29:34.737+11:00 Stored muxer                               topics="libp2p connmanager" tid=4069991 file=connmanager.nim:483 muxer=16U*o97bUZ:606165bda0cc017a3e6f68ea handle=true connections=1
TRC 2021-03-29 16:29:34.737+11:00 Triggering connect events                  topics="libp2p connmanager" tid=4069991 file=connmanager.nim:264 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.737+11:00 About to trigger peer events               topics="libp2p connmanager" tid=4069991 file=connmanager.nim:156 peer=16U*o97bUZ
TRC 2021-03-29 16:29:34.737+11:00 triggering peer events                     topics="libp2p connmanager" tid=4069991 file=connmanager.nim:169 peerId=16U*o97bUZ event="(kind: Joined, initiator: true)"
DBG 2021-03-29 16:29:34.737+11:00 created new pubsub peer                    topics="libp2p pubsub" tid=4069991 file=pubsub.nim:290 peer=16U*o97bUZ
TRC 2021-03-29 16:29:34.737+11:00 Dialing (existing)                         topics="libp2p switch" tid=4069991 file=switch.nim:225 peerId=16U*o97bUZ protos="@[\"/vac/waku/relay/2.0.0-beta2\", \"/vac/waku/relay/2.0.0-beta2\"]"
TRC 2021-03-29 16:29:34.737+11:00 Stream created                             topics="libp2p lpstream" tid=4069991 file=lpstream.nim:128 s=606165bea0cc017a3e6f68ee objName=LPChannel dir=Out
TRC 2021-03-29 16:29:34.737+11:00 Monitoring for timeout                     topics="libp2p connection" tid=4069991 file=connection.nim:74 s=606165bea0cc017a3e6f68ee timeout=5m0ns
TRC 2021-03-29 16:29:34.737+11:00 BufferStream created                       topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:53 s=606165bea0cc017a3e6f68ee
TRC 2021-03-29 16:29:34.737+11:00 Created new lpchannel                      topics="libp2p mplex" tid=4069991 file=lpchannel.nim:226 s=16U*o97bUZ:606165bea0cc017a3e6f68ee id=1 initiator=true
TRC 2021-03-29 16:29:34.737+11:00 Creating new channel                       topics="libp2p mplex" tid=4069991 file=mplex.nim:100 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ee id=1 initiator=true name=
TRC 2021-03-29 16:29:34.737+11:00 Opening channel                            topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:62 s=16U*o97bUZ:606165bea0cc017a3e6f68ee conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.737+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=1 msgType=New data=24 encoded=26
TRC 2021-03-29 16:29:34.738+11:00 About to trigger connection events         topics="libp2p connmanager" tid=4069991 file=connmanager.nim:122 peer=16U*o97bUZ
TRC 2021-03-29 16:29:34.738+11:00 triggering connection events               topics="libp2p connmanager" tid=4069991 file=connmanager.nim:124 peer=16U*o97bUZ event=Connected
TRC 2021-03-29 16:29:34.738+11:00 Stream created                             topics="libp2p lpstream" tid=4069991 file=lpstream.nim:128 s=606165bea0cc017a3e6f68ef objName=LPChannel dir=Out
TRC 2021-03-29 16:29:34.738+11:00 Monitoring for timeout                     topics="libp2p connection" tid=4069991 file=connection.nim:74 s=606165bea0cc017a3e6f68ef timeout=5m0ns
TRC 2021-03-29 16:29:34.738+11:00 BufferStream created                       topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:53 s=606165bea0cc017a3e6f68ef
TRC 2021-03-29 16:29:34.738+11:00 Created new lpchannel                      topics="libp2p mplex" tid=4069991 file=lpchannel.nim:226 s=16U*o97bUZ:606165bea0cc017a3e6f68ef id=2 initiator=true
TRC 2021-03-29 16:29:34.738+11:00 Creating new channel                       topics="libp2p mplex" tid=4069991 file=mplex.nim:100 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ef id=2 initiator=true name=
TRC 2021-03-29 16:29:34.738+11:00 Opening channel                            topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:62 s=16U*o97bUZ:606165bea0cc017a3e6f68ef conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.738+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=2 msgType=New data=24 encoded=26
TRC 2021-03-29 16:29:34.738+11:00 add leftovers                              topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:150 s=16U*o97bUZ:606165bea0cc017a3e6f68ec len=19
TRC 2021-03-29 16:29:34.738+11:00 add leftovers                              topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:150 s=16U*o97bUZ:606165bea0cc017a3e6f68ed len=19
TRC 2021-03-29 16:29:34.738+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa bytes=1
TRC 2021-03-29 16:29:34.738+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb bytes=1
TRC 2021-03-29 16:29:34.738+11:00 pushed data to channel                     topics="libp2p mplex" tid=4069991 file=mplex.nim:178 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa len=29 msgType=MsgOut id=1 initiator=false size=29
TRC 2021-03-29 16:29:34.738+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.738+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=34
TRC 2021-03-29 16:29:34.738+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=cc1d91f5711c...4654803283e7 tagOut=cc1d91f5711c...4654803283e7 nonce=7
TRC 2021-03-29 16:29:34.738+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=18 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.738+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=16 data=0f2f69706673...312e302e300a conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.738+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=0f2f69706673...312e302e300a msgType=MsgOut id=2 initiator=false size=16
TRC 2021-03-29 16:29:34.738+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb data=0f2f69706673...312e302e300a msgType=MsgOut id=2 initiator=false size=16
TRC 2021-03-29 16:29:34.738+11:00 pushing data to channel                    topics="libp2p mplex" tid=4069991 file=mplex.nim:176 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb len=16 msgType=MsgOut id=2 initiator=false size=16
TRC 2021-03-29 16:29:34.738+11:00 Pushing data                               topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:80 s=16U*o97bUZ:606165bea0cc017a3e6f68ed data=16
TRC 2021-03-29 16:29:34.738+11:00 pushed data to channel                     topics="libp2p mplex" tid=4069991 file=mplex.nim:178 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb len=16 msgType=MsgOut id=2 initiator=false size=16
TRC 2021-03-29 16:29:34.738+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:34.738+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa bytes=19
TRC 2021-03-29 16:29:34.738+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb bytes=19
TRC 2021-03-29 16:29:34.738+11:00 handle: got request                        topics="libp2p multistream" tid=4069991 file=multistream.nim:135 conn=16U*o97bUZ:606165bea0cc017a3e6f68ec ms=/multistream/1.0.0
TRC 2021-03-29 16:29:34.738+11:00 write msg                                  topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:189 s=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=20
TRC 2021-03-29 16:29:34.739+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=1 msgType=MsgIn data=20 encoded=22
TRC 2021-03-29 16:29:34.739+11:00 handle: got request                        topics="libp2p multistream" tid=4069991 file=multistream.nim:135 conn=16U*o97bUZ:606165bea0cc017a3e6f68ed ms=/multistream/1.0.0
TRC 2021-03-29 16:29:34.739+11:00 write msg                                  topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:189 s=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=20
TRC 2021-03-29 16:29:34.739+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=2 msgType=MsgIn data=20 encoded=22
TRC 2021-03-29 16:29:34.739+11:00 Negotiating stream                         topics="libp2p switch" tid=4069991 file=switch.nim:214 conn=16U*o97bUZ:606165bea0cc017a3e6f68ee protos="@[\"/vac/waku/relay/2.0.0-beta2\", \"/vac/waku/relay/2.0.0-beta2\"]"
TRC 2021-03-29 16:29:34.739+11:00 initiating handshake                       topics="libp2p multistream" tid=4069991 file=multistream.nim:53 conn=16U*o97bUZ:606165bea0cc017a3e6f68ee codec="\x13/multistream/1.0.0\n"
TRC 2021-03-29 16:29:34.739+11:00 write msg                                  topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:189 s=16U*o97bUZ:606165bea0cc017a3e6f68ee conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=20
TRC 2021-03-29 16:29:34.739+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=1 msgType=MsgOut data=20 encoded=22
TRC 2021-03-29 16:29:34.739+11:00 initiating handshake                       topics="libp2p multistream" tid=4069991 file=multistream.nim:53 conn=16U*o97bUZ:606165bea0cc017a3e6f68ef codec="\x13/multistream/1.0.0\n"
TRC 2021-03-29 16:29:34.739+11:00 write msg                                  topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:189 s=16U*o97bUZ:606165bea0cc017a3e6f68ef conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=20
TRC 2021-03-29 16:29:34.739+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=2 msgType=MsgOut data=20 encoded=22
TRC 2021-03-29 16:29:34.739+11:00 add leftovers                              topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:150 s=16U*o97bUZ:606165bea0cc017a3e6f68ec len=28
TRC 2021-03-29 16:29:34.739+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa bytes=1
TRC 2021-03-29 16:29:34.739+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa bytes=28
TRC 2021-03-29 16:29:34.739+11:00 handle: got request                        topics="libp2p multistream" tid=4069991 file=multistream.nim:135 conn=16U*o97bUZ:606165bea0cc017a3e6f68ec ms=/vac/waku/relay/2.0.0-beta2
TRC 2021-03-29 16:29:34.739+11:00 found handler                              topics="libp2p multistream" tid=4069991 file=multistream.nim:164 conn=16U*o97bUZ:606165bea0cc017a3e6f68ec protocol=/vac/waku/relay/2.0.0-beta2
TRC 2021-03-29 16:29:34.739+11:00 write msg                                  topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:189 s=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=29
TRC 2021-03-29 16:29:34.739+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=1 msgType=MsgIn data=29 encoded=31
TRC 2021-03-29 16:29:34.739+11:00 add leftovers                              topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:150 s=16U*o97bUZ:606165bea0cc017a3e6f68ed len=15
TRC 2021-03-29 16:29:34.739+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb bytes=1
TRC 2021-03-29 16:29:34.739+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb bytes=15
TRC 2021-03-29 16:29:34.739+11:00 handle: got request                        topics="libp2p multistream" tid=4069991 file=multistream.nim:135 conn=16U*o97bUZ:606165bea0cc017a3e6f68ed ms=/ipfs/id/1.0.0
TRC 2021-03-29 16:29:34.739+11:00 found handler                              topics="libp2p multistream" tid=4069991 file=multistream.nim:164 conn=16U*o97bUZ:606165bea0cc017a3e6f68ed protocol=/ipfs/id/1.0.0
TRC 2021-03-29 16:29:34.739+11:00 write msg                                  topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:189 s=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=16
TRC 2021-03-29 16:29:34.739+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=2 msgType=MsgIn data=16 encoded=18
TRC 2021-03-29 16:29:34.739+11:00 selecting proto                            topics="libp2p multistream" tid=4069991 file=multistream.nim:57 conn=16U*o97bUZ:606165bea0cc017a3e6f68ee proto=/vac/waku/relay/2.0.0-beta2
TRC 2021-03-29 16:29:34.739+11:00 write msg                                  topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:189 s=16U*o97bUZ:606165bea0cc017a3e6f68ee conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=29
TRC 2021-03-29 16:29:34.739+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=1 msgType=MsgOut data=29 encoded=31
TRC 2021-03-29 16:29:34.739+11:00 selecting proto                            topics="libp2p multistream" tid=4069991 file=multistream.nim:57 conn=16U*o97bUZ:606165bea0cc017a3e6f68ef proto=/ipfs/id/1.0.0
TRC 2021-03-29 16:29:34.739+11:00 write msg                                  topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:189 s=16U*o97bUZ:606165bea0cc017a3e6f68ef conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=16
TRC 2021-03-29 16:29:34.739+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=2 msgType=MsgOut data=16 encoded=18
DBG 2021-03-29 16:29:34.739+11:00 Incoming WakuRelay connection              topics="wakurelay" tid=4069991 file=waku_relay.nim:28
DBG 2021-03-29 16:29:34.740+11:00 starting pubsub read loop                  topics="libp2p pubsubpeer" tid=4069991 file=pubsubpeer.nim:112 conn=16U*o97bUZ:606165bea0cc017a3e6f68ec peer=16U*o97bUZ closed=false
TRC 2021-03-29 16:29:34.740+11:00 waiting for data                           topics="libp2p pubsubpeer" tid=4069991 file=pubsubpeer.nim:117 conn=16U*o97bUZ:606165bea0cc017a3e6f68ec peer=16U*o97bUZ closed=false
TRC 2021-03-29 16:29:34.740+11:00 handling identify request                  topics="libp2p identify" tid=4069991 file=identify.nim:112 conn=16U*o97bUZ:606165bea0cc017a3e6f68ed
TRC 2021-03-29 16:29:34.740+11:00 write msg                                  topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:189 s=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=136
TRC 2021-03-29 16:29:34.740+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=2 msgType=MsgIn data=136 encoded=139
TRC 2021-03-29 16:29:34.740+11:00 exiting identify handler                   topics="libp2p identify" tid=4069991 file=identify.nim:120 conn=16U*o97bUZ:606165bea0cc017a3e6f68ed
TRC 2021-03-29 16:29:34.740+11:00 Closing with EOF                           topics="libp2p lpstream" tid=4069991 file=lpstream.nim:289 s=606165bea0cc017a3e6f68ed
TRC 2021-03-29 16:29:34.740+11:00 Closing channel                            topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:117 s=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=0
TRC 2021-03-29 16:29:34.740+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=2 msgType=CloseIn data=0 encoded=2
TRC 2021-03-29 16:29:34.740+11:00 Closed channel                             topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:133 s=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb len=0
TRC 2021-03-29 16:29:35.373+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=38
TRC 2021-03-29 16:29:35.373+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=fa89a5aff943...6ded719a9577 tagOut=fa89a5aff943...6ded719a9577 nonce=8
TRC 2021-03-29 16:29:35.373+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=9 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.373+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=20 data=132f6d756c74...312e302e300a conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.373+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=132f6d756c74...312e302e300a msgType=MsgIn id=1 initiator=true size=20
TRC 2021-03-29 16:29:35.374+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ee data=132f6d756c74...312e302e300a msgType=MsgIn id=1 initiator=true size=20
TRC 2021-03-29 16:29:35.374+11:00 pushing data to channel                    topics="libp2p mplex" tid=4069991 file=mplex.nim:176 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ee len=20 msgType=MsgIn id=1 initiator=true size=20
TRC 2021-03-29 16:29:35.374+11:00 Pushing data                               topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:80 s=16U*o97bUZ:606165bea0cc017a3e6f68ee data=20
TRC 2021-03-29 16:29:35.374+11:00 pushed data to channel                     topics="libp2p mplex" tid=4069991 file=mplex.nim:178 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ee len=20 msgType=MsgIn id=1 initiator=true size=20
TRC 2021-03-29 16:29:35.374+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.374+11:00 add leftovers                              topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:150 s=16U*o97bUZ:606165bea0cc017a3e6f68ee len=19
TRC 2021-03-29 16:29:35.374+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ee bytes=1
TRC 2021-03-29 16:29:35.374+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ee bytes=19
TRC 2021-03-29 16:29:35.374+11:00 multistream handshake success              topics="libp2p multistream" tid=4069991 file=multistream.nim:67 conn=16U*o97bUZ:606165bea0cc017a3e6f68ee
TRC 2021-03-29 16:29:35.665+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=38
TRC 2021-03-29 16:29:35.665+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=4e86d48b25ce...64a3cbfff70b tagOut=4e86d48b25ce...64a3cbfff70b nonce=9
TRC 2021-03-29 16:29:35.665+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=17 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.665+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=20 data=132f6d756c74...312e302e300a conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.665+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=132f6d756c74...312e302e300a msgType=MsgIn id=2 initiator=true size=20
TRC 2021-03-29 16:29:35.666+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ef data=132f6d756c74...312e302e300a msgType=MsgIn id=2 initiator=true size=20
TRC 2021-03-29 16:29:35.666+11:00 pushing data to channel                    topics="libp2p mplex" tid=4069991 file=mplex.nim:176 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ef len=20 msgType=MsgIn id=2 initiator=true size=20
TRC 2021-03-29 16:29:35.666+11:00 Pushing data                               topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:80 s=16U*o97bUZ:606165bea0cc017a3e6f68ef data=20
TRC 2021-03-29 16:29:35.666+11:00 pushed data to channel                     topics="libp2p mplex" tid=4069991 file=mplex.nim:178 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ef len=20 msgType=MsgIn id=2 initiator=true size=20
TRC 2021-03-29 16:29:35.666+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.666+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=51
TRC 2021-03-29 16:29:35.666+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=d46f966a6e8a...815b836f59a5 tagOut=d46f966a6e8a...815b836f59a5 nonce=10
TRC 2021-03-29 16:29:35.666+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=10 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.666+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=33 data=200a1e080112...2f70726f746f conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.666+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=200a1e080112...2f70726f746f msgType=MsgOut id=1 initiator=false size=33
TRC 2021-03-29 16:29:35.667+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa data=200a1e080112...2f70726f746f msgType=MsgOut id=1 initiator=false size=33
TRC 2021-03-29 16:29:35.667+11:00 pushing data to channel                    topics="libp2p mplex" tid=4069991 file=mplex.nim:176 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa len=33 msgType=MsgOut id=1 initiator=false size=33
TRC 2021-03-29 16:29:35.667+11:00 Pushing data                               topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:80 s=16U*o97bUZ:606165bea0cc017a3e6f68ec data=33
TRC 2021-03-29 16:29:35.667+11:00 pushed data to channel                     topics="libp2p mplex" tid=4069991 file=mplex.nim:178 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa len=33 msgType=MsgOut id=1 initiator=false size=33
TRC 2021-03-29 16:29:35.667+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.667+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=18
TRC 2021-03-29 16:29:35.667+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=88b753ec4fc5...e2881cc5dfbf tagOut=88b753ec4fc5...e2881cc5dfbf nonce=11
TRC 2021-03-29 16:29:35.667+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=20 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.667+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=0 data= conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.667+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data= msgType=CloseOut id=2 initiator=false size=0
TRC 2021-03-29 16:29:35.668+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb data= msgType=CloseOut id=2 initiator=false size=0
TRC 2021-03-29 16:29:35.668+11:00 Pushing EOF                                topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:98 s=16U*o97bUZ:606165bea0cc017a3e6f68ed
TRC 2021-03-29 16:29:35.668+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.668+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=47
TRC 2021-03-29 16:29:35.668+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=9cec6afe9d4a...f91466e33383 tagOut=9cec6afe9d4a...f91466e33383 nonce=12
TRC 2021-03-29 16:29:35.668+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=9 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.668+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=29 data=1c2f7661632f...62657461320a conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.668+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=1c2f7661632f...62657461320a msgType=MsgIn id=1 initiator=true size=29
TRC 2021-03-29 16:29:35.668+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ee data=1c2f7661632f...62657461320a msgType=MsgIn id=1 initiator=true size=29
TRC 2021-03-29 16:29:35.669+11:00 pushing data to channel                    topics="libp2p mplex" tid=4069991 file=mplex.nim:176 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ee len=29 msgType=MsgIn id=1 initiator=true size=29
TRC 2021-03-29 16:29:35.669+11:00 Pushing data                               topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:80 s=16U*o97bUZ:606165bea0cc017a3e6f68ee data=29
TRC 2021-03-29 16:29:35.669+11:00 pushed data to channel                     topics="libp2p mplex" tid=4069991 file=mplex.nim:178 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ee len=29 msgType=MsgIn id=1 initiator=true size=29
TRC 2021-03-29 16:29:35.669+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.669+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=34
TRC 2021-03-29 16:29:35.669+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=b2c1ec14f892...e09e8737024c tagOut=b2c1ec14f892...e09e8737024c nonce=13
TRC 2021-03-29 16:29:35.669+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=17 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.669+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=16 data=0f2f69706673...312e302e300a conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.669+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=0f2f69706673...312e302e300a msgType=MsgIn id=2 initiator=true size=16
TRC 2021-03-29 16:29:35.669+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ef data=0f2f69706673...312e302e300a msgType=MsgIn id=2 initiator=true size=16
TRC 2021-03-29 16:29:35.669+11:00 pushing data to channel                    topics="libp2p mplex" tid=4069991 file=mplex.nim:176 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ef len=16 msgType=MsgIn id=2 initiator=true size=16
TRC 2021-03-29 16:29:35.669+11:00 Pushing data                               topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:80 s=16U*o97bUZ:606165bea0cc017a3e6f68ef data=16
TRC 2021-03-29 16:29:35.669+11:00 add leftovers                              topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:150 s=16U*o97bUZ:606165bea0cc017a3e6f68ef len=19
TRC 2021-03-29 16:29:35.669+11:00 add leftovers                              topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:150 s=16U*o97bUZ:606165bea0cc017a3e6f68ec len=32
TRC 2021-03-29 16:29:35.669+11:00 EOF                                        topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:141 s=16U*o97bUZ:606165bea0cc017a3e6f68ed
TRC 2021-03-29 16:29:35.669+11:00 add leftovers                              topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:150 s=16U*o97bUZ:606165bea0cc017a3e6f68ee len=28
TRC 2021-03-29 16:29:35.669+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ef bytes=1
TRC 2021-03-29 16:29:35.669+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa bytes=1
TRC 2021-03-29 16:29:35.669+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb bytes=0
TRC 2021-03-29 16:29:35.669+11:00 Closing BufferStream                       topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:169 s=16U*o97bUZ:606165bea0cc017a3e6f68ed len=0
TRC 2021-03-29 16:29:35.670+11:00 Closed BufferStream                        topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:202 s=16U*o97bUZ:606165bea0cc017a3e6f68ed
TRC 2021-03-29 16:29:35.670+11:00 Closing connection                         topics="libp2p connection" tid=4069991 file=connection.nim:84 s=16U*o97bUZ:606165bea0cc017a3e6f68ed
TRC 2021-03-29 16:29:35.670+11:00 Closed connection                          topics="libp2p connection" tid=4069991 file=connection.nim:94 s=16U*o97bUZ:606165bea0cc017a3e6f68ed
TRC 2021-03-29 16:29:35.670+11:00 Closing stream                             topics="libp2p lpstream" tid=4069991 file=lpstream.nim:257 s=606165bea0cc017a3e6f68ed objName=LPChannel dir=In
TRC 2021-03-29 16:29:35.670+11:00 Closed stream                              topics="libp2p lpstream" tid=4069991 file=lpstream.nim:261 s=606165bea0cc017a3e6f68ed objName=LPChannel dir=In
TRC 2021-03-29 16:29:35.670+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ee bytes=1
TRC 2021-03-29 16:29:35.670+11:00 pushed data to channel                     topics="libp2p mplex" tid=4069991 file=mplex.nim:178 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ef len=16 msgType=MsgIn id=2 initiator=true size=16
TRC 2021-03-29 16:29:35.670+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.670+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=214
TRC 2021-03-29 16:29:35.670+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=0544d39f6dcc...a2c54d9e6f4f tagOut=0544d39f6dcc...a2c54d9e6f4f nonce=14
TRC 2021-03-29 16:29:35.670+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=17 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.670+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=195 data=c1010a250802...2f302e302e31 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.670+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=c1010a250802...2f302e302e31 msgType=MsgIn id=2 initiator=true size=195
TRC 2021-03-29 16:29:35.670+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ef data=c1010a250802...2f302e302e31 msgType=MsgIn id=2 initiator=true size=195
TRC 2021-03-29 16:29:35.670+11:00 pushing data to channel                    topics="libp2p mplex" tid=4069991 file=mplex.nim:176 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ef len=195 msgType=MsgIn id=2 initiator=true size=195
TRC 2021-03-29 16:29:35.670+11:00 Pushing data                               topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:80 s=16U*o97bUZ:606165bea0cc017a3e6f68ef data=195
TRC 2021-03-29 16:29:35.670+11:00 cleaned up channel                         topics="libp2p mplex" tid=4069991 file=mplex.nim:62 m=16U*o97bUZ:606165bda0cc017a3e6f68ea chann=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb
TRC 2021-03-29 16:29:35.670+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ef bytes=19
TRC 2021-03-29 16:29:35.670+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ec:606165be1f3fee26335b7cfa bytes=32
TRC 2021-03-29 16:29:35.670+11:00 Already closed                             topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:113 s=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb
TRC 2021-03-29 16:29:35.670+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ee bytes=28
TRC 2021-03-29 16:29:35.670+11:00 multistream handshake success              topics="libp2p multistream" tid=4069991 file=multistream.nim:67 conn=16U*o97bUZ:606165bea0cc017a3e6f68ef
TRC 2021-03-29 16:29:35.670+11:00 add leftovers                              topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:150 s=16U*o97bUZ:606165bea0cc017a3e6f68ef len=15
TRC 2021-03-29 16:29:35.670+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ef bytes=1
TRC 2021-03-29 16:29:35.671+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ef bytes=15
TRC 2021-03-29 16:29:35.671+11:00 reading first requested proto              topics="libp2p multistream" tid=4069991 file=multistream.nim:74 conn=16U*o97bUZ:606165bea0cc017a3e6f68ef
TRC 2021-03-29 16:29:35.671+11:00 successfully selected                      topics="libp2p multistream" tid=4069991 file=multistream.nim:76 conn=16U*o97bUZ:606165bea0cc017a3e6f68ef proto=/ipfs/id/1.0.0
TRC 2021-03-29 16:29:35.671+11:00 read data from peer                        topics="libp2p pubsubpeer" tid=4069991 file=pubsubpeer.nim:120 conn=16U*o97bUZ:606165bea0cc017a3e6f68ec peer=16U*o97bUZ closed=false data=0a1e0801121a...2f70726f746f
TRC 2021-03-29 16:29:35.671+11:00 decodeRpcMsg: decoding message             topics="pubsubprotobuf" tid=4069991 file=protobuf.nim:321 msg=0a1e0801121a...2f70726f746f
TRC 2021-03-29 16:29:35.671+11:00 decodeMessages: decoding message           topics="pubsubprotobuf" tid=4069991 file=protobuf.nim:296
TRC 2021-03-29 16:29:35.671+11:00 decodeMessages: no messages found          topics="pubsubprotobuf" tid=4069991 file=protobuf.nim:304
TRC 2021-03-29 16:29:35.671+11:00 decodeSubscriptions: decoding message      topics="pubsubprotobuf" tid=4069991 file=protobuf.nim:251
TRC 2021-03-29 16:29:35.671+11:00 decodeSubscriptions: read subscriptions    topics="pubsubprotobuf" tid=4069991 file=protobuf.nim:256 count=1
TRC 2021-03-29 16:29:35.671+11:00 decodeSubscription: decoding message       topics="pubsubprotobuf" tid=4069991 file=protobuf.nim:235
TRC 2021-03-29 16:29:35.671+11:00 decodeSubscription: read subscribe         topics="pubsubprotobuf" tid=4069991 file=protobuf.nim:240 subscribe=1
TRC 2021-03-29 16:29:35.671+11:00 decodeSubscription: read topic             topics="pubsubprotobuf" tid=4069991 file=protobuf.nim:244 topic=/waku/2/default-waku/proto
TRC 2021-03-29 16:29:35.671+11:00 decodeControl: decoding message            topics="pubsubprotobuf" tid=4069991 file=protobuf.nim:202
TRC 2021-03-29 16:29:35.671+11:00 decoded msg from peer                      topics="libp2p pubsubpeer" tid=4069991 file=pubsubpeer.nim:131 conn=16U*o97bUZ:606165bea0cc017a3e6f68ec peer=16U*o97bUZ closed=false msg="(subscriptions: @[(subscribe: true, topic: \"/waku/2/default-waku/proto\")], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-03-29 16:29:35.671+11:00 processing RPC message                     topics="libp2p pubsub" tid=4069991 file=pubsub.nim:210 msg="(subscriptions: @[(subscribe: true, topic: \"/waku/2/default-waku/proto\")], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))" peer=16U*o97bUZ
TRC 2021-03-29 16:29:35.671+11:00 about to subscribe to topic                topics="libp2p pubsub" tid=4069991 file=pubsub.nim:213 topicId=/waku/2/default-waku/proto peer=16U*o97bUZ subscribe=true
TRC 2021-03-29 16:29:35.671+11:00 peer subscribed to topic                   topics="libp2p gossipsub" tid=4069991 file=gossipsub.nim:247 peer=16U*o97bUZ topic=/waku/2/default-waku/proto
TRC 2021-03-29 16:29:35.671+11:00 gossip peers                               topics="libp2p gossipsub" tid=4069991 file=gossipsub.nim:263 peers=1 topic=/waku/2/default-waku/proto peer=16U*o97bUZ
TRC 2021-03-29 16:29:35.671+11:00 waiting for data                           topics="libp2p pubsubpeer" tid=4069991 file=pubsubpeer.nim:117 conn=16U*o97bUZ:606165bea0cc017a3e6f68ec peer=16U*o97bUZ closed=false
TRC 2021-03-29 16:29:35.671+11:00 Closing with EOF                           topics="libp2p lpstream" tid=4069991 file=lpstream.nim:289 s=606165bea0cc017a3e6f68ed
TRC 2021-03-29 16:29:35.671+11:00 Already closed                             topics="libp2p lpstream" tid=4069991 file=lpstream.nim:291
TRC 2021-03-29 16:29:35.671+11:00 Stream handler done                        topics="libp2p switch" tid=4069991 file=muxedupgrade.nim:206 conn=16U*o97bUZ:606165bea0cc017a3e6f68ed
TRC 2021-03-29 16:29:35.671+11:00 reading first requested proto              topics="libp2p multistream" tid=4069991 file=multistream.nim:74 conn=16U*o97bUZ:606165bea0cc017a3e6f68ee
TRC 2021-03-29 16:29:35.671+11:00 successfully selected                      topics="libp2p multistream" tid=4069991 file=multistream.nim:76 conn=16U*o97bUZ:606165bea0cc017a3e6f68ee proto=/vac/waku/relay/2.0.0-beta2
TRC 2021-03-29 16:29:35.671+11:00 finished handling stream                   topics="libp2p mplex" tid=4069991 file=mplex.nim:117 m=16U*o97bUZ:606165bda0cc017a3e6f68ea chann=16U*o97bUZ:606165bea0cc017a3e6f68ed:606165be1f3fee26335b7cfb
TRC 2021-03-29 16:29:35.671+11:00 initiating identify                        topics="libp2p identify" tid=4069991 file=identify.nim:129 conn=16U*o97bUZ:606165bea0cc017a3e6f68ef
TRC 2021-03-29 16:29:35.671+11:00 add leftovers                              topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:150 s=16U*o97bUZ:606165bea0cc017a3e6f68ef len=194
TRC 2021-03-29 16:29:35.671+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ef bytes=1
TRC 2021-03-29 16:29:35.671+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ef bytes=1
TRC 2021-03-29 16:29:35.671+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ef bytes=193
DBG 2021-03-29 16:29:35.671+11:00 decodeMsg: decoded message                 topics="libp2p identify" tid=4069991 file=identify.nim:94 pubkey=secp25...85]))) addresses=@[/ip4/0.0.0.0/tcp/30303] protocols="@[\"/ipfs/id/1.0.0\", \"/vac/waku/store/2.0.0-beta1\", \"/vac/waku/filter/2.0.0-beta1\", \"/vac/waku/relay/2.0.0-beta2\"]" observable_address=Some(/ip4/122.151.214.125/tcp/51998) proto_version="Some(\"ipfs/0.1.0\")" agent_version="Some(\"nim-libp2p/0.0.1\")"
TRC 2021-03-29 16:29:35.671+11:00 identified remote peer                     tid=4069991 file=upgrade.nim:80 conn=16U*o97bUZ:606165bea0cc017a3e6f68ef peerInfo="(peerId: \"16Uiu2HAmPLe7Mzm8TsYUubgCAW1aJoeFScxrLj8ppHFivPo97bUZ\", addrs: @[\"/ip4/0.0.0.0/tcp/30303\"], protocols: @[\"/ipfs/id/1.0.0\", \"/vac/waku/store/2.0.0-beta1\", \"/vac/waku/filter/2.0.0-beta1\", \"/vac/waku/relay/2.0.0-beta2\"], protoVersion: \"ipfs/0.1.0\", agentVersion: \"nim-libp2p/0.0.1\")"
TRC 2021-03-29 16:29:35.672+11:00 pushed data to channel                     topics="libp2p mplex" tid=4069991 file=mplex.nim:178 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ef len=195 msgType=MsgIn id=2 initiator=true size=195
TRC 2021-03-29 16:29:35.672+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.672+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=18
TRC 2021-03-29 16:29:35.672+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=37fdd75725e4...ea9a427de2e3 tagOut=37fdd75725e4...ea9a427de2e3 nonce=15
TRC 2021-03-29 16:29:35.672+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=19 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.672+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=0 data= conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.672+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data= msgType=CloseIn id=2 initiator=true size=0
TRC 2021-03-29 16:29:35.672+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bea0cc017a3e6f68ef data= msgType=CloseIn id=2 initiator=true size=0
TRC 2021-03-29 16:29:35.672+11:00 Pushing EOF                                topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:98 s=16U*o97bUZ:606165bea0cc017a3e6f68ef
TRC 2021-03-29 16:29:35.672+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.672+11:00 Closing with EOF                           topics="libp2p lpstream" tid=4069991 file=lpstream.nim:289 s=606165bea0cc017a3e6f68ef
TRC 2021-03-29 16:29:35.672+11:00 Closing channel                            topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:117 s=16U*o97bUZ:606165bea0cc017a3e6f68ef conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=0
TRC 2021-03-29 16:29:35.672+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=2 msgType=CloseOut data=0 encoded=2
TRC 2021-03-29 16:29:35.672+11:00 Get new send connection                    topics="libp2p pubsubpeer" tid=4069991 file=pubsubpeer.nim:167 p=16U*o97bUZ newConn=16U*o97bUZ:606165bea0cc017a3e6f68ee
TRC 2021-03-29 16:29:35.672+11:00 sending pubsub message to peer             topics="libp2p pubsub" tid=4069991 file=pubsub.nim:126 peer=16U*o97bUZ msg="(subscriptions: @[(subscribe: true, topic: \"/waku/2/default-waku/proto\")], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-03-29 16:29:35.672+11:00 sending msg to peer                        topics="libp2p pubsubpeer" tid=4069991 file=pubsubpeer.nim:237 peer=16U*o97bUZ rpcMsg="(subscriptions: @[(subscribe: true, topic: \"/waku/2/default-waku/proto\")], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-03-29 16:29:35.672+11:00 encodeRpcMsg: encoding message             topics="pubsubprotobuf" tid=4069991 file=protobuf.nim:308 msg="(subscriptions: @[(subscribe: true, topic: \"/waku/2/default-waku/proto\")], messages: @[], control: (ihave: @[], iwant: @[], graft: @[], prune: @[]))"
TRC 2021-03-29 16:29:35.672+11:00 sending encoded msgs to peer               topics="libp2p pubsubpeer" tid=4069991 file=pubsubpeer.nim:209 conn=16U*o97bUZ:606165bea0cc017a3e6f68ee encoded=0a1e0801121a...2f70726f746f
TRC 2021-03-29 16:29:35.672+11:00 write msg                                  topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:189 s=16U*o97bUZ:606165bea0cc017a3e6f68ee conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=33
TRC 2021-03-29 16:29:35.672+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=1 msgType=MsgOut data=33 encoded=35
DBG 2021-03-29 16:29:35.672+11:00 starting pubsub read loop                  topics="libp2p pubsubpeer" tid=4069991 file=pubsubpeer.nim:112 conn=16U*o97bUZ:606165bea0cc017a3e6f68ee peer=16U*o97bUZ closed=false
TRC 2021-03-29 16:29:35.672+11:00 waiting for data                           topics="libp2p pubsubpeer" tid=4069991 file=pubsubpeer.nim:117 conn=16U*o97bUZ:606165bea0cc017a3e6f68ee peer=16U*o97bUZ closed=false
TRC 2021-03-29 16:29:35.672+11:00 Closed channel                             topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:133 s=16U*o97bUZ:606165bea0cc017a3e6f68ef len=0
TRC 2021-03-29 16:29:35.672+11:00 EOF                                        topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:141 s=16U*o97bUZ:606165bea0cc017a3e6f68ef
TRC 2021-03-29 16:29:35.672+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bea0cc017a3e6f68ef bytes=0
TRC 2021-03-29 16:29:35.672+11:00 Closing BufferStream                       topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:169 s=16U*o97bUZ:606165bea0cc017a3e6f68ef len=0
TRC 2021-03-29 16:29:35.673+11:00 Closed BufferStream                        topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:202 s=16U*o97bUZ:606165bea0cc017a3e6f68ef
TRC 2021-03-29 16:29:35.673+11:00 Closing connection                         topics="libp2p connection" tid=4069991 file=connection.nim:84 s=16U*o97bUZ:606165bea0cc017a3e6f68ef
TRC 2021-03-29 16:29:35.673+11:00 Closed connection                          topics="libp2p connection" tid=4069991 file=connection.nim:94 s=16U*o97bUZ:606165bea0cc017a3e6f68ef
TRC 2021-03-29 16:29:35.673+11:00 Closing stream                             topics="libp2p lpstream" tid=4069991 file=lpstream.nim:257 s=606165bea0cc017a3e6f68ef objName=LPChannel dir=Out
TRC 2021-03-29 16:29:35.673+11:00 Closed stream                              topics="libp2p lpstream" tid=4069991 file=lpstream.nim:261 s=606165bea0cc017a3e6f68ef objName=LPChannel dir=Out
TRC 2021-03-29 16:29:35.673+11:00 cleaned up channel                         topics="libp2p mplex" tid=4069991 file=mplex.nim:62 m=16U*o97bUZ:606165bda0cc017a3e6f68ea chann=16U*o97bUZ:606165bea0cc017a3e6f68ef
TRC 2021-03-29 16:29:35.673+11:00 sent pubsub message to remote              topics="libp2p pubsubpeer" tid=4069991 file=pubsubpeer.nim:211 conn=16U*o97bUZ:606165bea0cc017a3e6f68ee
TRC 2021-03-29 16:29:35.673+11:00 Upgraded outgoing connection               tid=4069991 file=muxedupgrade.nim:93 conn=16U*o97bUZ:606165bca0cc017a3e6f68e9 sconn=16U*o97bUZ:606165bda0cc017a3e6f68ea
DBG 2021-03-29 16:29:35.673+11:00 Dial successful                            topics="libp2p switch" tid=4069991 file=switch.nim:154 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea peerInfo="(peerId: \"16Uiu2HAmPLe7Mzm8TsYUubgCAW1aJoeFScxrLj8ppHFivPo97bUZ\", addrs: @[\"/ip4/0.0.0.0/tcp/30303\"], protocols: @[\"/ipfs/id/1.0.0\", \"/vac/waku/store/2.0.0-beta1\", \"/vac/waku/filter/2.0.0-beta1\", \"/vac/waku/relay/2.0.0-beta2\"], protoVersion: \"ipfs/0.1.0\", agentVersion: \"nim-libp2p/0.0.1\")"
TRC 2021-03-29 16:29:35.673+11:00 Opening stream                             topics="libp2p switch" tid=4069991 file=switch.nim:256 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.673+11:00 Stream created                             topics="libp2p lpstream" tid=4069991 file=lpstream.nim:128 s=606165bfa0cc017a3e6f68f0 objName=LPChannel dir=Out
TRC 2021-03-29 16:29:35.673+11:00 Monitoring for timeout                     topics="libp2p connection" tid=4069991 file=connection.nim:74 s=606165bfa0cc017a3e6f68f0 timeout=5m0ns
TRC 2021-03-29 16:29:35.673+11:00 BufferStream created                       topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:53 s=606165bfa0cc017a3e6f68f0
TRC 2021-03-29 16:29:35.673+11:00 Created new lpchannel                      topics="libp2p mplex" tid=4069991 file=lpchannel.nim:226 s=16U*o97bUZ:606165bfa0cc017a3e6f68f0 id=3 initiator=true
TRC 2021-03-29 16:29:35.673+11:00 Creating new channel                       topics="libp2p mplex" tid=4069991 file=mplex.nim:100 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bfa0cc017a3e6f68f0 id=3 initiator=true name=
TRC 2021-03-29 16:29:35.673+11:00 Opening channel                            topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:62 s=16U*o97bUZ:606165bfa0cc017a3e6f68f0 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:35.673+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=3 msgType=New data=24 encoded=26
TRC 2021-03-29 16:29:35.673+11:00 Negotiating stream                         topics="libp2p switch" tid=4069991 file=switch.nim:214 conn=16U*o97bUZ:606165bfa0cc017a3e6f68f0 protos="@[\"/vac/waku/relay/2.0.0-beta2\"]"
TRC 2021-03-29 16:29:35.673+11:00 initiating handshake                       topics="libp2p multistream" tid=4069991 file=multistream.nim:53 conn=16U*o97bUZ:606165bfa0cc017a3e6f68f0 codec="\x13/multistream/1.0.0\n"
TRC 2021-03-29 16:29:35.673+11:00 write msg                                  topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:189 s=16U*o97bUZ:606165bfa0cc017a3e6f68f0 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=20
TRC 2021-03-29 16:29:35.673+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=3 msgType=MsgOut data=20 encoded=22
TRC 2021-03-29 16:29:35.673+11:00 selecting proto                            topics="libp2p multistream" tid=4069991 file=multistream.nim:57 conn=16U*o97bUZ:606165bfa0cc017a3e6f68f0 proto=/vac/waku/relay/2.0.0-beta2
TRC 2021-03-29 16:29:35.673+11:00 write msg                                  topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:189 s=16U*o97bUZ:606165bfa0cc017a3e6f68f0 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea len=29
TRC 2021-03-29 16:29:35.673+11:00 writing mplex message                      topics="libp2p mplexcoder" tid=4069991 file=coder.nim:78 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea id=3 msgType=MsgOut data=29 encoded=31
TRC 2021-03-29 16:29:36.264+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=38
TRC 2021-03-29 16:29:36.264+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=f83ed94dcb0c...2bf6f36968ba tagOut=f83ed94dcb0c...2bf6f36968ba nonce=16
TRC 2021-03-29 16:29:36.264+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=25 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:36.264+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=20 data=132f6d756c74...312e302e300a conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:36.264+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=132f6d756c74...312e302e300a msgType=MsgIn id=3 initiator=true size=20
TRC 2021-03-29 16:29:36.265+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bfa0cc017a3e6f68f0 data=132f6d756c74...312e302e300a msgType=MsgIn id=3 initiator=true size=20
TRC 2021-03-29 16:29:36.265+11:00 pushing data to channel                    topics="libp2p mplex" tid=4069991 file=mplex.nim:176 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bfa0cc017a3e6f68f0 len=20 msgType=MsgIn id=3 initiator=true size=20
TRC 2021-03-29 16:29:36.265+11:00 Pushing data                               topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:80 s=16U*o97bUZ:606165bfa0cc017a3e6f68f0 data=20
TRC 2021-03-29 16:29:36.265+11:00 pushed data to channel                     topics="libp2p mplex" tid=4069991 file=mplex.nim:178 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bfa0cc017a3e6f68f0 len=20 msgType=MsgIn id=3 initiator=true size=20
TRC 2021-03-29 16:29:36.265+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:36.265+11:00 add leftovers                              topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:150 s=16U*o97bUZ:606165bfa0cc017a3e6f68f0 len=19
TRC 2021-03-29 16:29:36.265+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bfa0cc017a3e6f68f0 bytes=1
TRC 2021-03-29 16:29:36.265+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bfa0cc017a3e6f68f0 bytes=19
TRC 2021-03-29 16:29:36.265+11:00 multistream handshake success              topics="libp2p multistream" tid=4069991 file=multistream.nim:67 conn=16U*o97bUZ:606165bfa0cc017a3e6f68f0
TRC 2021-03-29 16:29:36.556+11:00 readFrame                                  topics="libp2p noise" tid=4069991 file=noise.nim:293 sconn=16U*o97bUZ:606165bca0cc017a3e6f68e9 size=47
TRC 2021-03-29 16:29:36.557+11:00 decryptWithAd                              topics="libp2p noise" tid=4069991 file=noise.nim:155 tagIn=763588a99c11...730503a61c78 tagOut=763588a99c11...730503a61c78 nonce=17
TRC 2021-03-29 16:29:36.557+11:00 read header varint                         topics="libp2p mplexcoder" tid=4069991 file=coder.nim:45 varint=25 conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:36.557+11:00 read data                                  topics="libp2p mplexcoder" tid=4069991 file=coder.nim:48 dataLen=29 data=1c2f7661632f...62657461320a conn=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:36.557+11:00 read message from connection               topics="libp2p mplex" tid=4069991 file=mplex.nim:140 m=16U*o97bUZ:606165bda0cc017a3e6f68ea data=1c2f7661632f...62657461320a msgType=MsgIn id=3 initiator=true size=29
TRC 2021-03-29 16:29:36.557+11:00 Processing channel message                 topics="libp2p mplex" tid=4069991 file=mplex.nim:159 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bfa0cc017a3e6f68f0 data=1c2f7661632f...62657461320a msgType=MsgIn id=3 initiator=true size=29
TRC 2021-03-29 16:29:36.557+11:00 pushing data to channel                    topics="libp2p mplex" tid=4069991 file=mplex.nim:176 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bfa0cc017a3e6f68f0 len=29 msgType=MsgIn id=3 initiator=true size=29
TRC 2021-03-29 16:29:36.557+11:00 Pushing data                               topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:80 s=16U*o97bUZ:606165bfa0cc017a3e6f68f0 data=29
TRC 2021-03-29 16:29:36.557+11:00 pushed data to channel                     topics="libp2p mplex" tid=4069991 file=mplex.nim:178 m=16U*o97bUZ:606165bda0cc017a3e6f68ea channel=16U*o97bUZ:606165bfa0cc017a3e6f68f0 len=29 msgType=MsgIn id=3 initiator=true size=29
TRC 2021-03-29 16:29:36.558+11:00 waiting for data                           topics="libp2p mplex" tid=4069991 file=mplex.nim:129 m=16U*o97bUZ:606165bda0cc017a3e6f68ea
TRC 2021-03-29 16:29:36.558+11:00 add leftovers                              topics="libp2p bufferstream" tid=4069991 file=bufferstream.nim:150 s=16U*o97bUZ:606165bfa0cc017a3e6f68f0 len=28
TRC 2021-03-29 16:29:36.558+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bfa0cc017a3e6f68f0 bytes=1
TRC 2021-03-29 16:29:36.558+11:00 readOnce                                   topics="libp2p mplexchannel" tid=4069991 file=lpchannel.nim:155 s=16U*o97bUZ:606165bfa0cc017a3e6f68f0 bytes=28
TRC 2021-03-29 16:29:36.558+11:00 reading first requested proto              topics="libp2p multistream" tid=4069991 file=multistream.nim:74 conn=16U*o97bUZ:606165bfa0cc017a3e6f68f0
TRC 2021-03-29 16:29:36.558+11:00 successfully selected                      topics="libp2p multistream" tid=4069991 file=multistream.nim:76 conn=16U*o97bUZ:606165bfa0cc017a3e6f68f0 proto=/vac/waku/relay/2.0.0-beta2
INF 2021-03-29 16:29:36.558+11:00 Post peerManager dial                      topics="wakunode" tid=4069991 file=wakunode2.nim:430
INF 2021-03-29 16:29:41.559+11:00 dialPeer                                   topics="wakunode" tid=4069991 file=wakunode2.nim:433 address=/ip4/134.209.139.210/tcp/30303/p2p/16Uiu2HAmPLe7Mzm8TsYUubgCAW1aJoeFScxrLj8ppHFivPo97bUZ
libbacktrace error: no debugging symbols available. Compile with '--debugger:native'.
Traceback (most recent call last, using override)
/home/froyer/src/status-im/nim-waku/waku/v2/node/wakunode2.nim(607) wakunode2
/home/froyer/src/status-im/nim-waku/waku/v2/node/wakunode2.nim(437) setStorePeer
/home/froyer/src/status-im/nim-waku/waku/v2/protocol/waku_store/waku_store.nim(364) setPeer
/home/froyer/src/status-im/nim-waku/vendor/nimbus-build-system/vendor/Nim/lib/system/excpt.nim(610) signalHandler
SIGSEGV: Illegal storage access. (Attempt to read from nil?)
DBG 2021-03-29 16:29:43.701+11:00 UPnP: deleted port mapping                 topics="nat" tid=4069991 file=nat.nim:212 externalPort=60000 internalPort=60000 protocol=TCP
DBG 2021-03-29 16:29:43.708+11:00 UPnP: deleted port mapping                 topics="nat" tid=4069991 file=nat.nim:212 externalPort=60000 internalPort=60000 protocol=UDP
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@jm-clius jm-clius

Labels
bug Something isn't working
Projects
None yet
Milestone
No milestone
Development

Successfully merging a pull request may close this issue.

Fix nil error when starting wakunode2 with store/filter node waku-org/nwaku
2 participants
@D4nte @jm-clius