Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Chemical_Chemical.Obsolete.Examples.SimpleReaction.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001366/0.001366, allocations: 104.3 kB / 19.76 MB, free: 1.906 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001398/0.001398, allocations: 212 kB / 23.06 MB, free: 4.984 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.194/1.194, allocations: 230.7 MB / 256.9 MB, free: 7.719 MB / 206.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 2.0.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 2.0.0/package.mo): time 0.1105/0.1105, allocations: 23.88 MB / 337.2 MB, free: 10.89 MB / 270.1 MB
"
[Timeout remaining time 180]
Using package Chemical with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 2.0.0/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(Chemical.Obsolete.Examples.SimpleReaction,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Chemical_Chemical.Obsolete.Examples.SimpleReaction")
translateModel(Chemical.Obsolete.Examples.SimpleReaction,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Chemical_Chemical.Obsolete.Examples.SimpleReaction") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.278e-05/1.278e-05, allocations: 2.281 kB / 472.4 MB, free: 6.988 MB / 334.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 2.0.0/Obsolete.mo:14337:7-14337:66:writable] Warning: An inner declaration for outer component system could not be found and was automatically generated.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 2.0.0/Obsolete.mo:14337:7-14337:66:writable] Notification: The diagnostics message for the missing inner is: 
Your model is using an outer \"system\" component but
an inner \"system\" component is not defined.
For simulation drag Modelica.Fluid.System into your model
to specify system properties.
Notification: Performance of NFInst.instantiate(Chemical.Obsolete.Examples.SimpleReaction): time 0.06135/0.06137, allocations: 24.8 MB / 497.2 MB, free: 14.32 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008202/0.06957, allocations: 7.06 MB / 0.4925 GB, free: 7.23 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0002665/0.06983, allocations: 19.88 kB / 0.4925 GB, free: 7.211 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0004251/0.07026, allocations: 170.6 kB / 0.4927 GB, free: 7.043 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.001325/0.07159, allocations: 0.7021 MB / 0.4933 GB, free: 6.336 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00182/0.0734, allocations: 1.041 MB / 0.4944 GB, free: 5.289 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.000972/0.07438, allocations: 1.096 MB / 0.4954 GB, free: 4.188 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0005435/0.07492, allocations: 430.8 kB / 0.4958 GB, free: 3.762 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0004194/0.07534, allocations: 478.8 kB / 0.4963 GB, free: 3.293 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.000462/0.0758, allocations: 442.2 kB / 0.4967 GB, free: 2.859 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001112/0.07591, allocations: 87.98 kB / 0.4968 GB, free: 2.773 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0012/0.07711, allocations: 0.819 MB / 0.4976 GB, free: 1.953 MB / 366.1 MB
Notification: Performance of combineBinaries: time 0.001158/0.07827, allocations: 1.583 MB / 0.4991 GB, free: 364 kB / 366.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0005438/0.07881, allocations: 0.9103 MB / 0.5 GB, free: 15.43 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 9.314e-05/0.07891, allocations: 72 kB / 0.5001 GB, free: 15.36 MB / 382.1 MB
Notification: Performance of FrontEnd: time 8.729e-05/0.07899, allocations: 31.92 kB / 0.5001 GB, free: 15.33 MB / 382.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 168 (168)
 * Number of variables: 168 (168)
Notification: Performance of [SIM] Bindings: time 0.003051/0.08205, allocations: 3.793 MB / 0.5038 GB, free: 11.35 MB / 382.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0005101/0.08256, allocations: 0.55 MB / 0.5044 GB, free: 10.78 MB / 382.1 MB
Notification: Performance of [SIM] Early Inline: time 0.00289/0.08545, allocations: 3.041 MB / 0.5073 GB, free: 7.699 MB / 382.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0005382/0.08598, allocations: 404.4 kB / 0.5077 GB, free: 7.234 MB / 382.1 MB
Warning: NBAlias.setStartFixed: Alias set with conflicting unfixed start values detected. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.003971/0.08995, allocations: 3.454 MB / 0.5111 GB, free: 3.43 MB / 382.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0003319/0.09029, allocations: 260.3 kB / 0.5113 GB, free: 3.125 MB / 382.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001389/0.09043, allocations: 140.1 kB / 0.5115 GB, free: 2.961 MB / 382.1 MB
Notification: Performance of [SIM] Detect States: time 0.0003091/0.09073, allocations: 324.1 kB / 0.5118 GB, free: 2.586 MB / 382.1 MB
Notification: Performance of [SIM] Events: time 9.889e-05/0.09083, allocations: 67.88 kB / 0.5119 GB, free: 2.52 MB / 382.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0005359/0.09137, allocations: 0.51 MB / 0.5124 GB, free: 1.98 MB / 382.1 MB
Notification: Performance of [SIM] Causalize: time 0.001307/0.09268, allocations: 1.104 MB / 0.5134 GB, free: 0.8438 MB / 382.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0006381/0.09331, allocations: 0.7962 MB / 0.5142 GB, free: 48 kB / 382.1 MB
Notification: Performance of [INI] Simplify: time 0.1355/0.2288, allocations: 0.5686 MB / 0.5148 GB, free: 43.06 MB / 382.1 MB
Notification: Performance of [INI] Inline: time 0.001235/0.23, allocations: 1.42 MB / 0.5162 GB, free: 43.05 MB / 382.1 MB
Notification: Performance of [INI] Partitioning: time 6.369e-05/0.2301, allocations: 36.12 kB / 0.5162 GB, free: 43.02 MB / 382.1 MB
Notification: Performance of [INI] Cleanup: time 0.0002092/0.2303, allocations: 221.4 kB / 0.5164 GB, free: 43.02 MB / 382.1 MB
Notification: Performance of [INI] Causalize: time 0.001846/0.2321, allocations: 1.445 MB / 0.5178 GB, free: 42.91 MB / 382.1 MB
Notification: Performance of [INI] Tearing: time 2.801e-05/0.2322, allocations: 48.59 kB / 0.5179 GB, free: 42.91 MB / 382.1 MB
Notification: Performance of [SIM] Initialization: time 1.102e-06/0.2322, allocations: 1.344 kB / 0.5179 GB, free: 42.91 MB / 382.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.0004641/0.2326, allocations: 402 kB / 0.5182 GB, free: 42.84 MB / 382.1 MB
Notification: Performance of [SIM] Remove Dummies: time 5.61e-06/0.2326, allocations: 2.031 kB / 0.5182 GB, free: 42.84 MB / 382.1 MB
Notification: Performance of [SIM] Tearing: time 2.457e-05/0.2327, allocations: 76.12 kB / 0.5183 GB, free: 42.82 MB / 382.1 MB
Notification: Performance of [SIM] Categorize: time 1.313e-05/0.2327, allocations: 3.25 kB / 0.5183 GB, free: 42.82 MB / 382.1 MB
Notification: Performance of [SIM] Solve: time 0.001496/0.2342, allocations: 1.446 MB / 0.5197 GB, free: 42.74 MB / 382.1 MB
Notification: Performance of [SIM] Jacobian: time 0.0007614/0.2349, allocations: 1.409 MB / 0.5211 GB, free: 41.75 MB / 382.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 3
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 0
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 0
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 3 (3) {B.temperature, A.logn, B.logn}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 4 (4) {$SEV_3, $SEV_2, $SEV_1, $SEV_0}
 * Number of clocks: ............................. 0 (0) {}
 * Number of top-level inputs: ................... 0 (0) {}
Notification: [Simulation] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 74 (scalar:70, array:4, record:0)
 * Number of multi strong components: ............ 1 (algorithm:0, when:0, if:1, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 89 (scalar:85, array:4, record:0)
 * Number of multi strong components: ............ 1 (algorithm:0, when:0, if:1, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: Performance of backend: time 3.59e-05/0.235, allocations: 44.83 kB / 0.5212 GB, free: 41.72 MB / 382.1 MB
Notification: Performance of SimCode: time 0.004812/0.2398, allocations: 4.384 MB / 0.5254 GB, free: 40.64 MB / 382.1 MB
Notification: Performance of Templates: time 0.01367/0.2534, allocations: 11.19 MB / 0.5364 GB, free: 38.14 MB / 382.1 MB
"
[Timeout remaining time 660]
make -j1 -f Chemical_Chemical.Obsolete.Examples.SimpleReaction.makefile [Timeout 660]
(rm -f Chemical_Chemical.Obsolete.Examples.SimpleReaction.pipe ; mkfifo Chemical_Chemical.Obsolete.Examples.SimpleReaction.pipe ; head -c 1048576 < Chemical_Chemical.Obsolete.Examples.SimpleReaction.pipe >> ../files/Chemical_Chemical.Obsolete.Examples.SimpleReaction.sim & ./Chemical_Chemical.Obsolete.Examples.SimpleReaction  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Chemical_Chemical.Obsolete.Examples.SimpleReaction.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 4.175125040987041]
Failed to read output from testmodel.py, exit status != 0:
0.25962902494939044 0.267484738 0.18669770300000002
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f213025afb0>