Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_ClaRa.Components.Utilities.Blocks.Check.TestMinMax.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo): time 0.01152/0.01152, allocations: 1.759 MB / 21.42 MB, free: 392 kB / 14.72 MB
"
[Timeout remaining time 180]
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.001394/0.001394, allocations: 95.2 kB / 25 MB, free: 1.98 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.001675/0.001675, allocations: 203.5 kB / 28.72 MB, free: 3.266 MB / 19.63 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.293/1.293, allocations: 230.7 MB / 262.9 MB, free: 5.77 MB / 206.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/package.mo): time 0.933/0.933, allocations: 130.7 MB / 450.5 MB, free: 6.383 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package ClaRa with version 1.9.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.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)
Using package SMArtInt with version 0.3.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo)
Running command: translateModel(ClaRa.Components.Utilities.Blocks.Check.TestMinMax,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="Time|timeExtrema.ymax|timeExtrema.ymin",fileNamePrefix="ClaRa_ClaRa.Components.Utilities.Blocks.Check.TestMinMax")
translateModel(ClaRa.Components.Utilities.Blocks.Check.TestMinMax,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="Time|timeExtrema.ymax|timeExtrema.ymin",fileNamePrefix="ClaRa_ClaRa.Components.Utilities.Blocks.Check.TestMinMax") [Timeout 800]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.426e-05/1.426e-05, allocations: 4.812 kB / 0.6167 GB, free: 8.777 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(ClaRa.Components.Utilities.Blocks.Check.TestMinMax): time 0.002378/0.002392, allocations: 2.303 MB / 0.619 GB, free: 6.465 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.0007062/0.003099, allocations: 0.5012 MB / 0.6195 GB, free: 5.961 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 5.002e-05/0.003149, allocations: 0 / 0.6195 GB, free: 5.961 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 3.748e-05/0.003186, allocations: 7.938 kB / 0.6195 GB, free: 5.953 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 6.535e-05/0.003252, allocations: 17.05 kB / 0.6195 GB, free: 5.938 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0002327/0.003484, allocations: 51.88 kB / 0.6195 GB, free: 5.887 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0001238/0.003608, allocations: 111 kB / 0.6196 GB, free: 5.781 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 9.562e-05/0.003704, allocations: 8 kB / 0.6196 GB, free: 5.773 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0001355/0.003839, allocations: 51.88 kB / 0.6197 GB, free: 5.723 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 5.652e-05/0.003896, allocations: 43.72 kB / 0.6197 GB, free: 5.68 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 1.53e-05/0.003911, allocations: 8 kB / 0.6197 GB, free: 5.672 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 3.826e-05/0.003949, allocations: 9.125 kB / 0.6197 GB, free: 5.664 MB / 446.1 MB
Notification: Performance of combineBinaries: time 7.917e-05/0.004028, allocations: 150.4 kB / 0.6199 GB, free: 5.516 MB / 446.1 MB
Notification: Performance of replaceArrayConstructors: time 2.322e-05/0.004052, allocations: 90.8 kB / 0.62 GB, free: 5.426 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 1.226e-05/0.004064, allocations: 4.719 kB / 0.62 GB, free: 5.422 MB / 446.1 MB
Notification: Performance of FrontEnd: time 1.086e-05/0.004075, allocations: 4 kB / 0.62 GB, free: 5.418 MB / 446.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 10 (10)
 * Number of variables: 10 (10)
