Loads, then exits without notification [RC5]

[colin1497]

I get no notification of anything on exit, the process just ends. It loads, connects, and starts processing existing torrents to seed, then just exits hard. I've looked for log files, and the only log file that has anything is in \collected torrents and contains:

2015/08/24-10:58:58.000873 1430 Recovering log #3
2015/08/24-10:58:58.000877 1430 Delete type=0 #3
2015/08/24-10:58:58.000877 1430 Delete type=3 #2

Let me know if you want me to do anything else. This is in Windows 10...

[colin1497]

Confirmed same happens with 5-g61899fd build.

[whirm]

@colin1497 have you looked for logs in %APPDATA% ?

Thanks!

[colin1497]

Nothing in the root of %appdata%.

I just tried one start. If I go to %appdata%.tribler and search for files modified today, I see the following:

The log in collected_metadata says:

 2015/08/25-08:44:44.000500 17f8 Recovering log #7
 2015/08/25-08:44:44.000506 17f8 Delete type=0 #7
 2015/08/25-08:44:44.000506 17f8 Delete type=3 #6

The log in collected_torrents says:

 015/08/25-08:44:44.000489 17f8 Recovering log #7
 2015/08/25-08:44:44.000496 17f8 Delete type=0 #7
 2015/08/25-08:44:44.000496 17f8 Delete type=3 #6

[whirm]

I'll be pushing a new RC shortly which fixes the broken logging, could you retry and upload the log?

[colin1497]

Absolutely.

[colin1497]

