start fails with "Control port file not created"

Start log observed on Android 4.4.4, Tor Browser 60.6.1, 100% failure rate:

- Set background service to FOREGROUND
- updating settings in Tor service
- updating torrc custom configuration...
- success.
- checking binary version: 0.3.5.8-rc-openssl1.0.2p
- Orbot is starting…
- Tor control port file not created
- Unable to start Tor: java.io.IOException: Control port file not created: /data/data/org.torproject.torbrowser_alpha/app_torservice/lib/tor/control.txt, len = 0

I've seen two mentions of this by Google search, but couldn't find an existing ticket.

Trac:
Username: belm0

added TorBrowserTeam201905R blog component::applications/tor browser google-play-store owner::tbb-team priority::high reporter::belm0 resolution::fixed severity::normal status::closed tbb-8.5-must type::defect user-feedback labels

That's just using the 8.5a11 alpha on your device without any modifications?

Trac:
Status: new to needs_information
Cc: N/A to sisbell, sysrqb
Keywords: N/A deleted, tbb-8.5-must, TorBrowserTeam201904 added

Do we see any other error logs in logcat? Startup will print the error stream to logcat.

#30401 (moved) is a duplicate. echaskaris: can you help us out with debugging if we give you instructions? We would need some logcat output...

Trac:
Cc: sisbell, sysrqb to sisbell, sysrqb, echaskaris

Replying to gk:

#30401 (moved) is a duplicate. echaskaris: can you help us out with debugging if we give you instructions? We would need some logcat output...

Yes, I can help, what do you want me to do?

Trac:
Username: echaskaris

Trac:
Username: echaskaris

2019-05-07-12-51-48.txt

Logcat filtered by tor

Trac:
Username: echaskaris

Trac:
Username: echaskaris

logcat.txt

logcat unfiltered

Trac:
Username: echaskaris

Hm, we could use more log output I guess. We fail somewhere here

            LOG.info("Waiting for control port");
            boolean isCreated = controlPortFile.exists() || controlPortFile.createNewFile();
            WriteObserver controlPortFileObserver = onionProxyContext.createControlPortFileObserver();
            if (!isCreated || (controlPortFile.length() == 0 && !controlPortFileObserver.poll(config.getFileCreationTimeout(), SECONDS))) {
                LOG.warn("Control port file not created");

sisbell: any ideas apart from creating a debug build with more logging enabled? (if not, could you create that build and make it available for echaskaris?)

Okay, I move forward here and created a testbuild with more log output:

Could you attach the logcat output of that one again? Thanks!

https://people.torproject.org/~gk/testbuild/tor-browser-tbb-nightly-30284_android-armv7-multi-qa.apk https://people.torproject.org/~gk/testbuild/tor-browser-tbb-nightly-30284_android-armv7-multi-qa.apk.asc

Trac:
Username: echaskaris

logcat__1_.txt

Logcat from nightly build

Trac:
Username: echaskaris

Thank you! I'm doing this correctly right?

Trac:
Username: echaskaris

Replying to echaskaris:

Thank you! I'm doing this correctly right?

Thanks! Yes, that is exactly what we requested!

Unfortunately, this is still a mystery. The file is successfully created, but tor never writes to it. I wonder if tor is dying, or it is starting slowly and doesn't create the cookie before the timeout?

5331	05-08 18:13:35.919 I/OnionProxyManager(13091): Waiting for control port
5332	05-08 18:13:35.919 I/OnionProxyManager(13091): Control port file existence: true
5333	05-08 18:13:35.919 I/OnionProxyManager(13091): isCreated is: true
[snip]
5903	05-08 18:13:50.929 W/OnionProxyManager(13091): Control port file not created
5904	05-08 18:13:50.929 W/OnionProxyManager(13091): Control port file length is: 0
5905	05-08 18:13:50.929 I/FileUtilities(13091): /data/data/org.torproject.torbrowser_nightly/app_torservice/lib/tor/control.txt
5906	05-08 18:13:50.939 I/System.out(13091): Sending status: STOPPING
5907	05-08 18:13:50.959 I/System.out(13091): Sending status: OFF

Hm. What about

	05-07 13:09:22.958 E/cutils  (216): Failed to mkdirat(/storage/extSdCard/Android): Read-only file system
5454	05-07 13:09:22.958 W/ContextImpl(23487): Failed to ensure directory: /storage/extSdCard/Android/data/org.torproject.torbrowser_alpha/files/Download
5455	05-07 13:09:22.958 W/Vold    (216): Returning OperationFailed - no handler for errno 30

?

I think extSDcard is for the SD card, but I don't have one. Internal storage is at /storage/sdcard0.

Trac:
Username: echaskaris

I put in an SD card and I can see the directories(/storage/extSdCard/Android/data/org.torproject.torbrowser_alpha/files/Download) in file manager. Tor still doesn't connect.

Trac:
Username: echaskaris

Moving tickets to May

Trac:
Keywords: TorBrowserTeam201904 deleted, TorBrowserTeam201905 added

Tested on a non-samsung jellybean phone, it worked fine. I have samsung with Kitkat btw.

Trac:
Username: echaskaris