Notification: Performance of [SIM] Bindings: time 0.0003766/0.004451, allocations: 0.4899 MB / 0.6205 GB, free: 4.809 MB / 446.1 MB
Notification: Performance of [SIM] FunctionAlias: time 4.864e-05/0.0045, allocations: 63.98 kB / 0.6205 GB, free: 4.734 MB / 446.1 MB
Notification: Performance of [SIM] Early Inline: time 0.000155/0.004655, allocations: 264 kB / 0.6208 GB, free: 4.449 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 1: time 6.547e-05/0.00472, allocations: 102.8 kB / 0.6209 GB, free: 4.305 MB / 446.1 MB
Notification: Performance of [SIM] Alias: time 0.0003904/0.005111, allocations: 345.2 kB / 0.6212 GB, free: 3.883 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 2: time 5.373e-05/0.005165, allocations: 97.05 kB / 0.6213 GB, free: 3.746 MB / 446.1 MB
Notification: Performance of [SIM] Remove Stream: time 2.652e-05/0.005191, allocations: 48.41 kB / 0.6213 GB, free: 3.672 MB / 446.1 MB
Notification: Performance of [SIM] Detect States: time 9.4e-05/0.005285, allocations: 112.8 kB / 0.6215 GB, free: 3.508 MB / 446.1 MB
Notification: Performance of [SIM] Events: time 7.054e-05/0.005356, allocations: 50.75 kB / 0.6215 GB, free: 3.461 MB / 446.1 MB
Notification: Performance of [SIM] Partitioning: time 8.963e-05/0.005445, allocations: 92.05 kB / 0.6216 GB, free: 3.363 MB / 446.1 MB
Notification: Performance of [SIM] Causalize: time 0.0001489/0.005594, allocations: 133 kB / 0.6217 GB, free: 3.227 MB / 446.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 9.098e-05/0.005685, allocations: 191.8 kB / 0.6219 GB, free: 3.039 MB / 446.1 MB
Notification: Performance of [INI] Simplify: time 0.0001221/0.005807, allocations: 164.4 kB / 0.6221 GB, free: 2.812 MB / 446.1 MB
Notification: Performance of [INI] Inline: time 0.0001371/0.005944, allocations: 287.8 kB / 0.6223 GB, free: 2.527 MB / 446.1 MB
Notification: Performance of [INI] Partitioning: time 9.918e-06/0.005954, allocations: 16.06 kB / 0.6223 GB, free: 2.504 MB / 446.1 MB
Notification: Performance of [INI] Cleanup: time 2.372e-05/0.005978, allocations: 44.55 kB / 0.6224 GB, free: 2.461 MB / 446.1 MB
Notification: Performance of [INI] Causalize: time 0.0002008/0.006179, allocations: 185.8 kB / 0.6226 GB, free: 2.273 MB / 446.1 MB
Notification: Performance of [INI] Tearing: time 1.022e-05/0.006189, allocations: 15.56 kB / 0.6226 GB, free: 2.262 MB / 446.1 MB
Notification: Performance of [SIM] Initialization: time 3.61e-07/0.006189, allocations: 0 / 0.6226 GB, free: 2.262 MB / 446.1 MB
Notification: Performance of [SIM] DAE-Mode: time 8.586e-05/0.006275, allocations: 80.64 kB / 0.6227 GB, free: 2.191 MB / 446.1 MB
Notification: Performance of [SIM] Remove Dummies: time 3.897e-06/0.006279, allocations: 4.531 kB / 0.6227 GB, free: 2.188 MB / 446.1 MB
Notification: Performance of [SIM] Tearing: time 2.564e-06/0.006282, allocations: 4.5 kB / 0.6227 GB, free: 2.184 MB / 446.1 MB
Notification: Performance of [SIM] Categorize: time 5.981e-06/0.006288, allocations: 9 kB / 0.6227 GB, free: 2.176 MB / 446.1 MB
Notification: Performance of [SIM] Solve: time 0.0002358/0.006524, allocations: 259.6 kB / 0.6229 GB, free: 1.934 MB / 446.1 MB
Notification: Performance of [SIM] Jacobian: time 0.000158/0.006681, allocations: 408 kB / 0.6233 GB, free: 1.469 MB / 446.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 0
 * 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: ............................. 2 (2) {timeExtrema.y_max, timeExtrema.y_min}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 4 (4) {$TEV_3, $TEV_2, $TEV_1, $TEV_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: ........... 9 (scalar:9, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, 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: ........... 11 (scalar:11, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, 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.21e-05/0.006714, allocations: 15.41 kB / 0.6233 GB, free: 1.461 MB / 446.1 MB
Notification: Performance of SimCode: time 0.0004726/0.007186, allocations: 388.7 kB / 0.6237 GB, free: 1.059 MB / 446.1 MB
Notification: Performance of Templates: time 0.003019/0.01021, allocations: 0.9971 MB / 0.6247 GB, free: 84 kB / 446.1 MB
"
[Timeout remaining time 800]
make -j1 -f ClaRa_ClaRa.Components.Utilities.Blocks.Check.TestMinMax.makefile [Timeout 800]
(rm -f ClaRa_ClaRa.Components.Utilities.Blocks.Check.TestMinMax.pipe ; mkfifo ClaRa_ClaRa.Components.Utilities.Blocks.Check.TestMinMax.pipe ; head -c 1048576 < ClaRa_ClaRa.Components.Utilities.Blocks.Check.TestMinMax.pipe >> ../files/ClaRa_ClaRa.Components.Utilities.Blocks.Check.TestMinMax.sim & ./ClaRa_ClaRa.Components.Utilities.Blocks.Check.TestMinMax  -abortSlowSimulation -alarm=800  -emit_protected -lv LOG_STATS > ClaRa_ClaRa.Components.Utilities.Blocks.Check.TestMinMax.pipe 2>&1) [Timeout 800]
[Calling os._exit(0), Time elapsed: 5.024673979962245]
Failed to read output from testmodel.py, exit status != 0:
0.016359059954993427 0.030244676 0.023475842
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fe7d6caedd0>