OK, RC4, this is crazy, same thing, no log in %appdata% (\users\xxxx\appdata\roaming.), and similar stuff in %appdata%.tribler, but I searched \users\xxxx\appdata for * trib * log and found a log in the C:\Users\xxxx\AppData\Roaming\Raptr\ltc folder (http://raptr.com/, comes with AMD video drivers). Raptr is monitoring processes that start to see if they're games to do its overlays with, etc. Anyway, contents of log:

[18:26:10.00696-  2216] tribler.exe     dbglog.cpp(110)       DbgLog_F                       Log for module 'game' (99265) started with process 'tribler.exe' (x86, PID: 1820, CmdLine: "C:\Program Files (x86)\Tribler\tribler.exe" ) - Thu Aug 27 18:26:10 2015
[18:26:10.00695-  2216] tribler.exe     ltc_game.cpp(5226)    DllMain                        Current process is an auto-hook candidate
[18:26:10.00696-  2216] tribler.exe     ltc_game.cpp(2081)    StartAutoHookMonitoring        Auto-hook monitoring started
[18:26:10.00696-  2216] tribler.exe     common.cpp(519)       CreateGlobalEvent              Global event "Global\ltc_load_game_event_1820l" created, with manual reset.
[18:26:10.00696-  2216] tribler.exe     common.cpp(519)       CreateGlobalEvent              Global event "Global\ltc_unload_game_event_1820l" created, with manual reset.
[18:26:10.00696-  2216] tribler.exe     dbglog.cpp(206)       OpenGlobalEvent                Global event "Global\ltc_shutdown_event" opened.
[18:26:10.00697-  9948] tribler.exe     autohook.cpp(265)     AutoHookManager::_WatchForQuitOrTimeoutThread Watching
[18:26:10.00697-  7108] tribler.exe     ltc_game.cpp(5035)    MainThread                     MainThread executing...
[18:26:10.00697-  7108] tribler.exe     console.h(158)        CConsole::Output               CONSOLE: Console logging has been enabled.
[18:26:10.00697-  7108] tribler.exe     console.h(158)        CConsole::Output               CONSOLE: Added alias [sta:"StartRecorder"]
[18:26:10.00698-  7108] tribler.exe     console.h(158)        CConsole::Output               CONSOLE: Added alias [stp:"StopRecorder"]
[18:26:10.00698-  7108] tribler.exe     console.h(158)        CConsole::Output               CONSOLE: Added alias [slsk:"SetLiveStreamKey live_37135925_lD9UDVtrXfCT8yf0kHeWdVhaITG9oy"]
[18:26:10.00698-  7108] tribler.exe     console.h(158)        CConsole::Output               CONSOLE: Added alias [srl:"RecorderLog C:\Users\Randal\Desktop\ffmpeg.log"]
[18:26:10.00698-  7108] tribler.exe     console.h(158)        CConsole::Output               CONSOLE: Added alias [twitchtest2:"BroadcasterDebug 1;GetTwitchAuthToken ql7dn49ya6oalq5xux4s33a16n3e7sv RaptrRandal raptrtest"]
[18:26:10.00698-  7108] tribler.exe     console.h(158)        CConsole::Output               CONSOLE: Added alias [twitchtest:"BroadcasterDebug 1;SetTwitchAuthToken 1eduwz3hdp3yprib6vhpr5c64go2nv3;StartBroadcast"]
[18:26:10.00698-  7108] tribler.exe     console.h(158)        CConsole::Output               CONSOLE: Added alias [gfastfb:"GetFramebufferAsSizeFastBench 1"]
[18:26:10.00698-  7108] tribler.exe     console.h(158)        CConsole::Output               CONSOLE: Added alias [dt1:"D3D11FastDumpTest 1"]
[18:26:10.00698-  7108] tribler.exe     console.h(158)        CConsole::Output               CONSOLE: Console initialised.
[18:26:10.00703-  7108] tribler.exe     overlayui.cpp(1128)   OverlayUI_InitializeConsole    This game has no on-load flags.
[18:26:10.00704-  7240] tribler.exe     ltc_game.cpp(2542)    ModuleScanThread               ModuleScanThread executing...
[18:26:10.00704-  7240] tribler.exe     ltc_game.cpp(2549)    ModuleScanThread               Process File Name:tribler.exe
[18:26:10.00704-  7240] tribler.exe     ltc_game.cpp(2652)    ModuleScanThread               Found Graphics Module:dxgi.dll
[18:26:10.00704-  7240] tribler.exe     com_hooks.cpp(153)    DirectXGI                      Installing dxgi.dll hooks...
[18:26:10.00844-  7108] tribler.exe     ltc_game.cpp(3700)    EnumWindowsPrep                Window [002C0714] has been input-enabled.
[18:26:10.00879-  7108] tribler.exe     ltc_game.cpp(3700)    EnumWindowsPrep                Window [000C075C] has been input-enabled.
[18:26:10.00881-  7108] tribler.exe     ltc_game.cpp(3700)    EnumWindowsPrep                Window [0033072E] has been input-enabled.
[18:26:10.00882-  7108] tribler.exe     ltc_game.cpp(5087)    MainThread                     Waiting for shutdown event (00000C24) or unload event...
[18:26:28.00062-  9892] tribler.exe     ../common/crash.h(122) CCrashMgr::OnDumped            *CRASH!* ACCESS_VIOLATION(C0000005) exception at address 0x7233B9F0(MSVCR90.dll+3B9F0), while attempting to read memory at 0x00000020
[18:26:28.00062-  9892] tribler.exe     ../common/crash.h(144) CCrashMgr::OnDumped            Sending minidump...
[18:26:29.00451-  9892] tribler.exe     ../common/crash.h(152) CCrashMgr::OnDumped            Crash report generated and submitted to server. Identifier: aab96627-0985-4e81-9152-97ec9ce11d81.

Interestingly, I killed Raptr and I at least got a "tribler.exe has stopped working" dialog when it died, but still no log file.

[whirm]

Could you disable this thing, reboot and try again with it disabled?

Thanks!

[colin1497]

Sure. No change. Exactly the same behavior as when I ran it after exiting the raptr app:

[whirm]

Also, the log you pasted is not Tribler's It's probably one generated by this Raptr app for every executable.

Tribler's log should be on: %APPDATA%/roaming/Tribler.exe.log double check that it is not there. If it's not, could you temporarily rename .Tribler to something else and see if Tribler starts correctly with a clean slate?

Thanks!

[colin1497]

Yeah, I'm aware that the log I posted was the Raptr log, I just thought it was moderately interesting and it did at least trap the fact that tribler had crashed. I've confirmed after each start the non-existence of tribler.exe.log in \users\xxxx\appdata\roaming. It was actually there after the first RC, I believe, but hasn't been there for these builds.

With a clean .tribler directory (renaming to .tribler.old), tribler starts without problems. Of course, annoyingly, I now have an empty download window and my channel is gone.

I went to my own channel (which I no longer would control with a clean install) and selected a torrent that I know I have on my hard drive. I unchecked the top box to select open downloading with anonymous seeding. Tribler correctly got the torrent, identified, checked. Instead of seeding, it then said it was downloading, 0.0%, anonymous. So far it hasn't actually modified the file in my torrents directory, so I think maybe it really is seeding? Hard to be sure.

At no point in this process is a log file created in \users\xxxx\appdata\roaming, or anywhere else on my boot drive. From the drive's root and an administrator prompt I have repeatedly done:

dir tribler.exe.log /a/s

and received "File Not Found"

[colin1497]

Also, it won't save my changes to the default download location. Every restart I get it reset to:

C:\Users\xxxx\Downloads\TriblerDownloads

It did save the changes I made on the anonimity page. And it should be the anonymity page, I believe. :)

