Internal Server Error: ArrayIndexOutOfBoundsException

[AlexanderDaniel]

Seen at Tue Feb 4 16:26:33 CET 2014

Execution exception[[ArrayIndexOutOfBoundsException: -1]]

[AlexanderDaniel]

Log output from heroku logs --app scala-vienna-prod:

2014-02-04T15:27:11.021848+00:00 app[web.1]: [error] application - 
2014-02-04T15:27:11.021859+00:00 app[web.1]: ! @6h6ea3kol - Internal server error, for (GET) [/] ->
2014-02-04T15:27:11.021856+00:00 app[web.1]: 
2014-02-04T15:27:11.021860+00:00 app[web.1]: 
2014-02-04T15:27:11.021872+00:00 app[web.1]:    at scala.runtime.AbstractPartialFunction.apply(AbstractPartialFunction.scala:33) [org.scala-lang.scala-library-2.10.3.jar:na]
2014-02-04T15:27:11.021868+00:00 app[web.1]:    at play.core.server.netty.PlayDefaultUpstreamHandler$$anonfun$12$$anonfun$apply$1.applyOrElse(PlayDefaultUpstreamHandler.scala:165) [com.typesafe.play.play_2.10-2.2.1.jar:2.2.1]
2014-02-04T15:27:11.021873+00:00 app[web.1]:    at scala.util.Failure$$anonfun$recover$1.apply(Try.scala:185) [org.scala-lang.scala-library-2.10.3.jar:na]
2014-02-04T15:27:11.021866+00:00 app[web.1]:    at play.api.DefaultApplication.handleError(Application.scala:399) [com.typesafe.play.play_2.10-2.2.1.jar:2.2.1]
2014-02-04T15:27:11.021862+00:00 app[web.1]: play.api.Application$$anon$1: Execution exception[[ArrayIndexOutOfBoundsException: -1]]
2014-02-04T15:27:11.021877+00:00 app[web.1]:    at java.util.ArrayList.elementData(ArrayList.java:338) ~[na:1.6.0_27]
2014-02-04T15:27:11.021878+00:00 app[web.1]:    at java.util.ArrayList.get(ArrayList.java:351) ~[na:1.6.0_27]
2014-02-04T15:27:11.021880+00:00 app[web.1]:    at com.sun.imageio.plugins.jpeg.JPEGImageReader.checkTablesOnly(JPEGImageReader.java:369) ~[na:1.6.0_27]
2014-02-04T15:27:11.021882+00:00 app[web.1]:    at com.sun.imageio.plugins.jpeg.JPEGImageReader.gotoImage(JPEGImageReader.java:470) ~[na:1.6.0_27]
2014-02-04T15:27:11.021884+00:00 app[web.1]:    at com.sun.imageio.plugins.jpeg.JPEGImageReader.readHeader(JPEGImageReader.java:586) ~[na:1.6.0_27]
2014-02-04T15:27:11.021903+00:00 app[web.1]:    at com.sun.imageio.plugins.jpeg.JPEGImageReader.readInternal(JPEGImageReader.java:1004) ~[na:1.6.0_27]
2014-02-04T15:27:11.021864+00:00 app[web.1]:    at play.api.Application$class.handleError(Application.scala:293) ~[com.typesafe.play.play_2.10-2.2.1.jar:2.2.1]
2014-02-04T15:27:11.021870+00:00 app[web.1]:    at play.core.server.netty.PlayDefaultUpstreamHandler$$anonfun$12$$anonfun$apply$1.applyOrElse(PlayDefaultUpstreamHandler.scala:162) [com.typesafe.play.play_2.10-2.2.1.jar:2.2.1]
2014-02-04T15:27:11.021875+00:00 app[web.1]: Caused by: java.lang.ArrayIndexOutOfBoundsException: -1
2014-02-04T15:27:11.144032+00:00 heroku[router]: at=info method=GET path=/assets/stylesheets/scala-vienna.css host=www.scala-vienna.org request_id=661c0783-7158-4622-9222-aafb0570a540 fwd="81.223.107.114" dyno=web.1 connect=1ms service=10ms status=304 bytes=0
2014-02-04T15:27:11.145652+00:00 heroku[router]: at=info method=GET path=/webjars/bootstrap/3.0.2/js/bootstrap.min.js host=www.scala-vienna.org request_id=f5ead984-429c-4a18-aa1a-92100ee149c3 fwd="81.223.107.114" dyno=web.1 connect=1ms service=14ms status=304 bytes=0
2014-02-04T15:27:37.247380+00:00 heroku[router]: at=info method=GET path=/?pingdom host=www.scala-vienna.org request_id=4e1ae00f-1af8-44e7-a9c4-a71151e77444 fwd="176.31.228.137" dyno=web.1 connect=1ms service=8ms status=500 bytes=4362
2014-02-04T15:27:37.243904+00:00 app[web.1]: [error] application - 
2014-02-04T15:27:37.243910+00:00 app[web.1]: 
2014-02-04T15:27:37.243932+00:00 app[web.1]:    at com.sun.imageio.plugins.jpeg.JPEGImageReader.checkTablesOnly(JPEGImageReader.java:369) ~[na:1.6.0_27]
2014-02-04T15:27:37.243917+00:00 app[web.1]:    at play.api.Application$class.handleError(Application.scala:293) ~[com.typesafe.play.play_2.10-2.2.1.jar:2.2.1]
2014-02-04T15:27:37.243913+00:00 app[web.1]: 
2014-02-04T15:27:37.243915+00:00 app[web.1]: play.api.Application$$anon$1: Execution exception[[ArrayIndexOutOfBoundsException: -1]]
2014-02-04T15:27:37.243934+00:00 app[web.1]:    at com.sun.imageio.plugins.jpeg.JPEGImageReader.gotoImage(JPEGImageReader.java:470) ~[na:1.6.0_27]
2014-02-04T15:27:37.243940+00:00 app[web.1]:    at com.sun.imageio.plugins.jpeg.JPEGImageReader.readHeader(JPEGImageReader.java:586) ~[na:1.6.0_27]
2014-02-04T15:27:37.243921+00:00 app[web.1]:    at play.core.server.netty.PlayDefaultUpstreamHandler$$anonfun$12$$anonfun$apply$1.applyOrElse(PlayDefaultUpstreamHandler.scala:165) [com.typesafe.play.play_2.10-2.2.1.jar:2.2.1]
2014-02-04T15:27:37.243912+00:00 app[web.1]: ! @6h6ea57j9 - Internal server error, for (GET) [/?pingdom] ->
2014-02-04T15:27:37.243928+00:00 app[web.1]: Caused by: java.lang.ArrayIndexOutOfBoundsException: -1
2014-02-04T15:27:37.243919+00:00 app[web.1]:    at play.api.DefaultApplication.handleError(Application.scala:399) [com.typesafe.play.play_2.10-2.2.1.jar:2.2.1]
2014-02-04T15:27:37.243924+00:00 app[web.1]:    at scala.runtime.AbstractPartialFunction.apply(AbstractPartialFunction.scala:33) [org.scala-lang.scala-library-2.10.3.jar:na]
2014-02-04T15:27:37.243926+00:00 app[web.1]:    at scala.util.Failure$$anonfun$recover$1.apply(Try.scala:185) [org.scala-lang.scala-library-2.10.3.jar:na]
2014-02-04T15:27:37.243923+00:00 app[web.1]:    at play.core.server.netty.PlayDefaultUpstreamHandler$$anonfun$12$$anonfun$apply$1.applyOrElse(PlayDefaultUpstreamHandler.scala:162) [com.typesafe.play.play_2.10-2.2.1.jar:2.2.1]
2014-02-04T15:27:37.243943+00:00 app[web.1]:    at com.sun.imageio.plugins.jpeg.JPEGImageReader.readInternal(JPEGImageReader.java:1004) ~[na:1.6.0_27]
2014-02-04T15:27:37.243929+00:00 app[web.1]:    at java.util.ArrayList.elementData(ArrayList.java:338) ~[na:1.6.0_27]
2014-02-04T15:27:37.243931+00:00 app[web.1]:    at java.util.ArrayList.get(ArrayList.java:351) ~[na:1.6.0_27]

[AlexanderDaniel]

Restarted dyno heroku ps:restart --app scala-vienna-prod

http://www.scala-vienna.org works again :-)

Still we should probably investigate to solve the root cause.

[rafacm]

I've expanded the log context and extracted it to https://www.dropbox.com/s/j3uyj2yelu1g23o/github-issue-39-scala-vienna-prod-log.txt
After having a quick look I believe this issue might have to do with the app receiving requests while we are populating the talks cache.