Very serious Android app bug will cause eventual systemcrash

Need help? Post your questions here.

Moderator: moderators

Very serious Android app bug will cause eventual systemcrash

Postby delcypher » Fri Jul 30, 2010 12:28 am

I found a bug in the Android app just now which appears very serious because it has the capability to eventually crash the system that the Subsonic server is running on.

I've looked at the android application log when I try and select a song and use "download to phone" the application provides no visual feed back.

However if I take a look at the adb log (android) I can see that the subsonic download service keeps trying to start the download and fails. The connections in the pool rapidly starts to increase as the download fails.

On the server side every download request results in a transcode attempt. As the number of connections in the pool in the android app spirals out of control the number of simultaneous transcodes does as well.

I don't know if there is a limit to the number of simultaneous transcodes but if there isn't then this will eventually cause the system to crash as it runs out of RAM and swap space!

The android app appears to struggling with the fact a .partial file doesn't exist and throws an IOexception.

Code: Select all
D/CancellableTask(  693): Starting thread for DownloadTask (Too Late)
D/RESTMusicService(  693): Connections in pool: 2
I/RESTMusicService(  693): Using URL http://subsonic.su-root.eu:80/rest/stream.view?u=danwifi&p=enc:XXX&v=1.2.0&c=android&id=2f6d656469612f7573623530302f666c61632d6d757369632f536c6176657320746f20477261766974792f53636174746572207468652043726f77202f30352d546f6f204c6174652e666c6163&maxBitRate=0
D/dalvikvm(  321): GC_FOR_MALLOC freed 10669 objects / 488200 bytes in 77ms
I/Resources(  693): Loaded time zone names for en_US in 144ms.
W/DownloadFile(  693): Failed to download 'Too Late'.
W/DownloadFile(  693): java.io.FileNotFoundException: /mnt/sdcard/subsonic/music/Slaves to Gravity/Scatter the Crow /05-Too Late.mp3.0.partial (No such file or directory)
W/DownloadFile(  693):    at org.apache.harmony.luni.platform.OSFileSystem.openImpl(Native Method)
W/DownloadFile(  693):    at org.apache.harmony.luni.platform.OSFileSystem.open(OSFileSystem.java:152)
W/DownloadFile(  693):    at java.io.FileOutputStream.<init>(FileOutputStream.java:97)
W/DownloadFile(  693):    at net.sourceforge.subsonic.androidapp.service.DownloadFile$DownloadTask.execute(DownloadFile.java:198)
W/DownloadFile(  693):    at net.sourceforge.subsonic.androidapp.util.CancellableTask$1.run(CancellableTask.java:74)
D/LocationMasfClient(   87): getNetworkLocation(): Returning cache location with accuracy 697.0
E/FileUtil(  693): Failed to create directory /mnt/sdcard/subsonic/music/Slaves to Gravity/Scatter the Crow
D/CancellableTask(  693): Starting thread for DownloadTask (Too Late)
D/RESTMusicService(  693): Connections in pool: 3
I/RESTMusicService(  693): Using URL http://subsonic.su-root.eu:80/rest/stream.view?u=danwifi&p=enc:XXX&v=1.2.0&c=android&id=2f6d656469612f7573623530302f666c61632d6d757369632f536c6176657320746f20477261766974792f53636174746572207468652043726f77202f30352d546f6f204c6174652e666c6163&maxBitRate=0
D/SntpClient(   87): request time failed: java.net.SocketTimeoutException: Connection timed out
D/WifiService(   87): releaseWifiLockLocked: WifiLock{NetworkLocationProvider type=2 binder=android.os.Binder@44cd99d8}
D/dalvikvm(  693): GC_FOR_MALLOC freed 7339 objects / 333024 bytes in 84ms
I/Resources(  693): Loaded time zone names for en_US in 140ms.
W/DownloadFile(  693): Failed to download 'Too Late'.
W/DownloadFile(  693): java.io.FileNotFoundException: /mnt/sdcard/subsonic/music/Slaves to Gravity/Scatter the Crow /05-Too Late.mp3.0.partial (No such file or directory)
W/DownloadFile(  693):    at org.apache.harmony.luni.platform.OSFileSystem.openImpl(Native Method)
W/DownloadFile(  693):    at org.apache.harmony.luni.platform.OSFileSystem.open(OSFileSystem.java:152)
W/DownloadFile(  693):    at java.io.FileOutputStream.<init>(FileOutputStream.java:97)
W/DownloadFile(  693):    at net.sourceforge.subsonic.androidapp.service.DownloadFile$DownloadTask.execute(DownloadFile.java:198)
W/DownloadFile(  693):    at net.sourceforge.subsonic.androidapp.util.CancellableTask$1.run(CancellableTask.java:74)
E/FileUtil(  693): Failed to create directory /mnt/sdcard/subsonic/music/Slaves to Gravity/Scatter the Crow
D/CancellableTask(  693): Starting thread for DownloadTask (Too Late)
D/RESTMusicService(  693): Connections in pool: 4
I/RESTMusicService(  693): Using URL http://subsonic.su-root.eu:80/rest/stream.view?u=danwifi&p=enc:XXX&v=1.2.0&c=android&id=2f6d656469612f7573623530302f666c61632d6d757369632f536c6176657320746f20477261766974792f53636174746572207468652043726f77202f30352d546f6f204c6174652e666c6163&maxBitRate=0
D/dalvikvm(  693): GC_FOR_MALLOC freed 6394 objects / 295160 bytes in 64ms
I/Resources(  693): Loaded time zone names for en_US in 136ms.
W/DownloadFile(  693): Failed to download 'Too Late'.
W/DownloadFile(  693): java.io.FileNotFoundException: /mnt/sdcard/subsonic/music/Slaves to Gravity/Scatter the Crow /05-Too Late.mp3.0.partial (No such file or directory)
W/DownloadFile(  693):    at org.apache.harmony.luni.platform.OSFileSystem.openImpl(Native Method)
W/DownloadFile(  693):    at org.apache.harmony.luni.platform.OSFileSystem.open(OSFileSystem.java:152)
W/DownloadFile(  693):    at java.io.FileOutputStream.<init>(FileOutputStream.java:97)
W/DownloadFile(  693):    at net.sourceforge.subsonic.androidapp.service.DownloadFile$DownloadTask.execute(DownloadFile.java:198)
W/DownloadFile(  693):    at net.sourceforge.subsonic.androidapp.util.CancellableTask$1.run(CancellableTask.java:74)
D/dalvikvm(  434): GC_FOR_MALLOC freed 6726 objects / 500656 bytes in 58ms
I/ActivityManager(   87): No longer want org.wordpress.android (pid 516): hidden #16
E/FileUtil(  693): Failed to create directory /mnt/sdcard/subsonic/music/Slaves to Gravity/Scatter the Crow
D/CancellableTask(  693): Starting thread for DownloadTask (Too Late)
D/RESTMusicService(  693): Connections in pool: 5
I/RESTMusicService(  693): Using URL http://subsonic.su-root.eu:80/rest/stream.view?u=danwifi&p=enc:XXX&v=1.2.0&c=android&id=2f6d656469612f7573623530302f666c61632d6d757369632f536c6176657320746f20477261766974792f53636174746572207468652043726f77202f30352d546f6f204c6174652e666c6163&maxBitRate=0


The adb logfile continues like this until I kill the server.
Last edited by delcypher on Fri Jul 30, 2010 9:43 am, edited 2 times in total.
delcypher
 
Posts: 109
Joined: Tue Jun 01, 2010 10:39 am

Postby delcypher » Fri Jul 30, 2010 12:47 am

The same problem appears to be described here

http://forum.subsonic.org/forum/viewtopic.php?t=3807
delcypher
 
Posts: 109
Joined: Tue Jun 01, 2010 10:39 am

Postby sindre_mehus » Fri Jul 30, 2010 11:06 am

Yes, that is very serious indeed. I'll look into it. Thanks for mentioning it!

Sindre
Subsonic developer
User avatar
sindre_mehus
 
Posts: 1955
Joined: Tue Nov 29, 2005 6:19 pm
Location: Oslo, Norway


Return to Help

Who is online

Users browsing this forum: No registered users and 29 guests