[colin1497]

OK, it's definitely trying to download the torrent through the anonymous network (recall that I said download non-anonymously). I started getting download traffic. I picked a very large torrent and it says it's .1% complete. The time stamp on the file hasn't changed at all, but there is disk activity.

(I realize that this is all unrelated to the original issue)

And.... I now have a .log file. It's empty, but it's been created.

08/28/2015 01:55 PM 0 tribler.exe.log

[colin1497]

OK, since things clearly weren't working right, I shut tribler down and got a crash and notification to check the log, which is there:

Traceback (most recent call last): File "Tribler\Main\vwxGUI\SearchGridManager.pyo", line 606, in _do_gui_callback File "Tribler\Main\vwxGUI\list_details.pyo", line 935, in OnRefresh File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func File "Tribler\Main\vwxGUI\list_details.pyo", line 1300, in _Refresh File "wx_controls.pyo", line 4594, in SetItemColumnImage PyAssertionError: C++ assertion "id >= 0 && id < GetItemCount()" failed at ....\src\msw\listctrl.cpp(771) in wxListCtrl::SetItem(): invalid item index in SetItem Traceback (most recent call last): File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func

File "Tribler\Main\vwxGUI\list_body.pyo", line 442, in OnMouse

File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func

File "Tribler\Main\vwxGUI\list_body.pyo", line 455, in OnClick

File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func

File "Tribler\Main\vwxGUI\list_body.pyo", line 719, in OnExpand

File "Tribler\Main\vwxGUI\list.pyo", line 1674, in OnExpand

File "Tribler\Main\vwxGUI\list_details.pyo", line 1073, in setTorrent

File "Tribler\Main\vwxGUIinit.pyo", line 128, in invoke_func

File "Tribler\Main\vwxGUI\list_details.pyo", line 179, in setTorrent

File "Tribler\Main\vwxGUIinit.pyo", line 128, in invoke_func

File "Tribler\Main\vwxGUI\list_details.pyo", line 249, in showTorrent

File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func

File "Tribler\Main\vwxGUI\list_details.pyo", line 1300, in _Refresh

File "wx_controls.pyo", line 4594, in SetItemColumnImage

