Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_ClaRa.Components.Utilities.Blocks.Check.test_1_LimPID.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.01208/0.01208, 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.001217/0.001217, allocations: 95.05 kB / 25 MB, free: 1.984 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.001453/0.001453, allocations: 205.5 kB / 28.72 MB, free: 3.262 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.297/1.297, allocations: 230.7 MB / 262.9 MB, free: 5.762 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.9682/0.9682, allocations: 130.7 MB / 450.5 MB, free: 6.344 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.test_1_LimPID,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="PID.D_approx.x|PID.I.y|PID.resetValueI|PID.resetValueID|PID.resetValueP|PID.smoothPIDInput.y_aux|PID.smoothPIDOutput.y_aux|PID.smoothPIDOutput1.y_aux|Time|firstOrder.y",fileNamePrefix="ClaRa_ClaRa.Components.Utilities.Blocks.Check.test_1_LimPID")
translateModel(ClaRa.Components.Utilities.Blocks.Check.test_1_LimPID,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="PID.D_approx.x|PID.I.y|PID.resetValueI|PID.resetValueID|PID.resetValueP|PID.smoothPIDInput.y_aux|PID.smoothPIDOutput.y_aux|PID.smoothPIDOutput1.y_aux|Time|firstOrder.y",fileNamePrefix="ClaRa_ClaRa.Components.Utilities.Blocks.Check.test_1_LimPID") [Timeout 800]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.369e-05/1.369e-05, allocations: 2.281 kB / 0.6167 GB, free: 8.758 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(ClaRa.Components.Utilities.Blocks.Check.test_1_LimPID): time 0.003068/0.003082, allocations: 3.3 MB / 0.6199 GB, free: 5.449 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.001309/0.004391, allocations: 0.9879 MB / 0.6209 GB, free: 4.457 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0001525/0.004543, allocations: 7.938 kB / 0.6209 GB, free: 4.449 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0001686/0.004712, allocations: 65.48 kB / 0.621 GB, free: 4.387 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0002531/0.004965, allocations: 109.7 kB / 0.6211 GB, free: 4.281 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0004524/0.005417, allocations: 322.4 kB / 0.6214 GB, free: 3.969 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0004068/0.005824, allocations: 0.7132 MB / 0.6221 GB, free: 3.262 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002143/0.006038, allocations: 134.3 kB / 0.6222 GB, free: 3.121 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0003141/0.006352, allocations: 311.7 kB / 0.6225 GB, free: 2.816 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.000132/0.006484, allocations: 174.9 kB / 0.6227 GB, free: 2.645 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 4.81e-05/0.006533, allocations: 45.12 kB / 0.6227 GB, free: 2.602 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 6.896e-05/0.006601, allocations: 48 kB / 0.6228 GB, free: 2.555 MB / 446.1 MB
Notification: Performance of combineBinaries: time 0.0003113/0.006913, allocations: 0.8503 MB / 0.6236 GB, free: 1.695 MB / 446.1 MB
Notification: Performance of replaceArrayConstructors: time 0.000145/0.007058, allocations: 0.5594 MB / 0.6241 GB, free: 1.129 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 3.683e-05/0.007095, allocations: 28 kB / 0.6242 GB, free: 1.102 MB / 446.1 MB
Notification: Performance of FrontEnd: time 4.623e-05/0.007141, allocations: 25.12 kB / 0.6242 GB, free: 1.078 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: 83 (83)
 * Number of variables: 83 (83)
