Phil_

I tried printing something completely different and smaller after that with the same issue. Turns out a reboot was all that was needed. Clearly a bug in the software.

I am trying to print this thing here: https://www.thingiverse.com/thing:2551559 Bottom_3.stl worked fine but when I try to print 2.stl / 1.stl the printer starts normally and then "fails": It starts and immediately finishes. Sep 15 09:50:07 ultimakersystem-ccbdd3003607 python3[350]: 2018-09-15 09:50:07,013 INFO printJobStateProperty New print job state: paused -> post_print Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: 2018-09-15 09:50:06,996 INFO procedure PrintProcedure(key='PRINT') transitioning from PrintFileStep(key='PRINTING') --> OtherProcedureStep(key='RUN_POST_PRINT') Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: 2018-09-15 09:50:06,508 INFO printFileStep File handler finished Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: AssertionError Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: assert self._paused == False Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: File "/usr/share/griffin/griffin/printer/fileHandlers/timeEstimators/timeEstimator.py", line 22, in __init__ Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: super().__init__(log.name) Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: File "/usr/share/griffin/griffin/printer/fileHandlers/timeEstimators/perLayerTimeEstimator.py", line 11, in __init__ Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: return PerLayerTimeEstimator(gcode_metadata) Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: File "/usr/share/griffin/griffin/printer/fileHandlers/timeEstimators/__init__.py", line 9, in getTimeEstimator Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: self._time_estimator = getTimeEstimator(self._metadata) Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: File "/usr/share/griffin/griffin/printer/fileHandlers/gcodeFileHandler.py", line 47, in run Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: self.run() Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: File "/usr/share/griffin/griffin/printer/fileHandlers/fileHandler.py", line 23, in _run Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: Traceback (most recent call last): Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: 2018-09-15 09:50:06,495 ERROR fileHandler Exception in file handling Sep 15 09:50:06 ultimakersystem-ccbdd3003607 python3[350]: 2018-09-15 09:50:06,481 INFO printerService Procedure next step: PRINT: PRINTING Any ideas how to work around this? log.log 2.stl

Just noticed... the app was last updated 2016 - I reported this bug in December 2017. I am beginning to understand the frustrations in the app reviews.