wx._core.PyAssertionError: C++ assertion "id >= 0 && id < GetItemCount()" failed at ....\src\msw\listctrl.cpp(771) in wxListCtrl::SetItem(): invalid item index in SetItem Traceback (most recent call last): File "Tribler\Main\vwxGUI\SearchGridManager.pyo", line 606, in _do_gui_callback File "Tribler\Main\vwxGUI\list_details.pyo", line 935, in OnRefresh File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func File "Tribler\Main\vwxGUI\list_details.pyo", line 1300, in _Refresh File "wx_controls.pyo", line 4594, in SetItemColumnImage PyAssertionError: C++ assertion "id >= 0 && id < GetItemCount()" failed at ....\src\msw\listctrl.cpp(771) in wxListCtrl::SetItem(): invalid item index in SetItem Traceback (most recent call last): File "Tribler\Main\vwxGUI\SearchGridManager.pyo", line 606, in _do_gui_callback File "Tribler\Main\vwxGUI\list_details.pyo", line 935, in OnRefresh File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func File "Tribler\Main\vwxGUI\list_details.pyo", line 1300, in _Refresh File "wx_controls.pyo", line 4594, in SetItemColumnImage PyAssertionError: C++ assertion "id >= 0 && id < GetItemCount()" failed at ....\src\msw\listctrl.cpp(771) in wxListCtrl::SetItem(): invalid item index in SetItem Traceback (most recent call last): File "Tribler\Main\vwxGUI\SearchGridManager.pyo", line 606, in _do_gui_callback File "Tribler\Main\vwxGUI\list_details.pyo", line 935, in OnRefresh File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func File "Tribler\Main\vwxGUI\list_details.pyo", line 1300, in _Refresh File "wx_controls.pyo", line 4594, in SetItemColumnImage PyAssertionError: C++ assertion "id >= 0 && id < GetItemCount()" failed at ....\src\msw\listctrl.cpp(771) in wxListCtrl::SetItem(): invalid item index in SetItem Traceback (most recent call last): File "Tribler\Main\vwxGUI\SearchGridManager.pyo", line 606, in _do_gui_callback File "Tribler\Main\vwxGUI\list_details.pyo", line 935, in OnRefresh File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func File "Tribler\Main\vwxGUI\list_details.pyo", line 1300, in _Refresh File "wx_controls.pyo", line 4594, in SetItemColumnImage PyAssertionError: C++ assertion "id >= 0 && id < GetItemCount()" failed at ....\src\msw\listctrl.cpp(771) in wxListCtrl::SetItem(): invalid item index in SetItem Traceback (most recent call last): File "Tribler\Main\vwxGUI\SearchGridManager.pyo", line 606, in _do_gui_callback File "Tribler\Main\vwxGUI\list_details.pyo", line 935, in OnRefresh File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func File "Tribler\Main\vwxGUI\list_details.pyo", line 1300, in _Refresh File "wx_controls.pyo", line 4594, in SetItemColumnImage PyAssertionError: C++ assertion "id >= 0 && id < GetItemCount()" failed at ....\src\msw\listctrl.cpp(771) in wxListCtrl::SetItem(): invalid item index in SetItem Traceback (most recent call last): File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func

File "Tribler\Main\vwxGUI\list_body.pyo", line 442, in OnMouse

File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func

File "Tribler\Main\vwxGUI\list_body.pyo", line 455, in OnClick

File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func

File "Tribler\Main\vwxGUI\list_body.pyo", line 719, in OnExpand

File "Tribler\Main\vwxGUI\list.pyo", line 1674, in OnExpand

File "Tribler\Main\vwxGUI\list_details.pyo", line 1073, in setTorrent

File "Tribler\Main\vwxGUIinit.pyo", line 128, in invoke_func

File "Tribler\Main\vwxGUI\list_details.pyo", line 179, in setTorrent

File "Tribler\Main\vwxGUIinit.pyo", line 128, in invoke_func

File "Tribler\Main\vwxGUI\list_details.pyo", line 249, in showTorrent

File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func

File "Tribler\Main\vwxGUI\list_details.pyo", line 1300, in _Refresh

File "wx_controls.pyo", line 4594, in SetItemColumnImage

wx._core.PyAssertionError: C++ assertion "id >= 0 && id < GetItemCount()" failed at ....\src\msw\listctrl.cpp(771) in wxListCtrl::SetItem(): invalid item index in SetItem Traceback (most recent call last): File "Tribler\Main\vwxGUI\SearchGridManager.pyo", line 606, in _do_gui_callback File "Tribler\Main\vwxGUI\list_details.pyo", line 935, in OnRefresh File "Tribler\Main\vwxGUIinit.pyo", line 119, in invoke_func File "Tribler\Main\vwxGUI\list_details.pyo", line 1300, in _Refresh File "wx_controls.pyo", line 4594, in SetItemColumnImage PyAssertionError: C++ assertion "id >= 0 && id < GetItemCount()" failed at ....\src\msw\listctrl.cpp(771) in wxListCtrl::SetItem(): invalid item index in SetItem

[Edited by @whirm: shorten log]

[whirm]

@colin1497 Could you please have a go a this build?

