2022-01-27T23:38:01.973341+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/proc.go:188 2022-01-27T23:38:01.973342+00:00 app[worker.1]: runtime.goexit 2022-01-27T23:38:01.973342+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/asm_amd64.s:1337 2022-01-27T23:38:01.973342+00:00 app[worker.1]: gopkg.in/tucnak/telebot%2ev2.(*Bot).debug 2022-01-27T23:38:01.973342+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/util.go:14 2022-01-27T23:38:01.973343+00:00 app[worker.1]: gopkg.in/tucnak/telebot%2ev2.(*LongPoller).Poll 2022-01-27T23:38:01.973343+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/poller.go:103 2022-01-27T23:38:01.973343+00:00 app[worker.1]: runtime.goexit 2022-01-27T23:38:01.973343+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/asm_amd64.s:1337 2022-01-27T23:38:26.000000+00:00 app[api]: Build succeeded 2022-01-27T23:38:59.876693+00:00 app[worker.1]: [2022/01/27 23:38:59] DEBUG Received update, chat: 2134853711, chatTitle: "", user: 2134853711 2022-01-27T23:38:59.876754+00:00 app[worker.1]: [2022/01/27 23:38:59] TRACE Took 00:00.000 time to complete update processing 2022-01-27T23:38:59.876799+00:00 app[worker.1]: [2022/01/27 23:38:59] TRACE Locking chat 2134853711 2022-01-27T23:38:59.982171+00:00 app[worker.1]: [2022/01/27 23:38:59] TRACE Unlocking chat 2134853711 2022-01-27T23:39:06.180539+00:00 app[worker.1]: [2022/01/27 23:39:06] DEBUG Received update, chat: -1001347061537, chatTitle: "Denemebot", user: 2134853711 2022-01-27T23:39:06.180640+00:00 app[worker.1]: [2022/01/27 23:39:06] TRACE Took 00:00.000 time to complete update processing 2022-01-27T23:39:06.180696+00:00 app[worker.1]: [2022/01/27 23:39:06] TRACE Locking chat -1001347061537 2022-01-27T23:39:06.180743+00:00 app[worker.1]: [2022/01/27 23:39:06] TRACE Restoring machine state for chat (-1001347061537) 2022-01-27T23:39:06.319119+00:00 app[worker.1]: [2022/01/27 23:39:06] ERROR lookupForMachine: error: NOAUTH Authentication required 2022-01-27T23:39:06.319126+00:00 app[worker.1]: [2022/01/27 23:39:06] DEBUG Starting new game, host: 2134853711, hostName: Denizπ’†œπ‘¨π’ˆπ’‚π’“π’•π’‚γ‚·οΈŽ 2022-01-27T23:39:06.319155+00:00 app[worker.1]: [2022/01/27 23:39:06] TRACE Saving machine state for chat (-1001347061537) 2022-01-27T23:39:06.386179+00:00 app[worker.1]: fatal error: sync: RUnlock of unlocked RWMutex 2022-01-27T23:39:06.388315+00:00 app[worker.1]: 2022-01-27T23:39:06.388316+00:00 app[worker.1]: goroutine 31 [running]: 2022-01-27T23:39:06.388322+00:00 app[worker.1]: runtime.throw(0x9cf5a4, 0x21) 2022-01-27T23:39:06.388353+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/panic.go:617 +0x72 fp=0xc0007faf98 sp=0xc0007faf68 pc=0x42e4f2 2022-01-27T23:39:06.388367+00:00 app[worker.1]: sync.throw(0x9cf5a4, 0x21) 2022-01-27T23:39:06.388412+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/panic.go:603 +0x35 fp=0xc0007fafb8 sp=0xc0007faf98 pc=0x42e475 2022-01-27T23:39:06.388414+00:00 app[worker.1]: sync.(*RWMutex).RUnlock(0xc000281058) 2022-01-27T23:39:06.388454+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/sync/rwmutex.go:71 +0x51 fp=0xc0007fafd8 sp=0xc0007fafb8 pc=0x4715a1 2022-01-27T23:39:06.388481+00:00 app[worker.1]: runtime.call32(0x0, 0x9e91f8, 0xc0000329f0, 0x800000008) 2022-01-27T23:39:06.388515+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/asm_amd64.s:519 +0x3b fp=0xc0007fb008 sp=0xc0007fafd8 pc=0x458dab 2022-01-27T23:39:06.388517+00:00 app[worker.1]: panic(0x91b7e0, 0xc0003ef860) 2022-01-27T23:39:06.388556+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/panic.go:522 +0x1b5 fp=0xc0007fb098 sp=0xc0007fb008 pc=0x42e025 2022-01-27T23:39:06.388597+00:00 app[worker.1]: main.newPool.func2(0xa8a100, 0xc000312460, 0xc074e9269304369c, 0xfbc748092, 0xe35740, 0xe35740, 0xe35700) 2022-01-27T23:39:06.388625+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:93 +0x98 fp=0xc0007fb0f8 sp=0xc0007fb098 pc=0x8a3288 2022-01-27T23:39:06.388653+00:00 app[worker.1]: github.com/gomodule/redigo/redis.(*Pool).get(0xc00027aaf0, 0x0, 0x0, 0x140, 0x121, 0x140) 2022-01-27T23:39:06.388682+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/gomodule/redigo@v2.0.0+incompatible/redis/pool.go:308 +0x364 fp=0xc0007fb200 sp=0xc0007fb0f8 pc=0x70ab44 2022-01-27T23:39:06.388699+00:00 app[worker.1]: github.com/gomodule/redigo/redis.(*Pool).Get(0xc00027aaf0, 0xc00011e680, 0xc000224dc0) 2022-01-27T23:39:06.388727+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/gomodule/redigo@v2.0.0+incompatible/redis/pool.go:179 +0x37 fp=0xc0007fb258 sp=0xc0007fb200 pc=0x70a367 2022-01-27T23:39:06.388780+00:00 app[worker.1]: github.com/nuetoban/crocodile-game-bot/storage.(*Redis).SaveMachineState(0xc0003f8028, 0x36, 0xffffff16db1068df, 0xc0002fe830, 0x9, 0xc00033da07, 0x14, 0x7f3f484f, 0xc000318090, 0x27, ...) 2022-01-27T23:39:06.388807+00:00 app[worker.1]: /tmp/build_9c65d8ba/storage/redis.go:39 +0xea fp=0xc0007fb3c8 sp=0xc0007fb258 pc=0x89275a 2022-01-27T23:39:06.388821+00:00 app[worker.1]: github.com/nuetoban/crocodile-game-bot/crocodile.(*Machine).saveState(0xc000685110, 0xc00019e3f0) 2022-01-27T23:39:06.388849+00:00 app[worker.1]: /tmp/build_9c65d8ba/crocodile/crocodile.go:284 +0x17d fp=0xc0007fb598 sp=0xc0007fb3c8 pc=0x7f8e9d 2022-01-27T23:39:06.388861+00:00 app[worker.1]: github.com/nuetoban/crocodile-game-bot/crocodile.(*Machine).saveState-fm(0xc00019e3f0) 2022-01-27T23:39:06.388889+00:00 app[worker.1]: /tmp/build_9c65d8ba/crocodile/crocodile.go:277 +0x34 fp=0xc0007fb5b8 sp=0xc0007fb598 pc=0x7f9604 2022-01-27T23:39:06.388902+00:00 app[worker.1]: github.com/looplab/fsm.(*FSM).afterEventCallbacks(0xc000281020, 0xc00019e3f0) 2022-01-27T23:39:06.388930+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/looplab/fsm@v0.1.0/fsm.go:417 +0xf7 fp=0xc0007fb608 sp=0xc0007fb5b8 pc=0x7f6057 2022-01-27T23:39:06.388943+00:00 app[worker.1]: github.com/looplab/fsm.(*FSM).Event.func1() 2022-01-27T23:39:06.388971+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/looplab/fsm@v0.1.0/fsm.go:309 +0xb6 fp=0xc0007fb650 sp=0xc0007fb608 pc=0x7f6136 2022-01-27T23:39:06.388971+00:00 app[worker.1]: github.com/looplab/fsm.transitionerStruct.transition(...) 2022-01-27T23:39:06.388986+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/looplab/fsm@v0.1.0/fsm.go:354 2022-01-27T23:39:06.389013+00:00 app[worker.1]: github.com/looplab/fsm.(*transitionerStruct).transition(0xe52370, 0xc000281020, 0x0, 0x0) 2022-01-27T23:39:06.389035+00:00 app[worker.1]: :1 +0x65 fp=0xc0007fb660 sp=0xc0007fb650 pc=0x7f6235 2022-01-27T23:39:06.389036+00:00 app[worker.1]: github.com/looplab/fsm.(*FSM).doTransition(...) 2022-01-27T23:39:06.389049+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/looplab/fsm@v0.1.0/fsm.go:339 2022-01-27T23:39:06.389089+00:00 app[worker.1]: github.com/looplab/fsm.(*FSM).Event(0xc000281020, 0x9c06b4, 0x8, 0x0, 0x0, 0x0, 0x0, 0x0) 2022-01-27T23:39:06.389116+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/looplab/fsm@v0.1.0/fsm.go:321 +0x582 fp=0xc0007fb7a0 sp=0xc0007fb660 pc=0x7f5502 2022-01-27T23:39:06.389168+00:00 app[worker.1]: github.com/nuetoban/crocodile-game-bot/crocodile.(*Machine).StartNewGameAndReturnWord(0xc000685110, 0x7f3f484f, 0xc000318090, 0x27, 0xc0002fe830, 0x9, 0x0, 0xc000318090, 0x28, 0x400) 2022-01-27T23:39:06.389215+00:00 app[worker.1]: /tmp/build_9c65d8ba/crocodile/crocodile.go:162 +0x3b7 fp=0xc0007fb870 sp=0xc0007fb7a0 pc=0x7f7a37 2022-01-27T23:39:06.389217+00:00 app[worker.1]: main.startNewGameHandler(0xc000138900) 2022-01-27T23:39:06.389255+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:507 +0x913 fp=0xc0007fbf58 sp=0xc0007fb870 pc=0x89e063 2022-01-27T23:39:06.389268+00:00 app[worker.1]: main.mustLock.func1.1(0xc000138900, 0x9e8978) 2022-01-27T23:39:06.389296+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:337 +0xd3 fp=0xc0007fbfd0 sp=0xc0007fbf58 pc=0x8a3ac3 2022-01-27T23:39:06.389296+00:00 app[worker.1]: runtime.goexit() 2022-01-27T23:39:06.389330+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/asm_amd64.s:1337 +0x1 fp=0xc0007fbfd8 sp=0xc0007fbfd0 pc=0x45aac1 2022-01-27T23:39:06.389331+00:00 app[worker.1]: created by main.mustLock.func1 2022-01-27T23:39:06.389346+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:332 +0x48 2022-01-27T23:39:06.389358+00:00 app[worker.1]: 2022-01-27T23:39:06.389369+00:00 app[worker.1]: goroutine 1 [select]: 2022-01-27T23:39:06.389383+00:00 app[worker.1]: gopkg.in/tucnak/telebot%2ev2.(*Bot).Start(0xc000102480) 2022-01-27T23:39:06.389397+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/bot.go:182 +0x1a6 2022-01-27T23:39:06.389411+00:00 app[worker.1]: main.main() 2022-01-27T23:39:06.389425+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:298 +0xfc0 2022-01-27T23:39:06.389425+00:00 app[worker.1]: 2022-01-27T23:39:06.389440+00:00 app[worker.1]: goroutine 5 [syscall, 1 minutes]: 2022-01-27T23:39:06.389453+00:00 app[worker.1]: os/signal.signal_recv(0x0) 2022-01-27T23:39:06.389468+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/sigqueue.go:139 +0x9c 2022-01-27T23:39:06.389480+00:00 app[worker.1]: os/signal.loop() 2022-01-27T23:39:06.389495+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/os/signal/signal_unix.go:23 +0x22 2022-01-27T23:39:06.389507+00:00 app[worker.1]: created by os/signal.init.0 2022-01-27T23:39:06.389521+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/os/signal/signal_unix.go:29 +0x41 2022-01-27T23:39:06.389521+00:00 app[worker.1]: 2022-01-27T23:39:06.389535+00:00 app[worker.1]: goroutine 7 [chan receive, 1 minutes]: 2022-01-27T23:39:06.389548+00:00 app[worker.1]: main.cleanupHook.func1(0xc0001fd980) 2022-01-27T23:39:06.389563+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:107 +0x34 2022-01-27T23:39:06.389575+00:00 app[worker.1]: created by main.cleanupHook 2022-01-27T23:39:06.389588+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:106 +0x13f 2022-01-27T23:39:06.389589+00:00 app[worker.1]: 2022-01-27T23:39:06.389633+00:00 app[worker.1]: goroutine 8 [select, 1 minutes]: 2022-01-27T23:39:06.389647+00:00 app[worker.1]: database/sql.(*DB).connectionOpener(0xc00010e0c0, 0xa86e60, 0xc000032a00) 2022-01-27T23:39:06.389662+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/database/sql/sql.go:1000 +0xe8 2022-01-27T23:39:06.389674+00:00 app[worker.1]: created by database/sql.OpenDB 2022-01-27T23:39:06.389689+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/database/sql/sql.go:670 +0x15e 2022-01-27T23:39:06.389689+00:00 app[worker.1]: 2022-01-27T23:39:06.389705+00:00 app[worker.1]: goroutine 9 [select, 1 minutes]: 2022-01-27T23:39:06.389731+00:00 app[worker.1]: database/sql.(*DB).connectionResetter(0xc00010e0c0, 0xa86e60, 0xc000032a00) 2022-01-27T23:39:06.389746+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/database/sql/sql.go:1013 +0xfb 2022-01-27T23:39:06.389748+00:00 app[worker.1]: created by database/sql.OpenDB 2022-01-27T23:39:06.389772+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/database/sql/sql.go:671 +0x194 2022-01-27T23:39:06.389774+00:00 app[worker.1]: 2022-01-27T23:39:06.389786+00:00 app[worker.1]: goroutine 56 [sleep]: 2022-01-27T23:39:06.389798+00:00 app[worker.1]: runtime.goparkunlock(...) 2022-01-27T23:39:06.389810+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/proc.go:307 2022-01-27T23:39:06.389812+00:00 app[worker.1]: time.Sleep(0x37e11d600) 2022-01-27T23:39:06.389827+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/time.go:105 +0x159 2022-01-27T23:39:06.389839+00:00 app[worker.1]: main.main.func1() 2022-01-27T23:39:06.389860+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:282 +0x34 2022-01-27T23:39:06.389872+00:00 app[worker.1]: created by main.main 2022-01-27T23:39:06.389885+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:279 +0xf4f 2022-01-27T23:39:06.389897+00:00 app[worker.1]: 2022-01-27T23:39:06.389899+00:00 app[worker.1]: goroutine 55 [IO wait]: 2022-01-27T23:39:06.389924+00:00 app[worker.1]: internal/poll.runtime_pollWait(0x7fbb6cd36f70, 0x72, 0x0) 2022-01-27T23:39:06.389938+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/netpoll.go:182 +0x56 2022-01-27T23:39:06.389984+00:00 app[worker.1]: internal/poll.(*pollDesc).wait(0xc000228018, 0x72, 0x0, 0x0, 0x9bfd9f) 2022-01-27T23:39:06.389998+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/internal/poll/fd_poll_runtime.go:87 +0x9b 2022-01-27T23:39:06.389998+00:00 app[worker.1]: internal/poll.(*pollDesc).waitRead(...) 2022-01-27T23:39:06.390012+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/internal/poll/fd_poll_runtime.go:92 2022-01-27T23:39:06.390050+00:00 app[worker.1]: internal/poll.(*FD).Accept(0xc000228000, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) 2022-01-27T23:39:06.390065+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/internal/poll/fd_unix.go:384 +0x1ba 2022-01-27T23:39:06.390093+00:00 app[worker.1]: net.(*netFD).accept(0xc000228000, 0x97bd00, 0x50, 0xc0003fa0f0) 2022-01-27T23:39:06.390106+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/fd_unix.go:238 +0x42 2022-01-27T23:39:06.390134+00:00 app[worker.1]: net.(*TCPListener).accept(0xc00012c018, 0xc000408700, 0x7fbb72fe3d98, 0x0) 2022-01-27T23:39:06.390149+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/tcpsock_posix.go:139 +0x32 2022-01-27T23:39:06.390162+00:00 app[worker.1]: net.(*TCPListener).AcceptTCP(0xc00012c018, 0x40dd28, 0x30, 0x97bd00) 2022-01-27T23:39:06.390187+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/tcpsock.go:247 +0x48 2022-01-27T23:39:06.390215+00:00 app[worker.1]: net/http.tcpKeepAliveListener.Accept(0xc00012c018, 0x97bd00, 0xc0001a43f0, 0x923ca0, 0xe26ca0) 2022-01-27T23:39:06.390228+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/server.go:3264 +0x2f 2022-01-27T23:39:06.390253+00:00 app[worker.1]: net/http.(*Server).Serve(0xc0000a2680, 0xa85f60, 0xc00012c018, 0x0, 0x0) 2022-01-27T23:39:06.390268+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/server.go:2859 +0x22d 2022-01-27T23:39:06.390284+00:00 app[worker.1]: net/http.(*Server).ListenAndServe(0xc0000a2680, 0xc0000a2680, 0xc00055c7b8) 2022-01-27T23:39:06.390411+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/server.go:2797 +0xe4 2022-01-27T23:39:06.390411+00:00 app[worker.1]: net/http.ListenAndServe(0x9bef46, 0x5, 0x0, 0x0, 0xc000406f40, 0x0) 2022-01-27T23:39:06.390412+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/server.go:3037 +0x74 2022-01-27T23:39:06.390412+00:00 app[worker.1]: created by main.main 2022-01-27T23:39:06.390413+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:277 +0xf37 2022-01-27T23:39:06.390413+00:00 app[worker.1]: 2022-01-27T23:39:06.390413+00:00 app[worker.1]: goroutine 67 [IO wait]: 2022-01-27T23:39:06.390413+00:00 app[worker.1]: internal/poll.runtime_pollWait(0x7fbb6cd37110, 0x72, 0xffffffffffffffff) 2022-01-27T23:39:06.390414+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/netpoll.go:182 +0x56 2022-01-27T23:39:06.390442+00:00 app[worker.1]: internal/poll.(*pollDesc).wait(0xc000130498, 0x72, 0x1800, 0x18a3, 0xffffffffffffffff) 2022-01-27T23:39:06.390457+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/internal/poll/fd_poll_runtime.go:87 +0x9b 2022-01-27T23:39:06.390457+00:00 app[worker.1]: internal/poll.(*pollDesc).waitRead(...) 2022-01-27T23:39:06.390472+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/internal/poll/fd_poll_runtime.go:92 2022-01-27T23:39:06.390511+00:00 app[worker.1]: internal/poll.(*FD).Read(0xc000130480, 0xc00026c000, 0x18a3, 0x18a3, 0x0, 0x0, 0x0) 2022-01-27T23:39:06.390524+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/internal/poll/fd_unix.go:169 +0x19b 2022-01-27T23:39:06.390562+00:00 app[worker.1]: net.(*netFD).Read(0xc000130480, 0xc00026c000, 0x18a3, 0x18a3, 0x203000, 0x0, 0x1896) 2022-01-27T23:39:06.390577+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/fd_unix.go:202 +0x4f 2022-01-27T23:39:06.390605+00:00 app[worker.1]: net.(*conn).Read(0xc0003f8030, 0xc00026c000, 0x18a3, 0x18a3, 0x0, 0x0, 0x0) 2022-01-27T23:39:06.390647+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/net.go:177 +0x69 2022-01-27T23:39:06.390689+00:00 app[worker.1]: crypto/tls.(*atLeastReader).Read(0xc0002f9240, 0xc00026c000, 0x18a3, 0x18a3, 0x2a, 0xa7bae0, 0xc0000c79e0) 2022-01-27T23:39:06.390721+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/crypto/tls/conn.go:761 +0x60 2022-01-27T23:39:06.390766+00:00 app[worker.1]: bytes.(*Buffer).ReadFrom(0xc000059758, 0xa7b9a0, 0xc0002f9240, 0x40b5a5, 0x9352c0, 0x9a2580) 2022-01-27T23:39:06.390788+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/bytes/buffer.go:207 +0xbd 2022-01-27T23:39:06.390841+00:00 app[worker.1]: crypto/tls.(*Conn).readFromUntil(0xc000059500, 0xa7c0e0, 0xc0003f8030, 0x5, 0xc0003f8030, 0xd) 2022-01-27T23:39:06.390877+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/crypto/tls/conn.go:783 +0xf8 2022-01-27T23:39:06.390911+00:00 app[worker.1]: crypto/tls.(*Conn).readRecordOrCCS(0xc000059500, 0x9e9100, 0xc000059638, 0x25) 2022-01-27T23:39:06.390941+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/crypto/tls/conn.go:590 +0x125 2022-01-27T23:39:06.390949+00:00 app[worker.1]: crypto/tls.(*Conn).readRecord(...) 2022-01-27T23:39:06.390967+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/crypto/tls/conn.go:558 2022-01-27T23:39:06.391025+00:00 app[worker.1]: crypto/tls.(*Conn).Read(0xc000059500, 0xc000760000, 0x1000, 0x1000, 0x0, 0x0, 0x0) 2022-01-27T23:39:06.391045+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/crypto/tls/conn.go:1236 +0x137 2022-01-27T23:39:06.391121+00:00 app[worker.1]: bufio.(*Reader).Read(0xc0002a95c0, 0xc00064ba78, 0x9, 0x9, 0xc0000c7dcb, 0xa80fdabb, 0x1) 2022-01-27T23:39:06.391140+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/bufio/bufio.go:223 +0x23e 2022-01-27T23:39:06.391215+00:00 app[worker.1]: io.ReadAtLeast(0xa7b820, 0xc0002a95c0, 0xc00064ba78, 0x9, 0x9, 0x9, 0xc000036500, 0x7ffeffff00000000, 0x3) 2022-01-27T23:39:06.391235+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/io/io.go:310 +0x88 2022-01-27T23:39:06.391243+00:00 app[worker.1]: io.ReadFull(...) 2022-01-27T23:39:06.391261+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/io/io.go:329 2022-01-27T23:39:06.391333+00:00 app[worker.1]: net/http.http2readFrameHeader(0xc00064ba78, 0x9, 0x9, 0xa7b820, 0xc0002a95c0, 0x0, 0x0, 0x9e91e8, 0xc0000c7df8) 2022-01-27T23:39:06.391354+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:1476 +0x88 2022-01-27T23:39:06.391391+00:00 app[worker.1]: net/http.(*http2Framer).ReadFrame(0xc00064ba40, 0xc0002fe8c0, 0x0, 0x0, 0x0) 2022-01-27T23:39:06.391416+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:1734 +0xa1 2022-01-27T23:39:06.391447+00:00 app[worker.1]: net/http.(*http2clientConnReadLoop).run(0xc0000c7fb8, 0x9e8b58, 0xc00055c7b8) 2022-01-27T23:39:06.391473+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:8132 +0x8f 2022-01-27T23:39:06.391489+00:00 app[worker.1]: net/http.(*http2ClientConn).readLoop(0xc000112600) 2022-01-27T23:39:06.391509+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:8060 +0x76 2022-01-27T23:39:06.391526+00:00 app[worker.1]: created by net/http.(*http2Transport).newClientConn 2022-01-27T23:39:06.391544+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:7123 +0x637 2022-01-27T23:39:06.391551+00:00 app[worker.1]: 2022-01-27T23:39:06.391568+00:00 app[worker.1]: goroutine 57 [select]: 2022-01-27T23:39:06.391604+00:00 app[worker.1]: gopkg.in/tucnak/telebot%2ev2.(*MiddlewarePoller).Poll(0xc000406f40, 0xc000102480, 0xc0002a8a80, 0xc00025a180) 2022-01-27T23:39:06.391624+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/poller.go:54 +0x174 2022-01-27T23:39:06.391634+00:00 app[worker.1]: created by gopkg.in/tucnak/telebot%2ev2.(*Bot).Start 2022-01-27T23:39:06.391659+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/bot.go:179 +0xa7 2022-01-27T23:39:06.391661+00:00 app[worker.1]: 2022-01-27T23:39:06.391678+00:00 app[worker.1]: goroutine 58 [select]: 2022-01-27T23:39:06.391736+00:00 app[worker.1]: net/http.(*http2ClientConn).roundTrip(0xc000112600, 0xc00014a600, 0x0, 0x0, 0x0, 0x0) 2022-01-27T23:39:06.391759+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:7534 +0x8cc 2022-01-27T23:39:06.391816+00:00 app[worker.1]: net/http.(*http2Transport).RoundTripOpt(0xc00007cfc0, 0xc00014a600, 0xc0006c1400, 0x6a7306, 0x0, 0xc000022ea0) 2022-01-27T23:39:06.391828+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:6897 +0x159 2022-01-27T23:39:06.391838+00:00 app[worker.1]: net/http.(*http2Transport).RoundTrip(...) 2022-01-27T23:39:06.391855+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:6859 2022-01-27T23:39:06.391893+00:00 app[worker.1]: net/http.http2noDialH2RoundTripper.RoundTrip(0xc00007cfc0, 0xc00014a600, 0xc00025a7e0, 0x5, 0xc000022f28) 2022-01-27T23:39:06.391913+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:8989 +0x3f 2022-01-27T23:39:06.391954+00:00 app[worker.1]: net/http.(*Transport).roundTrip(0xe2e200, 0xc00014a600, 0x10, 0xc0003ef410, 0xc0006c17a0) 2022-01-27T23:39:06.391985+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/transport.go:430 +0xe90 2022-01-27T23:39:06.392022+00:00 app[worker.1]: net/http.(*Transport).RoundTrip(0xe2e200, 0xc00014a600, 0xe2e200, 0x0, 0x0) 2022-01-27T23:39:06.392048+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/roundtrip.go:17 +0x35 2022-01-27T23:39:06.392120+00:00 app[worker.1]: net/http.send(0xc00014a600, 0xa7c180, 0xe2e200, 0x0, 0x0, 0x0, 0xc0003f81b0, 0xc0006c19c8, 0x1, 0x0) 2022-01-27T23:39:06.392140+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/client.go:250 +0x461 2022-01-27T23:39:06.392205+00:00 app[worker.1]: net/http.(*Client).send(0xe35560, 0xc00014a600, 0x0, 0x0, 0x0, 0xc0003f81b0, 0x0, 0x1, 0xc000086a80) 2022-01-27T23:39:06.392224+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/client.go:174 +0xfb 2022-01-27T23:39:06.392267+00:00 app[worker.1]: net/http.(*Client).do(0xe35560, 0xc00014a600, 0x0, 0x0, 0x0) 2022-01-27T23:39:06.392287+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/client.go:641 +0x279 2022-01-27T23:39:06.392304+00:00 app[worker.1]: net/http.(*Client).Do(...) 2022-01-27T23:39:06.392306+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/client.go:509 2022-01-27T23:39:06.392384+00:00 app[worker.1]: net/http.(*Client).Post(0xe35560, 0xc00025a7e0, 0x55, 0x9c405f, 0x10, 0xa7b860, 0xc00027eb70, 0x9be226, 0x1, 0x9c1609) 2022-01-27T23:39:06.392409+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/client.go:769 +0x171 2022-01-27T23:39:06.392482+00:00 app[worker.1]: gopkg.in/tucnak/telebot%2ev2.(*Bot).Raw(0xc000102480, 0x9c1609, 0xa, 0x91e780, 0xc00027eb40, 0x0, 0x0, 0x0, 0x0, 0x0) 2022-01-27T23:39:06.392511+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/api.go:30 +0x201 2022-01-27T23:39:06.392590+00:00 app[worker.1]: gopkg.in/tucnak/telebot%2ev2.(*Bot).getUpdates(0xc000102480, 0x24cf3716, 0x0, 0x12a05f200, 0x0, 0x0, 0x0, 0xc0003fd980, 0x1, 0x4, ...) 2022-01-27T23:39:06.392616+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/api.go:219 +0x1c5 2022-01-27T23:39:06.392646+00:00 app[worker.1]: gopkg.in/tucnak/telebot%2ev2.(*LongPoller).Poll(0xc0003f6630, 0xc000102480, 0xc00013a540, 0xc00025a1e0) 2022-01-27T23:39:06.392666+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/poller.go:100 +0xa8 2022-01-27T23:39:06.392682+00:00 app[worker.1]: created by gopkg.in/tucnak/telebot%2ev2.(*MiddlewarePoller).Poll 2022-01-27T23:39:06.392707+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/poller.go:51 +0xd4 2022-01-27T23:39:06.507023+00:00 heroku[worker.1]: Process exited with status 2 2022-01-27T23:39:07.149005+00:00 heroku[worker.1]: Starting process with command `bin/crocodile-game-bot` 2022-01-27T23:39:06.619026+00:00 heroku[worker.1]: State changed from up to crashed 2022-01-27T23:39:06.636491+00:00 heroku[worker.1]: State changed from crashed to starting 2022-01-27T23:39:07.803697+00:00 heroku[worker.1]: State changed from starting to up 2022-01-27T23:39:08.142189+00:00 app[worker.1]: [2022/01/27 23:39:08]  INFO Loading words 2022-01-27T23:39:08.143283+00:00 app[worker.1]: [2022/01/27 23:39:08]  INFO Readind DB env variables 2022-01-27T23:39:08.143325+00:00 app[worker.1]: [2022/01/27 23:39:08]  INFO Connecting to the database 2022-01-27T23:39:08.174899+00:00 app[worker.1]: [2022/01/27 23:39:08]  INFO Creating games fabric 2022-01-27T23:39:08.174915+00:00 app[worker.1]: [2022/01/27 23:39:08]  INFO Connecting to Telegram API 2022-01-27T23:39:08.353107+00:00 app[worker.1]: [2022/01/27 23:39:08]  INFO Binding handlers 2022-01-27T23:39:08.353241+00:00 app[worker.1]: [2022/01/27 23:39:08]  INFO Starting metrics exporter server 2022-01-27T23:39:08.353268+00:00 app[worker.1]: [2022/01/27 23:39:08]  INFO Starting the bot 2022-01-27T23:39:11.874652+00:00 app[worker.1]: [2022/01/27 23:39:11] DEBUG Received update, chat: -1001347061537, chatTitle: "Denemebot", user: 2134853711 2022-01-27T23:39:11.874691+00:00 app[worker.1]: [2022/01/27 23:39:11] TRACE Took 00:00.000 time to complete update processing 2022-01-27T23:39:11.874733+00:00 app[worker.1]: [2022/01/27 23:39:11] TRACE Locking chat -1001347061537 2022-01-27T23:39:11.874771+00:00 app[worker.1]: [2022/01/27 23:39:11] TRACE Restoring machine state for chat (-1001347061537) 2022-01-27T23:39:12.009421+00:00 app[worker.1]: [2022/01/27 23:39:12] ERROR lookupForMachine: error: NOAUTH Authentication required 2022-01-27T23:39:12.009428+00:00 app[worker.1]: [2022/01/27 23:39:12] DEBUG Starting new game, host: 2134853711, hostName: Denizπ’†œπ‘¨π’ˆπ’‚π’“π’•π’‚γ‚·οΈŽ 2022-01-27T23:39:12.009828+00:00 app[worker.1]: [2022/01/27 23:39:12] TRACE Saving machine state for chat (-1001347061537) 2022-01-27T23:39:12.075339+00:00 app[worker.1]: fatal error: sync: RUnlock of unlocked RWMutex 2022-01-27T23:39:12.077473+00:00 app[worker.1]: 2022-01-27T23:39:12.077477+00:00 app[worker.1]: goroutine 70 [running]: 2022-01-27T23:39:12.077512+00:00 app[worker.1]: runtime.throw(0x9cf5a4, 0x21) 2022-01-27T23:39:12.077564+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/panic.go:617 +0x72 fp=0xc00028cf98 sp=0xc00028cf68 pc=0x42e4f2 2022-01-27T23:39:12.077574+00:00 app[worker.1]: sync.throw(0x9cf5a4, 0x21) 2022-01-27T23:39:12.077623+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/panic.go:603 +0x35 fp=0xc00028cfb8 sp=0xc00028cf98 pc=0x42e475 2022-01-27T23:39:12.077634+00:00 app[worker.1]: sync.(*RWMutex).RUnlock(0xc000136518) 2022-01-27T23:39:12.078080+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/sync/rwmutex.go:71 +0x51 fp=0xc00028cfd8 sp=0xc00028cfb8 pc=0x4715a1 2022-01-27T23:39:12.078134+00:00 app[worker.1]: runtime.call32(0x0, 0x9e91f8, 0xc0003c8030, 0x800000008) 2022-01-27T23:39:12.078169+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/asm_amd64.s:519 +0x3b fp=0xc00028d008 sp=0xc00028cfd8 pc=0x458dab 2022-01-27T23:39:12.078195+00:00 app[worker.1]: panic(0x91b7e0, 0xc0002e06e0) 2022-01-27T23:39:12.078244+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/panic.go:522 +0x1b5 fp=0xc00028d098 sp=0xc00028d008 pc=0x42e025 2022-01-27T23:39:12.078299+00:00 app[worker.1]: main.newPool.func2(0xa8a100, 0xc00009ad20, 0xc074e928008e5ab1, 0xe73ccfe0, 0xe35740, 0xe35740, 0xe35700) 2022-01-27T23:39:12.078343+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:93 +0x98 fp=0xc00028d0f8 sp=0xc00028d098 pc=0x8a3288 2022-01-27T23:39:12.078404+00:00 app[worker.1]: github.com/gomodule/redigo/redis.(*Pool).get(0xc000276af0, 0x0, 0x0, 0x120, 0x11b, 0x120) 2022-01-27T23:39:12.078444+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/gomodule/redigo@v2.0.0+incompatible/redis/pool.go:308 +0x364 fp=0xc00028d200 sp=0xc00028d0f8 pc=0x70ab44 2022-01-27T23:39:12.078471+00:00 app[worker.1]: github.com/gomodule/redigo/redis.(*Pool).Get(0xc000276af0, 0xc000597040, 0xc000618000) 2022-01-27T23:39:12.078515+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/gomodule/redigo@v2.0.0+incompatible/redis/pool.go:179 +0x37 fp=0xc00028d258 sp=0xc00028d200 pc=0x70a367 2022-01-27T23:39:12.078592+00:00 app[worker.1]: github.com/nuetoban/crocodile-game-bot/storage.(*Redis).SaveMachineState(0xc0003ce030, 0x37, 0xffffff16db1068df, 0xc000281540, 0x9, 0xc000314bcc, 0xe, 0x7f3f484f, 0xc0005a84b0, 0x27, ...) 2022-01-27T23:39:12.078629+00:00 app[worker.1]: /tmp/build_9c65d8ba/storage/redis.go:39 +0xea fp=0xc00028d3c8 sp=0xc00028d258 pc=0x89275a 2022-01-27T23:39:12.078648+00:00 app[worker.1]: github.com/nuetoban/crocodile-game-bot/crocodile.(*Machine).saveState(0xc0008ba8f0, 0xc00015bea0) 2022-01-27T23:39:12.078684+00:00 app[worker.1]: /tmp/build_9c65d8ba/crocodile/crocodile.go:284 +0x17d fp=0xc00028d598 sp=0xc00028d3c8 pc=0x7f8e9d 2022-01-27T23:39:12.078701+00:00 app[worker.1]: github.com/nuetoban/crocodile-game-bot/crocodile.(*Machine).saveState-fm(0xc00015bea0) 2022-01-27T23:39:12.078735+00:00 app[worker.1]: /tmp/build_9c65d8ba/crocodile/crocodile.go:277 +0x34 fp=0xc00028d5b8 sp=0xc00028d598 pc=0x7f9604 2022-01-27T23:39:12.078754+00:00 app[worker.1]: github.com/looplab/fsm.(*FSM).afterEventCallbacks(0xc0001364e0, 0xc00015bea0) 2022-01-27T23:39:12.078806+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/looplab/fsm@v0.1.0/fsm.go:417 +0xf7 fp=0xc00028d608 sp=0xc00028d5b8 pc=0x7f6057 2022-01-27T23:39:12.078808+00:00 app[worker.1]: github.com/looplab/fsm.(*FSM).Event.func1() 2022-01-27T23:39:12.078843+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/looplab/fsm@v0.1.0/fsm.go:309 +0xb6 fp=0xc00028d650 sp=0xc00028d608 pc=0x7f6136 2022-01-27T23:39:12.078852+00:00 app[worker.1]: github.com/looplab/fsm.transitionerStruct.transition(...) 2022-01-27T23:39:12.078863+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/looplab/fsm@v0.1.0/fsm.go:354 2022-01-27T23:39:12.078896+00:00 app[worker.1]: github.com/looplab/fsm.(*transitionerStruct).transition(0xe52370, 0xc0001364e0, 0x0, 0x0) 2022-01-27T23:39:12.078933+00:00 app[worker.1]: :1 +0x65 fp=0xc00028d660 sp=0xc00028d650 pc=0x7f6235 2022-01-27T23:39:12.078942+00:00 app[worker.1]: github.com/looplab/fsm.(*FSM).doTransition(...) 2022-01-27T23:39:12.078967+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/looplab/fsm@v0.1.0/fsm.go:339 2022-01-27T23:39:12.079012+00:00 app[worker.1]: github.com/looplab/fsm.(*FSM).Event(0xc0001364e0, 0x9c06b4, 0x8, 0x0, 0x0, 0x0, 0x0, 0x0) 2022-01-27T23:39:12.079049+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/github.com/looplab/fsm@v0.1.0/fsm.go:321 +0x582 fp=0xc00028d7a0 sp=0xc00028d660 pc=0x7f5502 2022-01-27T23:39:12.079128+00:00 app[worker.1]: github.com/nuetoban/crocodile-game-bot/crocodile.(*Machine).StartNewGameAndReturnWord(0xc0008ba8f0, 0x7f3f484f, 0xc0005a84b0, 0x27, 0xc000281540, 0x9, 0x0, 0xc0005a84b0, 0x28, 0x80) 2022-01-27T23:39:12.079156+00:00 app[worker.1]: /tmp/build_9c65d8ba/crocodile/crocodile.go:162 +0x3b7 fp=0xc00028d870 sp=0xc00028d7a0 pc=0x7f7a37 2022-01-27T23:39:12.079172+00:00 app[worker.1]: main.startNewGameHandler(0xc0000a5b00) 2022-01-27T23:39:12.079217+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:507 +0x913 fp=0xc00028df58 sp=0xc00028d870 pc=0x89e063 2022-01-27T23:39:12.079234+00:00 app[worker.1]: main.mustLock.func1.1(0xc0000a5b00, 0x9e8978) 2022-01-27T23:39:12.079273+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:337 +0xd3 fp=0xc00028dfd0 sp=0xc00028df58 pc=0x8a3ac3 2022-01-27T23:39:12.079289+00:00 app[worker.1]: runtime.goexit() 2022-01-27T23:39:12.079322+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/asm_amd64.s:1337 +0x1 fp=0xc00028dfd8 sp=0xc00028dfd0 pc=0x45aac1 2022-01-27T23:39:12.079332+00:00 app[worker.1]: created by main.mustLock.func1 2022-01-27T23:39:12.079352+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:332 +0x48 2022-01-27T23:39:12.079360+00:00 app[worker.1]: 2022-01-27T23:39:12.079370+00:00 app[worker.1]: goroutine 1 [select]: 2022-01-27T23:39:12.079391+00:00 app[worker.1]: gopkg.in/tucnak/telebot%2ev2.(*Bot).Start(0xc000574000) 2022-01-27T23:39:12.079411+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/bot.go:182 +0x1a6 2022-01-27T23:39:12.079424+00:00 app[worker.1]: main.main() 2022-01-27T23:39:12.079450+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:298 +0xfc0 2022-01-27T23:39:12.079462+00:00 app[worker.1]: 2022-01-27T23:39:12.079478+00:00 app[worker.1]: goroutine 5 [syscall]: 2022-01-27T23:39:12.079495+00:00 app[worker.1]: os/signal.signal_recv(0x0) 2022-01-27T23:39:12.079515+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/sigqueue.go:139 +0x9c 2022-01-27T23:39:12.079530+00:00 app[worker.1]: os/signal.loop() 2022-01-27T23:39:12.079545+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/os/signal/signal_unix.go:23 +0x22 2022-01-27T23:39:12.079555+00:00 app[worker.1]: created by os/signal.init.0 2022-01-27T23:39:12.079575+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/os/signal/signal_unix.go:29 +0x41 2022-01-27T23:39:12.079583+00:00 app[worker.1]: 2022-01-27T23:39:12.079611+00:00 app[worker.1]: goroutine 7 [chan receive]: 2022-01-27T23:39:12.079622+00:00 app[worker.1]: main.cleanupHook.func1(0xc0001f9980) 2022-01-27T23:39:12.079636+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:107 +0x34 2022-01-27T23:39:12.079647+00:00 app[worker.1]: created by main.cleanupHook 2022-01-27T23:39:12.079676+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:106 +0x13f 2022-01-27T23:39:12.079677+00:00 app[worker.1]: 2022-01-27T23:39:12.079694+00:00 app[worker.1]: goroutine 8 [select]: 2022-01-27T23:39:12.079717+00:00 app[worker.1]: database/sql.(*DB).connectionOpener(0xc0001000c0, 0xa86e60, 0xc000032a00) 2022-01-27T23:39:12.079740+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/database/sql/sql.go:1000 +0xe8 2022-01-27T23:39:12.079751+00:00 app[worker.1]: created by database/sql.OpenDB 2022-01-27T23:39:12.079784+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/database/sql/sql.go:670 +0x15e 2022-01-27T23:39:12.079785+00:00 app[worker.1]: 2022-01-27T23:39:12.079786+00:00 app[worker.1]: goroutine 9 [select]: 2022-01-27T23:39:12.079817+00:00 app[worker.1]: database/sql.(*DB).connectionResetter(0xc0001000c0, 0xa86e60, 0xc000032a00) 2022-01-27T23:39:12.079841+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/database/sql/sql.go:1013 +0xfb 2022-01-27T23:39:12.079864+00:00 app[worker.1]: created by database/sql.OpenDB 2022-01-27T23:39:12.079866+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/database/sql/sql.go:671 +0x194 2022-01-27T23:39:12.079872+00:00 app[worker.1]: 2022-01-27T23:39:12.079886+00:00 app[worker.1]: goroutine 66 [IO wait]: 2022-01-27T23:39:12.079918+00:00 app[worker.1]: internal/poll.runtime_pollWait(0x7f992ce8e200, 0x72, 0xffffffffffffffff) 2022-01-27T23:39:12.079942+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/netpoll.go:182 +0x56 2022-01-27T23:39:12.079975+00:00 app[worker.1]: internal/poll.(*pollDesc).wait(0xc000574798, 0x72, 0x1800, 0x18a3, 0xffffffffffffffff) 2022-01-27T23:39:12.079995+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/internal/poll/fd_poll_runtime.go:87 +0x9b 2022-01-27T23:39:12.080016+00:00 app[worker.1]: internal/poll.(*pollDesc).waitRead(...) 2022-01-27T23:39:12.080028+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/internal/poll/fd_poll_runtime.go:92 2022-01-27T23:39:12.080082+00:00 app[worker.1]: internal/poll.(*FD).Read(0xc000574780, 0xc000268000, 0x18a3, 0x18a3, 0x0, 0x0, 0x0) 2022-01-27T23:39:12.080101+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/internal/poll/fd_unix.go:169 +0x19b 2022-01-27T23:39:12.080153+00:00 app[worker.1]: net.(*netFD).Read(0xc000574780, 0xc000268000, 0x18a3, 0x18a3, 0x203000, 0x0, 0x1896) 2022-01-27T23:39:12.080182+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/fd_unix.go:202 +0x4f 2022-01-27T23:39:12.080226+00:00 app[worker.1]: net.(*conn).Read(0xc000128008, 0xc000268000, 0x18a3, 0x18a3, 0x0, 0x0, 0x0) 2022-01-27T23:39:12.080248+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/net.go:177 +0x69 2022-01-27T23:39:12.080298+00:00 app[worker.1]: crypto/tls.(*atLeastReader).Read(0xc0005a0960, 0xc000268000, 0x18a3, 0x18a3, 0x2a, 0xa7bae0, 0xc0005839e0) 2022-01-27T23:39:12.080314+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/crypto/tls/conn.go:761 +0x60 2022-01-27T23:39:12.080357+00:00 app[worker.1]: bytes.(*Buffer).ReadFrom(0xc000059758, 0xa7b9a0, 0xc0005a0960, 0x40b5a5, 0x9352c0, 0x9a2580) 2022-01-27T23:39:12.080380+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/bytes/buffer.go:207 +0xbd 2022-01-27T23:39:12.080434+00:00 app[worker.1]: crypto/tls.(*Conn).readFromUntil(0xc000059500, 0xa7c0e0, 0xc000128008, 0x5, 0xc000128008, 0xd) 2022-01-27T23:39:12.080445+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/crypto/tls/conn.go:783 +0xf8 2022-01-27T23:39:12.080477+00:00 app[worker.1]: crypto/tls.(*Conn).readRecordOrCCS(0xc000059500, 0x9e9100, 0xc000059638, 0x5) 2022-01-27T23:39:12.080505+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/crypto/tls/conn.go:590 +0x125 2022-01-27T23:39:12.080506+00:00 app[worker.1]: crypto/tls.(*Conn).readRecord(...) 2022-01-27T23:39:12.080524+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/crypto/tls/conn.go:558 2022-01-27T23:39:12.080626+00:00 app[worker.1]: crypto/tls.(*Conn).Read(0xc000059500, 0xc0001f2000, 0x1000, 0x1000, 0x0, 0x0, 0x0) 2022-01-27T23:39:12.080691+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/crypto/tls/conn.go:1236 +0x137 2022-01-27T23:39:12.080832+00:00 app[worker.1]: bufio.(*Reader).Read(0xc00007df80, 0xc0007a7df8, 0x9, 0x9, 0xc000583dcb, 0xcd405523, 0x1) 2022-01-27T23:39:12.080893+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/bufio/bufio.go:223 +0x23e 2022-01-27T23:39:12.081075+00:00 app[worker.1]: io.ReadAtLeast(0xa7b820, 0xc00007df80, 0xc0007a7df8, 0x9, 0x9, 0x9, 0xc000038a00, 0x7ffeffff00000000, 0x3) 2022-01-27T23:39:12.081138+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/io/io.go:310 +0x88 2022-01-27T23:39:12.081159+00:00 app[worker.1]: io.ReadFull(...) 2022-01-27T23:39:12.081205+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/io/io.go:329 2022-01-27T23:39:12.081379+00:00 app[worker.1]: net/http.http2readFrameHeader(0xc0007a7df8, 0x9, 0x9, 0xa7b820, 0xc00007df80, 0x0, 0x0, 0x9e91e8, 0xc000583df8) 2022-01-27T23:39:12.081442+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:1476 +0x88 2022-01-27T23:39:12.081508+00:00 app[worker.1]: net/http.(*http2Framer).ReadFrame(0xc0007a7dc0, 0xc0002815e0, 0x0, 0x0, 0x0) 2022-01-27T23:39:12.081525+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:1734 +0xa1 2022-01-27T23:39:12.081560+00:00 app[worker.1]: net/http.(*http2clientConnReadLoop).run(0xc000583fb8, 0x9e8b58, 0xc00053ffb8) 2022-01-27T23:39:12.081595+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:8132 +0x8f 2022-01-27T23:39:12.081596+00:00 app[worker.1]: net/http.(*http2ClientConn).readLoop(0xc00055f200) 2022-01-27T23:39:12.081612+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:8060 +0x76 2022-01-27T23:39:12.081621+00:00 app[worker.1]: created by net/http.(*http2Transport).newClientConn 2022-01-27T23:39:12.081648+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:7123 +0x637 2022-01-27T23:39:12.081655+00:00 app[worker.1]: 2022-01-27T23:39:12.081665+00:00 app[worker.1]: goroutine 27 [IO wait]: 2022-01-27T23:39:12.081704+00:00 app[worker.1]: internal/poll.runtime_pollWait(0x7f992ce8e2d0, 0x72, 0x0) 2022-01-27T23:39:12.081724+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/netpoll.go:182 +0x56 2022-01-27T23:39:12.081758+00:00 app[worker.1]: internal/poll.(*pollDesc).wait(0xc00012d018, 0x72, 0x0, 0x0, 0x9bfd9f) 2022-01-27T23:39:12.081778+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/internal/poll/fd_poll_runtime.go:87 +0x9b 2022-01-27T23:39:12.081788+00:00 app[worker.1]: internal/poll.(*pollDesc).waitRead(...) 2022-01-27T23:39:12.081805+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/internal/poll/fd_poll_runtime.go:92 2022-01-27T23:39:12.081857+00:00 app[worker.1]: internal/poll.(*FD).Accept(0xc00012d000, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) 2022-01-27T23:39:12.081882+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/internal/poll/fd_unix.go:384 +0x1ba 2022-01-27T23:39:12.081908+00:00 app[worker.1]: net.(*netFD).accept(0xc00012d000, 0x97bd00, 0x50, 0xc000214000) 2022-01-27T23:39:12.081934+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/fd_unix.go:238 +0x42 2022-01-27T23:39:12.081969+00:00 app[worker.1]: net.(*TCPListener).accept(0xc00057a028, 0xe36520, 0x7f99321396d0, 0x0) 2022-01-27T23:39:12.081986+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/tcpsock_posix.go:139 +0x32 2022-01-27T23:39:12.082016+00:00 app[worker.1]: net.(*TCPListener).AcceptTCP(0xc00057a028, 0x40dd28, 0x30, 0x97bd00) 2022-01-27T23:39:12.082036+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/tcpsock.go:247 +0x48 2022-01-27T23:39:12.082073+00:00 app[worker.1]: net/http.tcpKeepAliveListener.Accept(0xc00057a028, 0x97bd00, 0xc00027a210, 0x923ca0, 0xe26ca0) 2022-01-27T23:39:12.082093+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/server.go:3264 +0x2f 2022-01-27T23:39:12.082138+00:00 app[worker.1]: net/http.(*Server).Serve(0xc0002d4000, 0xa85f60, 0xc00057a028, 0x0, 0x0) 2022-01-27T23:39:12.082162+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/server.go:2859 +0x22d 2022-01-27T23:39:12.082189+00:00 app[worker.1]: net/http.(*Server).ListenAndServe(0xc0002d4000, 0xc0002d4000, 0xc000567fb8) 2022-01-27T23:39:12.082209+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/server.go:2797 +0xe4 2022-01-27T23:39:12.082259+00:00 app[worker.1]: net/http.ListenAndServe(0x9bef46, 0x5, 0x0, 0x0, 0xc0003d2f80, 0x0) 2022-01-27T23:39:12.082284+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/server.go:3037 +0x74 2022-01-27T23:39:12.082294+00:00 app[worker.1]: created by main.main 2022-01-27T23:39:12.082313+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:277 +0xf37 2022-01-27T23:39:12.082320+00:00 app[worker.1]: 2022-01-27T23:39:12.082332+00:00 app[worker.1]: goroutine 28 [sleep]: 2022-01-27T23:39:12.082342+00:00 app[worker.1]: runtime.goparkunlock(...) 2022-01-27T23:39:12.082359+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/proc.go:307 2022-01-27T23:39:12.082375+00:00 app[worker.1]: time.Sleep(0x37e11d600) 2022-01-27T23:39:12.082391+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/runtime/time.go:105 +0x159 2022-01-27T23:39:12.082401+00:00 app[worker.1]: main.main.func1() 2022-01-27T23:39:12.082421+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:282 +0x34 2022-01-27T23:39:12.082431+00:00 app[worker.1]: created by main.main 2022-01-27T23:39:12.082460+00:00 app[worker.1]: /tmp/build_9c65d8ba/bot.go:279 +0xf4f 2022-01-27T23:39:12.082461+00:00 app[worker.1]: 2022-01-27T23:39:12.082471+00:00 app[worker.1]: goroutine 29 [select]: 2022-01-27T23:39:12.082508+00:00 app[worker.1]: gopkg.in/tucnak/telebot%2ev2.(*MiddlewarePoller).Poll(0xc0003d2f80, 0xc000574000, 0xc00027cb40, 0xc0003e0060) 2022-01-27T23:39:12.082538+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/poller.go:54 +0x174 2022-01-27T23:39:12.082539+00:00 app[worker.1]: created by gopkg.in/tucnak/telebot%2ev2.(*Bot).Start 2022-01-27T23:39:12.082557+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/bot.go:179 +0xa7 2022-01-27T23:39:12.082559+00:00 app[worker.1]: 2022-01-27T23:39:12.082574+00:00 app[worker.1]: goroutine 30 [select]: 2022-01-27T23:39:12.082616+00:00 app[worker.1]: net/http.(*http2ClientConn).roundTrip(0xc00055f200, 0xc000146200, 0x0, 0x0, 0x0, 0x0) 2022-01-27T23:39:12.082642+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:7534 +0x8cc 2022-01-27T23:39:12.082687+00:00 app[worker.1]: net/http.(*http2Transport).RoundTripOpt(0xc000592000, 0xc000146200, 0xc0008b3400, 0x6a7306, 0x0, 0xc000594000) 2022-01-27T23:39:12.082704+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:6897 +0x159 2022-01-27T23:39:12.082712+00:00 app[worker.1]: net/http.(*http2Transport).RoundTrip(...) 2022-01-27T23:39:12.082730+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:6859 2022-01-27T23:39:12.082771+00:00 app[worker.1]: net/http.http2noDialH2RoundTripper.RoundTrip(0xc000592000, 0xc000146200, 0xc0003e0600, 0x5, 0xc000594088) 2022-01-27T23:39:12.082780+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/h2_bundle.go:8989 +0x3f 2022-01-27T23:39:12.082827+00:00 app[worker.1]: net/http.(*Transport).roundTrip(0xe2e200, 0xc000146200, 0x10, 0xc0005de360, 0xc0008b37a0) 2022-01-27T23:39:12.082857+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/transport.go:430 +0xe90 2022-01-27T23:39:12.082883+00:00 app[worker.1]: net/http.(*Transport).RoundTrip(0xe2e200, 0xc000146200, 0xe2e200, 0x0, 0x0) 2022-01-27T23:39:12.082903+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/roundtrip.go:17 +0x35 2022-01-27T23:39:12.082972+00:00 app[worker.1]: net/http.send(0xc000146200, 0xa7c180, 0xe2e200, 0x0, 0x0, 0x0, 0xc0003ce768, 0xc0008b39c8, 0x1, 0x0) 2022-01-27T23:39:12.082992+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/client.go:250 +0x461 2022-01-27T23:39:12.083052+00:00 app[worker.1]: net/http.(*Client).send(0xe35560, 0xc000146200, 0x0, 0x0, 0x0, 0xc0003ce768, 0x0, 0x1, 0xc0003f0000) 2022-01-27T23:39:12.083077+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/client.go:174 +0xfb 2022-01-27T23:39:12.083115+00:00 app[worker.1]: net/http.(*Client).do(0xe35560, 0xc000146200, 0x0, 0x0, 0x0) 2022-01-27T23:39:12.083140+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/client.go:641 +0x279 2022-01-27T23:39:12.083148+00:00 app[worker.1]: net/http.(*Client).Do(...) 2022-01-27T23:39:12.083166+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/client.go:509 2022-01-27T23:39:12.083229+00:00 app[worker.1]: net/http.(*Client).Post(0xe35560, 0xc0003e0600, 0x55, 0x9c405f, 0x10, 0xa7b860, 0xc0005714a0, 0x9be226, 0x1, 0x9c1609) 2022-01-27T23:39:12.083247+00:00 app[worker.1]: /tmp/codon/tmp/cache/go1.12.17/go/src/net/http/client.go:769 +0x171 2022-01-27T23:39:12.083318+00:00 app[worker.1]: gopkg.in/tucnak/telebot%2ev2.(*Bot).Raw(0xc000574000, 0x9c1609, 0xa, 0x91e780, 0xc000571470, 0x0, 0x0, 0x0, 0x0, 0x0) 2022-01-27T23:39:12.083335+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/api.go:30 +0x201 2022-01-27T23:39:12.083407+00:00 app[worker.1]: gopkg.in/tucnak/telebot%2ev2.(*Bot).getUpdates(0xc000574000, 0x24cf3717, 0x0, 0x12a05f200, 0x0, 0x0, 0x0, 0xc00010ea80, 0x1, 0x4, ...) 2022-01-27T23:39:12.083424+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/api.go:219 +0x1c5 2022-01-27T23:39:12.083454+00:00 app[worker.1]: gopkg.in/tucnak/telebot%2ev2.(*LongPoller).Poll(0xc00027a780, 0xc000574000, 0xc000790660, 0xc0003e0480) 2022-01-27T23:39:12.083473+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/poller.go:100 +0xa8 2022-01-27T23:39:12.083487+00:00 app[worker.1]: created by gopkg.in/tucnak/telebot%2ev2.(*MiddlewarePoller).Poll 2022-01-27T23:39:12.083504+00:00 app[worker.1]: /tmp/codon/tmp/cache/go-path/pkg/mod/gopkg.in/tucnak/telebot.v2@v2.3.5/poller.go:51 +0xd4 2022-01-27T23:39:12.247900+00:00 heroku[worker.1]: State changed from up to crashed 2022-01-27T23:39:12.196309+00:00 heroku[worker.1]: Process exited with status 2