730634 - Intermittent Android "java.net.ConnectException: /10.250.48.219:20742 - Connection refused" during "Install App on Device" step

Status: RESOLVED DUPLICATE of bug 686084

(Infrastructure & Operations Graveyard :: CIDuty, task)

Description

[Richard Newman [:rnewman]]

+++ This bug was initially created as a clone of Bug #681948 +++

https://tbpl.mozilla.org/php/getParsedLog.php?id=9631229&tree=Try
Android Tegra 250 try opt test mochitest-4 on 2012-02-25 16:32:50 PST for push 028d4b6b2d83

========= Started Install App on Device failed (results: 2, elapsed: 20 mins, 38 secs) ==========
...
Push File Validated!

command timed out: 1200 seconds without output, killing pid 70769
process killed by signal 9
program finished with exit code -1
elapsedTime=1238.205069
======== Finished Install App on Device failed (results: 2, elapsed: 20 mins, 38 secs) ========
01-01 00:00:52.109 W/System.err( 1458): java.net.ConnectException: /10.250.48.219:20742 - Connection refused
01-01 00:00:52.109 W/System.err( 1458): 	at org.apache.harmony.luni.net.PlainSocketImpl.connect(PlainSocketImpl.java:254)
01-01 00:00:52.109 W/System.err( 1458): 	at org.apache.harmony.luni.net.PlainSocketImpl.connect(PlainSocketImpl.java:219)
01-01 00:00:52.109 W/System.err( 1458): 	at java.net.Socket.startupSocket(Socket.java:781)
01-01 00:00:52.109 W/System.err( 1458): 	at java.net.Socket.tryAllAddresses(Socket.java:194)
01-01 00:00:52.109 W/System.err( 1458): 	at java.net.Socket.<init>(Socket.java:258)
01-01 00:00:52.109 W/System.err( 1458): 	at java.net.Socket.<init>(Socket.java:222)
01-01 00:00:52.109 W/System.err( 1458): 	at com.mozilla.SUTAgentAndroid.service.DoCommand.RegisterTheDevice(DoCommand.java:2519)
01-01 00:00:52.109 W/System.err( 1458): 	at com.mozilla.SUTAgentAndroid.SUTAgentAndroid$2.run(SUTAgentAndroid.java:631)
01-01 00:00:52.109 W/System.err( 1458): 	at j
recv'ing...
response: ava.lang.Thread.run(Thread.java:1096)
01-01 00:00:52.249 W/InputManagerService( 1020): Starting input on non-focused client com.android.internal.view.IInputMethodClient$Stub$Proxy@4845df90 (uid=10018 pid=1239)
01-01 00:11:39.348 W/System.err( 1458): java.net.SocketException: Connection reset by peer
01-01 00:11:39.348 W/System.err( 1458): 	at org.apache.harmony.luni.platform.OSNetworkSystem.readSocketImpl(Native Method)
01-01 00:11:39.348 W/System.err( 1458): 	at org.apache.harmony.luni.platform.OSNetworkSystem.read(OSNetworkSystem.java:358)
01-01 00:11:39.348 W/System.err( 1458): 	at org.apache.harmony.luni.net.PlainSocketImpl.read(PlainSocketImpl.java:561)
01-01 00:11:39.348 W/System.err( 1458): 	at org.apache.harmony.luni.net.SocketInputStream.read(SocketInputStream.java:61)
01-01 00:11:39.356 W/System.err( 1458): 	at com.mozilla.SUTAgentAndroid.service.CmdWorkerThread.run(CmdWorkerThread.java:142)


...

01-01 00:00:51.236 E/        ( 1020): Couldn't find or open 'accelerometer_tegra' driver (Not a typewriter)
01-01 00:00:51.519 E/MediaScanner( 1346): new omx scanner
01-01 00:00:51.799 W/Service ( 1440): setForeground: ignoring old API call on com.mozilla.watcher.WatcherService
01-01 00:00:52.109 W/System.err( 1458): java.net.ConnectException: /10.250.48.219:20742 - Connection refused
01-01 00:00:52.109 W/System.err( 1458): 	at org.apache.harmony.luni.net.PlainSocketImpl.connect(PlainSocketImpl.java:254)
01-01 00:00:52.109 W/System.err( 1458): 	at org.apache.harmony.luni.net.PlainSocketImpl.connect(PlainSocketImpl.java:219)
01-01 00:00:52.109 W/System.err( 1458): 	at java.net.Socket.startupSocket(Socket.java:781)
01-01 00:00:52.109 W/System.err( 1458): 	at java.net.Socket.tryAllAddresses(Socket.java:194)
01-01 00:00:52.109 W/System.err( 1458): 	at java.net.Socket.<init>(Socket.java:258)
01-01 00:00:52.109 W/System.err( 1458): 	at java.net.Socket.<init>(Socket.java:222)
01-01 00:00:52.109 W/System.err( 1458): 	at com.mozilla.SUTAgentAndroid.service.DoCommand.RegisterTheDevice(DoCommand.java:2519)
01-01 00:00:52.109 W/System.err( 1458): 	at com.mozilla.SUTAgentAndroid.SUTAgentAndroid$2.run(SUTAgentAndroid.java:631)
01-01 00:00:52.109 W/System.err( 1458): 	at java.lang.Thread.run(Thread.java:1096)
01-01 00:00:52.249 W/InputManagerService( 1020): Starting input on non-focused client com.android.internal.view.IInputMethodClient$Stub$Proxy@4845df90 (uid=10018 pid=1239)
01-01 00:11:39.348 W/System.err( 1458): java.net.SocketException: Connection reset by peer
01-01 00:11:39.348 W/System.err( 1458): 	at org.apache.harmony.luni.platform.OSNetworkSystem.readSocketImpl(Native Method)
01-01 00:11:39.348 W/System.err( 1458): 	at org.apache.harmony.luni.platform.OSNetworkSystem.read(OSNetworkSystem.java:358)
01-01 00:11:39.348 W/System.err( 1458): 	at org.apache.harmony.luni.net.PlainSocketImpl.read(PlainSocketImpl.java:561)
01-01 00:11:39.348 W/System.err( 1458): 	at org.apache.harmony.luni.net.SocketInputStream.read(SocketInputStream.java:61)
01-01 00:11:39.356 W/System.err( 1458): 	at com.mozilla.SUTAgentAndroid.service.CmdWorkerThread.run(CmdWorkerThread.java:142)

Comment 1

[Chris AtLee [:catlee]]

not sure what to do about this. the ip address referenced is foopy17.

how often is this happening?

Comment 2

[Phil Ringnalda (:philor)]

To know that, you would have to read logs in bug 686084, because my theory was that either someone would globally retry "command timed out: 1200 seconds without output" and I wouldn't care which step timed out, or someone would retry it on cleanup device, and I could start worrying about what other steps timed out then.

Comment 3

[Chris AtLee [:catlee]]

so...isn't this the same as bug 686084 then?