http://jenkins.tribler.org/job/Publish_tribler_next/145/artifact/Tribler_6.5.0-rc4-27-g3a7cd10.exe

It contains fixes relevant to your issues. Thanks!

[colin1497]

Test 1: Restoring my .tribler.old directory to .tribler

Application crashed like before. From Event Viewer:

Faulting application name: tribler.exe, version: 0.0.0.0, time stamp: 0x55ddccf1 Faulting module name: MSVCR90.dll, version: 9.0.30729.9158, time stamp: 0x554b0726 Exception code: 0xc0000005 Fault offset: 0x0003b9f0 Faulting process id: 0x16f4 Faulting application start time: 0x01d0e673dd73b881 Faulting application path: C:\Program Files (x86)\Tribler\tribler.exe Faulting module path: C:\WINDOWS\WinSxS\x86_microsoft.vc90.crt_1fc8b3b9a1e18e3b_9.0.30729.9158_none_5091b51ebcb97cdc\MSVCR90.dll Report Id: 68a14534-964a-41a6-9df1-42659d8df3b4 Faulting package full name: Faulting package-relative application ID:

[colin1497]

Test 2a: back to my new, clean .Tribler directory, start seeding previously downloaded torrent, click link

• Initially started torrent from magnet link from Firefox, got an error that said to check my tribler.exe.log file. Contents of file:

  Unable to load logging config from 'logger.conf' file: No section: 'formatters'

• After clicking through error, torrent was ready to start, but I cancelled.

Test 2b: back to my new, clean .Tribler directory, start seeding previously downloaded torrent, copy link

• pasted magnet of existing torrent into search box, select public download, anonymous seed, 3 hops.
• Torrent starts checking, checks to 100%, changes to downloading 0.0%, anonymous, 3 hops
• Speed is very slow (3-4kBps) so I assume it really is downloading through anonymous network
• I still see a list of peers on the peers tab. This seems odd, like I have a combination between open download and anonymous download?
• Waited several minutes, speed increased greatly (to 500kBps briefly), one of my anon links on the network graph page shows 50MB downloaded, so I do think it's downloading the torrent anon
• date stamp on the file for the torrent never changed
• close tribler, tribler crashes. Tribler.exe.log as follows:

Unable to load logging config from 'logger.conf' file: No section: 'formatters' ERROR 1441305093.69 log:443 Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None

Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None ERROR 1441305093.69 log:443 Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None

Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None ERROR 1441305093.69 log:443 Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None

Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None ERROR 1441305093.69 log:443 Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None

Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None ERROR 1441305094.45 log:443 Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None

Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None ERROR 1441305094.45 log:443 Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None

Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None

From event viewer:

Faulting application name: tribler.exe, version: 0.0.0.0, time stamp: 0x55ddccf1 Faulting module name: wxmsw28uh_core_vc.dll, version: 2.8.12.1, time stamp: 0x4e2114c3 Exception code: 0xc0000005 Fault offset: 0x000560d8 Faulting process id: 0x257c Faulting application start time: 0x01d0e6752432ebe6 Faulting application path: C:\Program Files (x86)\Tribler\tribler.exe Faulting module path: C:\Program Files (x86)\Tribler\wxmsw28uh_core_vc.dll Report Id: 1ad2e3e8-f7f0-429c-981f-0f4a73818516 Faulting package full name: Faulting package-relative application ID:

[colin1497]

Test 3 Try seeding a different previously downloaded torrent, this time a bit smaller download

• restart tribler, remove download from test 2, delete .log file, restart tribler again
• paste magnet link into search
• again, deselect download anon
• leave selected anon seeding
• start download
• checking...checking....checking (anonymous: No) 100%
• anonymous: yes (3 hops), checking 0.0% (slightly different behavior from Test 2 above?)
• No progress for several minutes then relatively quickly to 100% and seeding (anon, 3 hops)

[colin1497]

Testing RC5 changes

Repeat Test 2b: back to my relatively clean .Tribler directory (2 anon seeds going), start seeding previously downloaded torrent (large, about 17GB):