Notification: Performance of [SIM] Bindings: time 0.001518/0.008659, allocations: 2.018 MB / 0.6262 GB, free: 14.93 MB / 462.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0002093/0.008868, allocations: 231.6 kB / 0.6264 GB, free: 14.69 MB / 462.1 MB
Notification: Performance of [SIM] Early Inline: time 0.0009754/0.009844, allocations: 1.093 MB / 0.6274 GB, free: 13.57 MB / 462.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0003102/0.01015, allocations: 216 kB / 0.6277 GB, free: 13.31 MB / 462.1 MB
Notification: Performance of [SIM] Alias: time 0.002348/0.0125, allocations: 1.91 MB / 0.6295 GB, free: 11.14 MB / 462.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.000209/0.01271, allocations: 164.4 kB / 0.6297 GB, free: 10.93 MB / 462.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001057/0.01282, allocations: 95.02 kB / 0.6298 GB, free: 10.81 MB / 462.1 MB
Notification: Performance of [SIM] Detect States: time 0.000357/0.01317, allocations: 280.8 kB / 0.63 GB, free: 10.49 MB / 462.1 MB
Notification: Performance of [SIM] Events: time 0.0001402/0.01331, allocations: 83.78 kB / 0.6301 GB, free: 10.4 MB / 462.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0003663/0.01368, allocations: 336 kB / 0.6304 GB, free: 10.04 MB / 462.1 MB
Notification: Performance of [SIM] Causalize: time 0.0007847/0.01446, allocations: 0.6213 MB / 0.631 GB, free: 9.379 MB / 462.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0004935/0.01496, allocations: 0.5776 MB / 0.6316 GB, free: 8.801 MB / 462.1 MB
Notification: Performance of [INI] Simplify: time 0.0007106/0.01567, allocations: 488.8 kB / 0.6321 GB, free: 8.254 MB / 462.1 MB
Notification: Performance of [INI] Inline: time 0.000865/0.01653, allocations: 1.004 MB / 0.6331 GB, free: 7.246 MB / 462.1 MB
Notification: Performance of [INI] Partitioning: time 4.554e-05/0.01658, allocations: 26.75 kB / 0.6331 GB, free: 7.211 MB / 462.1 MB
Notification: Performance of [INI] Cleanup: time 0.0001841/0.01676, allocations: 221.1 kB / 0.6333 GB, free: 6.992 MB / 462.1 MB
Notification: Performance of [INI] Causalize: time 0.002584/0.01935, allocations: 1.965 MB / 0.6352 GB, free: 5.012 MB / 462.1 MB
Notification: Performance of [INI] Tearing: time 4.527e-05/0.01939, allocations: 68 kB / 0.6353 GB, free: 4.945 MB / 462.1 MB
Notification: Performance of [SIM] Initialization: time 4.61e-07/0.01939, allocations: 0 / 0.6353 GB, free: 4.945 MB / 462.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.0003037/0.0197, allocations: 233.9 kB / 0.6355 GB, free: 4.676 MB / 462.1 MB
Notification: Performance of [SIM] Remove Dummies: time 6.402e-06/0.0197, allocations: 0 / 0.6355 GB, free: 4.676 MB / 462.1 MB
Notification: Performance of [SIM] Tearing: time 2.235e-05/0.01973, allocations: 43.94 kB / 0.6355 GB, free: 4.633 MB / 462.1 MB
Notification: Performance of [SIM] Categorize: time 1.137e-05/0.01974, allocations: 0 / 0.6355 GB, free: 4.633 MB / 462.1 MB
Notification: Performance of [SIM] Solve: time 0.001008/0.02075, allocations: 0.8127 MB / 0.6363 GB, free: 3.824 MB / 462.1 MB
Notification: Performance of [SIM] Jacobian: time 0.0007996/0.02155, allocations: 0.9467 MB / 0.6373 GB, free: 2.746 MB / 462.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 2
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 2
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 1
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 9 (9) {PID.I.y, PID.smoothPIDInput.y_aux, add.u2, PID.smoothPIDOutput1.y_aux, PID.resetValueP, PID.resetValueID, PID.resetValueI, firstOrder.y, PID.D_approx.x}
 * Number of discrete states: .................... 1 (1) {PID.time_lag_I_activation.entryTime}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 11 (11) {PID.activate_.y, PID.I_activation.y, booleanExpression.y, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $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: ........... 40 (scalar:40, array:0, record:0)
 * Number of multi strong components: ............ 1 (algorithm:0, when:1, 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: ........... 63 (scalar:63, 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 (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 63 (scalar:63, 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 8.645e-05/0.02163, allocations: 68.3 kB / 0.6373 GB, free: 2.688 MB / 462.1 MB
Error: Internal error NBEquation.WhenEquationBody.getBodyAttributes.getConditions failed for condition: change(PID.activate_.y)
"
[Timeout remaining time 800]
[Calling sys.exit(0), Time elapsed: 3.2775460690027103]
Failed to read output from testmodel.py, exit status != 0:
0.028808968025259674 0.043111143000000005 0.033237348
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f30075cedd0>