[tor-bugs] #24502 [Core Tor/Tor]: scheduler_release_channel: Non-fatal assertion

[Tor Bug Tracker & Wiki]

#24502: scheduler_release_channel: Non-fatal assertion
--------------------------+------------------------------------
 Reporter:  toralf        |          Owner:  (none)
     Type:  defect        |         Status:  needs_information
 Priority:  High          |      Milestone:  Tor: 0.3.2.x-final
Component:  Core Tor/Tor  |        Version:  Tor: 0.3.2.6-alpha
 Severity:  Normal        |     Resolution:
 Keywords:  tor-sched     |  Actual Points:
Parent ID:                |         Points:
 Reviewer:                |        Sponsor:
--------------------------+------------------------------------

Comment (by toralf):

 Indeed, here's the missing info :
 {{{
 /tmp/warn.log-Dec 03 01:19:02.000 [warn] Tried to establish rendezvous on
 non-OR circuit with purpose Acting as rendevous (pending)
 /tmp/warn.log-Dec 03 01:20:50.000 [warn] Tried to establish rendezvous on
 non-OR circuit with purpose Acting as rendevous (pending)
 /tmp/warn.log-Dec 03 01:21:51.000 [warn] Tried to establish rendezvous on
 non-OR circuit with purpose Acting as rendevous (pending)
 mr-fox ~ # grep -A 100 sched /tmp/warn*
 /tmp/warn.log:Dec 03 01:07:47.000 [warn] tor_bug_occurred_(): Bug:
 src/or/scheduler.c:631: scheduler_release_channel: Non-fatal assertion
 !(smartlist_pos(channels_pending, chan) == -1) failed. (on Tor
 0.3.2.6-alpha 87012d076ef58bb9)
 /tmp/warn.log:Dec 03 01:07:47.000 [warn] Bug: Non-fatal assertion
 !(smartlist_pos(channels_pending, chan) == -1) failed in
 scheduler_release_channel at src/or/scheduler.c:631. Stack trace: (on Tor
 0.3.2.6-alpha 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Bug:
 /usr/bin/tor(log_backtrace+0x53) [0x55ad3ea64e73] (on Tor 0.3.2.6-alpha
 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Bug:
 /usr/bin/tor(tor_bug_occurred_+0xc5) [0x55ad3ea81b75] (on Tor
 0.3.2.6-alpha 87012d076ef58bb9)
 /tmp/warn.log:Dec 03 01:07:47.000 [warn] Bug:
 /usr/bin/tor(scheduler_release_channel+0x19a) [0x55ad3e99312a] (on Tor
 0.3.2.6-alpha 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Bug:     /usr/bin/tor(+0xc7408)
 [0x55ad3e99f408] (on Tor 0.3.2.6-alpha 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Bug:
 /usr/bin/tor(connection_or_about_to_close+0x4d) [0x55ad3e9f43dd] (on Tor
 0.3.2.6-alpha 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Bug:     /usr/bin/tor(+0x4ff8c)
 [0x55ad3e927f8c] (on Tor 0.3.2.6-alpha 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Bug:     /usr/bin/tor(+0x50788)
 [0x55ad3e928788] (on Tor 0.3.2.6-alpha 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Bug:
 /usr/lib64/libevent-2.1.so.6(+0x2373a) [0x7ff15d09073a] (on Tor
 0.3.2.6-alpha 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Bug:
 /usr/lib64/libevent-2.1.so.6(event_base_loop+0x57f) [0x7ff15d09168f] (on
 Tor 0.3.2.6-alpha 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Bug:
 /usr/bin/tor(do_main_loop+0x24d) [0x55ad3e929f3d] (on Tor 0.3.2.6-alpha
 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Bug:
 /usr/bin/tor(tor_main+0x1c2d) [0x55ad3e92d9bd] (on Tor 0.3.2.6-alpha
 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Bug:     /usr/bin/tor(main+0x28)
 [0x55ad3e9252b8] (on Tor 0.3.2.6-alpha 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Bug:
 /lib64/libc.so.6(__libc_start_main+0xfd) [0x7ff15bc055ad] (on Tor
 0.3.2.6-alpha 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Bug:
 /usr/bin/tor(_start+0x2a) [0x55ad3e92530a] (on Tor 0.3.2.6-alpha
 87012d076ef58bb9)
 /tmp/warn.log:Dec 03 01:07:47.000 [warn] scheduler_bug_occurred(): Bug:
 Channel 431733 in state channel error and scheduler state 3. Num cells on
 cmux: 0. Connection outbuf len: 0. Num pending channels: 127. Channel in
 pending list: no. (on Tor 0.3.2.6-alpha 87012d076ef58bb9)
 /tmp/warn.log-Dec 03 01:07:47.000 [warn] Scheduler asked to release
 channel 431733 but it wasn't in channels_pending
 /tmp/warn.log-Dec 03 01:10:05.000 [warn] Tried to establish rendezvous on
 non-OR circuit with purpose Acting as rendevous (pending)
 /tmp/warn.log-Dec 03 01:11:45.000 [warn] Tried to establish rendezvous on
 non-OR circuit with purpose Acting as rendevous (pending)
 /tmp/warn.log-Dec 03 01:11:47.000 [warn] Tried to establish rendezvous on
 non-OR circuit with purpose Acting as rendevous (pending)
 /tmp/warn.log-Dec 03 01:13:36.000 [warn] Tried to establish rendezvous on
 non-OR circuit with purpose Acting as rendevous (pending)
 /tmp/warn.log-Dec 03 01:16:41.000 [warn] Tried to establish rendezvous on
 non-OR circuit with purpose Acting as rendevous (pending)
 /tmp/warn.log-Dec 03 01:18:19.000 [warn] Tried to establish rendezvous on
 non-OR circuit with purpose Acting as rendevous (pending)
 /tmp/warn.log-Dec 03 01:19:02.000 [warn] Tried to establish rendezvous on
 non-OR circuit with purpose Acting as rendevous (pending)
 }}}
 and FWIW I set the loglevel from warn to notice.

--
Ticket URL: <https://trac.torproject.org/projects/tor/ticket/24502#comment:4>
Tor Bug Tracker & Wiki <https://trac.torproject.org/>
The Tor Project: anonymity online