• pasted magnet of existing torrent into search box, select public download, anonymous seed, 3 hops.
• Torrent starts checking, checks to 100%, changes to downloading 0.0%, anonymous, 3 hops
• Speed is very slow (3-4kBps) so I assume it really is downloading through anonymous network, looking at the network graph page, it seems that there is data coming from the anon peers
• I still see a list of peers on the peers tab. This seems odd, like I have a combination between open download and anonymous download?
• Waited several minutes
• date stamp on the file for the torrent never changed
• close tribler, tribler crashes. Tribler.exe.log as follows:

ERROR 1441644854.46 log:443 Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None

Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None ERROR 1441644854.47 log:443 Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None

Unhandled Error Traceback (most recent call last): File "twisted\python\log.pyo", line 73, in callWithContext

File "twisted\python\context.pyo", line 118, in callWithContext

File "twisted\python\context.pyo", line 81, in callWithContext

File "twisted\internet\selectreactor.pyo", line 151, in _doReadOrWrite

--- --- File "twisted\internet\udp.pyo", line 234, in doRead

File "Tribler\community\tunnel\Socks5\server.pyo", line 64, in datagramReceived

File "Tribler\community\tunnel\routing.pyo", line 98, in tunnel_data

File "Tribler\community\tunnel\tunnel_community.pyo", line 1178, in tunnel_data_to_end

File "Tribler\community\tunnel\conversion.pyo", line 473, in encode_data

File "Tribler\community\tunnel\conversion.pyo", line 463, in encode_address

exceptions.TypeError: must be string, not None

[colin1497]

RC5 still crashes while building circuits when I use my .Tribler.old directory. Still no log file.

From Event Viewer: Faulting application name: tribler.exe, version: 0.0.0.0, time stamp: 0x55ddccf1 Faulting module name: MSVCR90.dll, version: 9.0.30729.9158, time stamp: 0x554b0726 Exception code: 0xc0000005 Fault offset: 0x0003b9f0 Faulting process id: 0x2908 Faulting application start time: 0x01d0e98f49523dd5 Faulting application path: C:\Program Files (x86)\Tribler\tribler.exe Faulting module path: C:\WINDOWS\WinSxS\x86_microsoft.vc90.crt_1fc8b3b9a1e18e3b_9.0.30729.9158_none_5091b51ebcb97cdc\MSVCR90.dll Report Id: da8d7422-2d4b-4e77-861a-24bb4ad02bf5 Faulting package full name: Faulting package-relative application ID:

[colin1497]

I just tried something I hadn't with my .Tribler.old directory:

I renamed my download directory so that all my old torrents would start downloading from scratch instead of continuing. When I did this, it didn't crash for several minutes. It spent several minutes building circuits for anon links (I did not let it complete anon building as I didn't have enough available drive space to recreate every download) and I cleaned out my download list some (91 to 78 torrents) and then exited without a crash.

I then renamed my .torrents directory so that I could try resuming again with fewer torrents. Building circuits took 5+ minutes. Once the blue bar reached 100% on building circuits it crashed. I get a notification to look at the log file, but no contents in the log file.

Event Viewer log:

Faulting application name: tribler.exe, version: 0.0.0.0, time stamp: 0x55ddccf1 Faulting module name: ntdll.dll, version: 10.0.10240.16430, time stamp: 0x55c599e1 Exception code: 0xc0000005 Fault offset: 0x0003cd9e Faulting process id: 0x2c20 Faulting application start time: 0x01d0e99110aa6899 Faulting application path: C:\Program Files (x86)\Tribler\tribler.exe Faulting module path: C:\WINDOWS\SYSTEM32\ntdll.dll Report Id: 910cbcd9-88d7-45c5-9934-8938fc8ed66c Faulting package full name: Faulting package-relative application ID:

[whirm]

Test 1: Restoring my .tribler.old directory to .tribler

Application crashed like before. From Event Viewer:

Ok, could you copy your .Tribler.old dir back to its place and delete one file at a time while trying to start Tribler after each deletion until you get to start it successfully? I would like to know which file is making it crash and maybe you can send it privately to me so I can have a look?

Thanks!

EDIT: Disregard this, I didn't get to you last post before writing this.

EDIT2: Oh well, I see it more or less works with a new .Tribler but crashes as soon as it builds enough circuits with with .Tribler.old? Then do what I asked on the first place: copy .Tribler.old, start, build circuits, if it crashes, delete one file/dir and try again until it doesn't.