I was able to reproduce this problem with an attached debugger - looks like it needs a fairly simple fix. Device: Onplus 3T, running Android 8, Ultimaker 3 App version 1.0.1, firmware update on the printer last done on the 20th. 12-23 18:19:51.418 10509-10509/? W/System.err: com.google.gson.JsonSyntaxException: java.lang.NumberFormatException: Expected an int but was 42115.42551996263 at line 1 column 326 path $.heads[0].extruders[0].active_material.length_remaining 12-23 18:19:51.424 10509-10509/? W/System.err: at com.google.gson.internal.bind.TypeAdapters$11.read(TypeAdapters.java:249) 12-23 18:19:51.424 10509-10509/? W/System.err: at com.google.gson.internal.bind.TypeAdapters$11.read(TypeAdapters.java:239) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$1.read(ReflectiveTypeAdapterFactory.java:116) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:216) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$1.read(ReflectiveTypeAdapterFactory.java:116) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:216) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.TypeAdapterRuntimeTypeWrapper.read(TypeAdapterRuntimeTypeWrapper.java:40) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.CollectionTypeAdapterFactory$Adapter.read(CollectionTypeAdapterFactory.java:82) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.CollectionTypeAdapterFactory$Adapter.read(CollectionTypeAdapterFactory.java:61) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$1.read(ReflectiveTypeAdapterFactory.java:116) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:216) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.TypeAdapterRuntimeTypeWrapper.read(TypeAdapterRuntimeTypeWrapper.java:40) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.CollectionTypeAdapterFactory$Adapter.read(CollectionTypeAdapterFactory.java:82) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.CollectionTypeAdapterFactory$Adapter.read(CollectionTypeAdapterFactory.java:61) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$1.read(ReflectiveTypeAdapterFactory.java:116) 12-23 18:19:51.425 10509-10509/? W/System.err: at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:216) 12-23 18:19:51.425 10509-10509/? W/System.err: at retrofit2.converter.gson.GsonResponseBodyConverter.convert(GsonResponseBodyConverter.java:37) 12-23 18:19:51.425 10509-10509/? W/System.err: at retrofit2.converter.gson.GsonResponseBodyConverter.convert(GsonResponseBodyConverter.java:25) 12-23 18:19:51.425 10509-10509/? W/System.err: at retrofit2.ServiceMethod.toResponse(ServiceMethod.java:117) 12-23 18:19:51.425 10509-10509/? W/System.err: at retrofit2.OkHttpCall.parseResponse(OkHttpCall.java:211) 12-23 18:19:51.425 10509-10509/? W/System.err: at retrofit2.OkHttpCall.execute(OkHttpCall.java:174) 12-23 18:19:51.425 10509-10509/? W/System.err: at retrofit2.adapter.rxjava.RxJavaCallAdapterFactory$RequestArbiter.request(RxJavaCallAdapterFactory.java:171) 12-23 18:19:51.425 10509-10509/? W/System.err: at rx.internal.operators.OperatorSubscribeOn$1$1$1.request(OperatorSubscribeOn.java:80) 12-23 18:19:51.425 10509-10509/? W/System.err: at rx.Subscriber.setProducer(Subscriber.java:211) 12-23 18:19:51.425 10509-10509/? W/System.err: at rx.internal.operators.OperatorSubscribeOn$1$1.setProducer(OperatorSubscribeOn.java:76) 12-23 18:19:51.425 10509-10509/? W/System.err: at rx.Subscriber.setProducer(Subscriber.java:205) 12-23 18:19:51.425 10509-10509/? W/System.err: at retrofit2.adapter.rxjava.RxJavaCallAdapterFactory$CallOnSubscribe.call(RxJavaCallAdapterFactory.java:152) 12-23 18:19:51.425 10509-10509/? W/System.err: at retrofit2.adapter.rxjava.RxJavaCallAdapterFactory$CallOnSubscribe.call(RxJavaCallAdapterFactory.java:138) 12-23 18:19:51.425 10509-10509/? W/System.err: at rx.internal.operators.OnSubscribeLift.call(OnSubscribeLift.java:50) 12-23 18:19:51.426 10509-10509/? W/System.err: at rx.internal.operators.OnSubscribeLift.call(OnSubscribeLift.java:30) 12-23 18:19:51.426 10509-10509/? W/System.err: at rx.Observable.unsafeSubscribe(Observable.java:8666) 12-23 18:19:51.426 10509-10509/? W/System.err: at rx.internal.operators.OperatorSubscribeOn$1.call(OperatorSubscribeOn.java:94) 12-23 18:19:51.426 10509-10509/? W/System.err: at rx.internal.schedulers.CachedThreadScheduler$EventLoopWorker$1.call(CachedThreadScheduler.java:220) 12-23 18:19:51.426 10509-10509/? W/System.err: at rx.internal.schedulers.ScheduledAction.run(ScheduledAction.java:55) 12-23 18:19:51.426 10509-10509/? W/System.err: at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:457) 12-23 18:19:51.426 10509-10509/? W/System.err: at java.util.concurrent.FutureTask.run(FutureTask.java:266) 12-23 18:19:51.426 10509-10509/? W/System.err: at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:301) 12-23 18:19:51.426 10509-10509/? W/System.err: at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1162) 12-23 18:19:51.426 10509-10509/? W/System.err: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:636) 12-23 18:19:51.426 10509-10509/? W/System.err: at java.lang.Thread.run(Thread.java:764) 12-23 18:19:51.426 10509-10509/? W/System.err: Caused by: java.lang.NumberFormatException: Expected an int but was 42115.42551996263 at line 1 column 326 path $.heads[0].extruders[0].active_material.length_remaining 12-23 18:19:51.427 10509-10509/? W/System.err: at com.google.gson.stream.JsonReader.nextInt(JsonReader.java:1201) 12-23 18:19:51.427 10509-10509/? W/System.err: at com.google.gson.internal.bind.TypeAdapters$11.read(TypeAdapters.java:247) 12-23 18:19:51.427 10509-10509/? W/System.err: ... 39 more