Closed Bug 874972 Opened 11 years ago Closed 10 years ago

Intermittent Android retry "Remote Device Error: updateApp() call failed - exiting"

Categories

(Testing :: General, defect)

x86
Android
defect
Not set
normal

Tracking

(Not tracked)

RESOLVED WORKSFORME

People

(Reporter: gbrown, Unassigned)

References

Details

(Keywords: intermittent-failure)

This failure is automatically retried (a "blue") so is usually not starred, but I have seen several of these over the last week or so.

https://tbpl.mozilla.org/php/getParsedLog.php?id=23264643&full=1&branch=mozilla-inbound

05/22/2013 08:32:58: INFO: copying build/fennec/application.ini to build/talos/remoteapp.ini
05/22/2013 08:32:58: DEBUG: calling [cp build/fennec/application.ini build/talos/remoteapp.ini]
05/22/2013 08:32:58: DEBUG: cp: cannot create regular file `build/talos/remoteapp.ini': No such file or directory
05/22/2013 08:32:58: INFO: connecting to: 10.250.50.51
reconnecting socket
05/22/2013 08:32:58: INFO: devroot /mnt/sdcard/tests
05/22/2013 08:32:58: INFO: 10.250.49.163, 50141
05/22/2013 08:32:58: INFO: Current device time is 2013/05/22 08:32:58
05/22/2013 08:32:58: INFO: Setting device time to 2013/05/22 08:32:58
05/22/2013 08:32:59: INFO: Current device time is 2013/05/22 08:32:58
results: {'process': [['10007', '1206', 'com.android.inputmethod.latin'], ['1001', '1214', 'com.android.phone'], ['1000', '1020', 'system'], ['10029', '1333', 'com.android.deskclock'], ['10031', '1495', 'com.mozilla.SUTAgentAndroid'], ['10018', '1222', 'com.android.launcher'], ['10013', '1442', 'com.cooliris.media'], ['10004', '1363', 'android.process.media'], ['10009', '1420', 'com.android.quicksearchbox'], ['10002', '1434', 'com.android.music'], ['10032', '1411', 'com.mozilla.watcher'], ['10006', '1393', 'com.android.mms'], ['10010', '1378', 'com.android.providers.calendar'], ['10014', '1353', 'com.android.email'], ['10017', '1345', 'com.android.bluetooth'], ['10015', '1263', 'android.process.acore'], ['1000', '1233', 'com.android.settings']]}
results: {'memory': ['PA:828657664, FREE: 754216960']}
results: {'uptime': ['0 days 2 hours 51 minutes 53 seconds 111 ms']}
results: {'screen': ['X:1024 Y:768']}
05/22/2013 08:32:59: INFO: Installing /mnt/sdcard/tests/fennec-24.0a1.en-US.android-arm.apk
in push file with: build/fennec-24.0a1.en-US.android-arm.apk, and: /mnt/sdcard/tests/fennec-24.0a1.en-US.android-arm.apk
sending: push /mnt/sdcard/tests/fennec-24.0a1.en-US.android-arm.apk
push returned: 0ade123342dee3413c683944be03e625
Push File Validated!
05/22/2013 08:33:15: INFO: /builds/tegra-141/test/../error.flg
Remote Device Error: updateApp() call failed - exiting

Notice logcat:

05-22 08:33:14.544 W/PackageParser( 1020): Exception reading libmozalloc.so in /data/app/vmdl39853.tmp
05-22 08:33:14.544 W/PackageParser( 1020): java.lang.IllegalArgumentException: Incorrect input name
05-22 08:33:14.544 W/PackageParser( 1020): 	at javax.security.auth.x500.X500Principal.<init>(X500Principal.java:135)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.x501.Name.getX500Principal(Name.java:104)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.pkcs7.SignerInfo.<init>(SignerInfo.java:82)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.pkcs7.SignerInfo$2.getDecodedObject(SignerInfo.java:211)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.asn1.ASN1Sequence.decode(ASN1Sequence.java:54)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.asn1.BerInputStream.decodeValueCollection(BerInputStream.java:757)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.asn1.BerInputStream.readSetOf(BerInputStream.java:734)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.asn1.DerInputStream.readSetOf(DerInputStream.java:139)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.asn1.ASN1SetOf.decode(ASN1SetOf.java:49)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.asn1.BerInputStream.readSequence(BerInputStream.java:666)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.asn1.DerInputStream.readSequence(DerInputStream.java:126)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.asn1.ASN1Sequence.decode(ASN1Sequence.java:49)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.asn1.ASN1Type.decode(ASN1Type.java:98)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.pkcs7.ContentInfo$1.getDecodedObject(ContentInfo.java:153)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.asn1.ASN1Sequence.decode(ASN1Sequence.java:54)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.utils.JarUtils.verifySignature(JarUtils.java:77)
05-22 08:33:14.544 W/PackageParser( 1020): 	at java.util.jar.JarVerifier.verifyCertificate(JarVerifier.java:303)
05-22 08:33:14.544 W/PackageParser( 1020): 	at java.util.jar.JarVerifier.readCertificates(JarVerifier.java:272)
05-22 08:33:14.544 W/PackageParser( 1020): 	at java.util.jar.JarFile.getInputStream(JarFile.java:392)
05-22 08:33:14.544 W/PackageParser( 1020): 	at android.content.pm.PackageParser.loadCertificates(PackageParser.java:337)
05-22 08:33:14.544 W/PackageParser( 1020): 	at android.content.pm.PackageParser.collectCertificates(PackageParser.java:508)
05-22 08:33:14.544 W/PackageParser( 1020): 	at com.android.server.PackageManagerService.installPackageLI(PackageManagerService.java:5882)
05-22 08:33:14.544 W/PackageParser( 1020): 	at com.android.server.PackageManagerService.access$2100(PackageManagerService.java:134)
05-22 08:33:14.544 W/PackageParser( 1020): 	at com.android.server.PackageManagerService$5.run(PackageManagerService.java:4740)
05-22 08:33:14.544 W/PackageParser( 1020): 	at android.os.Handler.handleCallback(Handler.java:587)
05-22 08:33:14.544 W/PackageParser( 1020): 	at android.os.Handler.dispatchMessage(Handler.java:92)
05-22 08:33:14.544 W/PackageParser( 1020): 	at android.os.Looper.loop(Looper.java:123)
05-22 08:33:14.544 W/PackageParser( 1020): 	at android.os.HandlerThread.run(HandlerThread.java:60)
05-22 08:33:14.544 W/PackageParser( 1020): Caused by: java.io.IOException: Unrecognizable attribute name: ex
05-22 08:33:14.544 W/PackageParser( 1020): 035����������������������������������������������������������������������������������������������
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.x501.AttributeTypeAndValue.<init>(AttributeTypeAndValue.java:291)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.x509.DNParser.parse(DNParser.java:446)
05-22 08:33:14.544 W/PackageParser( 1020): 	at org.apache.harmony.security.x501.Name.<init>(Name.java:89)
05-22 08:33:14.544 W/PackageParser( 1020): 	at javax.security.auth.x500.X500Principal.<init>(X500Principal.java:133)
05-22 08:33:14.544 W/PackageParser( 1020): 	... 27 more
05-22 08:33:14.554 E/PackageParser( 1020): Package org.mozilla.fennec has no certificates at entry libmozalloc.so; ignoring!
(Adding keyword so TBPL can see this bug)
This seems to happen in "waves". See for instance https://tbpl.mozilla.org/?tree=Try&rev=6dcd325fcc73 -- 3 instances over 10 minutes.
So if we took tegra-140, tegra-141, tegra-247 and tegra-262 completely out of the pool, how much would our end-to-end time _decrease_ by having four fewer slaves? 140 actually did something 9 of its last 100 jobs, 141 managed zero, 247 did 9, 262 did 8 (though failing 3 of them is even less impressive).
No longer depends on: tegra-262
No longer depends on: tegra-140
No longer depends on: tegra-141
Closing bugs where TBPLbot has previously commented, but have now not been modified for >3 months & do not contain the whiteboard strings for disabled/annotated tests or use the keyword leave-open. Filter on: mass-intermittent-bug-closure-2014-07
Status: NEW → RESOLVED
Closed: 10 years ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.