[colin1497]

Apologies, I need to figure out markdown syntax, this crazy formatting is annoying...

OK, haven't had much of a chance to mess with it, but tried again today. Reduced to 61 torrents and it managed to get through checking a ton of them but never finished building circuits for anything but the automatic test torrent. Eventually I had no anon peers and it was just sitting so I shut down, got crash and error log as below:

ERROR 1442863983.41 sqlitecachedb:272 cachedb: ===Twisted===

SQL Type: <type 'unicode'>

COMMIT;

None

Traceback (most recent call last): File "Tribler\Core\CacheDB\sqlitecachedb.pyo", line 261, in execute File "c:\apsw\src\cursor.c", line 231, in resetcursor SQLError: SQLError: cannot commit - no transaction is active ERROR 1442863983.41 sqlitecachedb:217 COMMIT FAILED Traceback (most recent call last): File "Tribler\Core\CacheDB\sqlitecachedb.pyo", line 215, in commit_now File "Tribler\dispersy\util.pyo", line 45, in helper File "Tribler\Core\CacheDB\sqlitecachedb.pyo", line 274, in execute SQLError: SQLError: cannot commit - no transaction is active ERROR 1442863983.41 database:388 SQLError: cannot commit - no transaction is active [C:\Users\xxxxx\AppData\Roaming.Tribler\sqlite\dispersy.db] Traceback (most recent call last): File "Tribler\dispersy\database.pyo", line 386, in commit File "Tribler\dispersy\util.pyo", line 35, in helper File "Tribler\Core\CacheDB\sqlitecachedb.pyo", line 215, in commit_now File "Tribler\dispersy\util.pyo", line 45, in helper File "Tribler\Core\CacheDB\sqlitecachedb.pyo", line 274, in execute

SQLError: SQLError: cannot commit - no transaction is active

After this I restarted, and it didn't insist on checking all the torrents again, so it's making some progress in that regard, now it just wants to seed the torrents anon, but it doesn't seem to be able to build the circuits for 61 anon torrents?

[colin1497]

After this I restarted, and it didn't insist on checking all the torrents again, so it's making some progress in that regard, now it just wants to seed the torrents anon, but it doesn't seem to be able to build the circuits for 61 anon torrents?

Anyway, starting and stopping doesn't crash now that it's finished checking all the torrents. I'm just going to leave it running for the next hour or so and see if I ever get circuits on anything but the sample.

[synctext]

hi Colin, many thanks for all your debugging efforts. we're struggling for a month now to get it solid..

Anonymous seeding of 61 torrents with 3 hops has never been tested by us. Hardcore.

if you seed only 5 or 10 torrents with3hops, is Tribler stable then? As Whirm suggested, could be 1 specific file breaking thongs or running out of file descriptors, sockets,something..

[colin1497]

One problem (secondary, but impacts debug) is that I can't stop the torrents that are trying to build circuits.

[colin1497]

OK, so I opened tribler today and it managed to build circuits and is now seeding anon.

Now it wants to redownload 8 of the 61 torrents, and, of course, it wants to do it anon, so it will essentially never happen. I don't completely understand what's going on here, maybe something failed during check?

So, I picked one of them and stopped it, copied the magnet link, removed the download, and started it again set to download BitTorrent, seed anon.

1) it loads without a name (reference issue #1669): 2) once it finishes checking, it starts trying to seed anon (back into "building circuits" hell: 3) I will confirm when it's finished if it goes back to redownloading the torrent, but that's my bet on what's going to happen if circuits are ever built.

'm not sure why it would flag that it needs to redownload something, but if it needs to redownload, and I've flagged it for BitTorrent download, it should do it over BitTorrent, not anon.

This might need to be opened as a separate issue. I think this bug report has multiple distinct issues in it...

[colin1497]

Should I try to break the multiple issues up into different bugs at this point or just let you guys sort it out? It seems like there are several independent issues that may need tracked separately.

[whirm]

@colin1497 yes please, that would be great, otherwise I'll miss something for sure :)

Thanks again!

[whirm]

I'm closing this one as it got split into 3 separate issues.