Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow.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.000801/0.000801, allocations: 80.25 kB / 20.06 MB, free: 4.465 MB / 18.57 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.0007872/0.0007872, allocations: 173.9 kB / 23.36 MB, free: 1.16 MB / 18.57 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 0.9667/0.9667, allocations: 177.1 MB / 203.7 MB, free: 5.707 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Examples/PPD12/Structure.mo:531:21-535:34:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Examples/PPD12/Structure.mo:531:21-535:42:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/package.mo): time 0.7623/0.7623, allocations: 154.6 MB / 414.7 MB, free: 0.793 MB / 378.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Utilities/Cryptographics/BaseClasses/sha.mo:2:1-19:8:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Utilities/File/sha2.mo:2:1-9:9:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 179]
Using package IDEAS with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/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(IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow,tolerance=1e-08,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow")
translateModel(IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow,tolerance=1e-08,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.775e-05/2.775e-05, allocations: 3.734 kB / 0.6196 GB, free: 13.07 MB / 0.5886 GB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow): time 0.5057/0.5057, allocations: 168.3 MB / 0.7839 GB, free: 10.48 MB / 0.6511 GB
Notification: Performance of NFInst.instExpressions: time 0.009986/0.5157, allocations: 5.435 MB / 0.7892 GB, free: 6.789 MB / 0.6511 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002193/0.5179, allocations: 57.28 kB / 0.7893 GB, free: 6.758 MB / 0.6511 GB
Notification: Performance of NFTyping.typeComponents: time 0.001612/0.5195, allocations: 0.5576 MB / 0.7898 GB, free: 6.453 MB / 0.6511 GB
Notification: Performance of NFTyping.typeBindings: time 0.004743/0.5243, allocations: 1.958 MB / 0.7917 GB, free: 5.367 MB / 0.6511 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002846/0.5271, allocations: 1.247 MB / 0.7929 GB, free: 4.695 MB / 0.6511 GB
Notification: Performance of NFFlatten.flatten: time 0.004758/0.5319, allocations: 3.529 MB / 0.7964 GB, free: 3.09 MB / 0.6511 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001756/0.5336, allocations: 0.9907 MB / 0.7973 GB, free: 2.484 MB / 0.6511 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003473/0.5371, allocations: 1.931 MB / 0.7992 GB, free: 1.629 MB / 0.6511 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001851/0.5389, allocations: 0.925 MB / 0.8001 GB, free: 1.309 MB / 0.6511 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001619/0.5391, allocations: 118.7 kB / 0.8002 GB, free: 1.309 MB / 0.6511 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002718/0.5418, allocations: 1.189 MB / 0.8014 GB, free: 0.8516 MB / 0.6511 GB
Notification: Performance of combineBinaries: time 0.001776/0.5436, allocations: 2.63 MB / 0.804 GB, free: 15.33 MB / 0.6667 GB
Notification: Performance of replaceArrayConstructors: time 0.0009236/0.5445, allocations: 1.601 MB / 0.8055 GB, free: 14.37 MB / 0.6667 GB
Notification: Performance of NFVerifyModel.verify: time 0.0002559/0.5448, allocations: 122.5 kB / 0.8057 GB, free: 14.37 MB / 0.6667 GB
Notification: Performance of FrontEnd: time 0.0003188/0.5451, allocations: 50.23 kB / 0.8057 GB, free: 14.34 MB / 0.6667 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 248 (225)
 * Number of variables: 248 (206)
Notification: Performance of [SIM] Bindings: time 0.005192/0.5503, allocations: 6.922 MB / 0.8125 GB, free: 9.699 MB / 0.6667 GB
Notification: Performance of [SIM] FunctionAlias: time 0.00123/0.5515, allocations: 1.217 MB / 0.8136 GB, free: 8.816 MB / 0.6667 GB
Notification: Performance of [SIM] Early Inline: time 0.003417/0.5549, allocations: 4.356 MB / 0.8179 GB, free: 5.57 MB / 0.6667 GB
Notification: Performance of [SIM] Simplify 1: time 0.0006041/0.5555, allocations: 0.522 MB / 0.8184 GB, free: 5.199 MB / 0.6667 GB
Warning: NBAlias.setStartFixed: Alias set with conflicting unfixed start values detected. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.005742/0.5613, allocations: 5.608 MB / 0.8239 GB, free: 1.398 MB / 0.6667 GB
Notification: Performance of [SIM] Simplify 2: time 0.0004959/0.5618, allocations: 425.2 kB / 0.8243 GB, free: 0.9453 MB / 0.6667 GB
Notification: Performance of [SIM] Remove Stream: time 0.0003023/0.5621, allocations: 397.2 kB / 0.8247 GB, free: 0.5156 MB / 0.6667 GB
Notification: Performance of [SIM] Detect States: time 0.0009153/0.563, allocations: 0.9865 MB / 0.8256 GB, free: 15.45 MB / 0.6823 GB
Notification: Performance of [SIM] Events: time 0.0001715/0.5632, allocations: 135.8 kB / 0.8258 GB, free: 15.31 MB / 0.6823 GB
Notification: Performance of [SIM] Partitioning: time 0.001115/0.5643, allocations: 1.242 MB / 0.827 GB, free: 13.97 MB / 0.6823 GB
Notification: Performance of [SIM] Causalize: time 0.005492/0.5698, allocations: 5.701 MB / 0.8325 GB, free: 8.078 MB / 0.6823 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.001715/0.5715, allocations: 2.4 MB / 0.8349 GB, free: 5.676 MB / 0.6823 GB
Notification: Performance of [INI] Simplify: time 0.002111/0.5736, allocations: 1.535 MB / 0.8364 GB, free: 4 MB / 0.6823 GB
Notification: Performance of [INI] Inline: time 0.003392/0.577, allocations: 4.611 MB / 0.8409 GB, free: 15.34 MB / 0.698 GB
Notification: Performance of [INI] Partitioning: time 0.0001554/0.5771, allocations: 80.25 kB / 0.841 GB, free: 15.24 MB / 0.698 GB
Notification: Performance of [INI] Cleanup: time 0.0006782/0.5778, allocations: 0.8195 MB / 0.8418 GB, free: 14.42 MB / 0.698 GB
Warning: NBResolveSingularities.balanceInitialization reports an overdetermined initialization!
Checking for consistency is not yet supported, following equations had to be removed:
	[SCAL] (1) volDyn.dynBal.p_start = volDyn.p_start ($RES_BND_386);
Notification: Performance of [INI] Causalize: time 0.008002/0.5858, allocations: 8.023 MB / 0.8496 GB, free: 6.188 MB / 0.698 GB
Notification: Performance of [INI] Tearing: time 0.0002244/0.5861, allocations: 227.7 kB / 0.8498 GB, free: 5.973 MB / 0.698 GB
Notification: Performance of [SIM] Initialization: time 2.154e-06/0.5861, allocations: 0 / 0.8498 GB, free: 5.973 MB / 0.698 GB
Notification: Performance of [SIM] DAE-Mode: time 0.000938/0.587, allocations: 0.6502 MB / 0.8505 GB, free: 5.281 MB / 0.698 GB
Notification: Performance of [SIM] Remove Dummies: time 9.458e-06/0.587, allocations: 1.312 kB / 0.8505 GB, free: 5.281 MB / 0.698 GB
Notification: Performance of [SIM] Tearing: time 0.000154/0.5872, allocations: 247 kB / 0.8507 GB, free: 5.043 MB / 0.698 GB
Notification: Performance of [SIM] Categorize: time 2.92e-05/0.5872, allocations: 8 kB / 0.8507 GB, free: 5.035 MB / 0.698 GB
Notification: Performance of [SIM] Solve: time 0.003198/0.5904, allocations: 2.799 MB / 0.8534 GB, free: 2.227 MB / 0.698 GB
Notification: Performance of [SIM] Jacobian: time 0.003797/0.5942, allocations: 4.857 MB / 0.8582 GB, free: 12.99 MB / 0.7136 GB
Notification: Performance of [SIM] Minimize Homotopy System: time 1.212e-06/0.5942, allocations: 0 / 0.8582 GB, free: 12.99 MB / 0.7136 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 4
 * 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) {volDyn.U, volDyn.Xi, volDyn.mC}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 2 (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: ........... 140 (scalar:121, array:19, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 8 (resizable: 8, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 3 (linear: 3, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 218 (scalar:179, array:39, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 8 (resizable: 8, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 4 (linear: 4, nonlinear:0)
Notification: Performance of backend: time 9.059e-05/0.5943, allocations: 66.66 kB / 0.8582 GB, free: 12.93 MB / 0.7136 GB
Error: Internal error NSimStrongComponent.Block.createEquation failed with status Solve.EXPLICIT for
[FOR-] (2) ($RES_SIM_45)
[----] for $i1 in 1:2 loop
[----]   [SCAL] (1) $RES_SIM_45[$i1] = volSte.hOut_internal - volSte.dynBal.ports[$i1].h_outflow ($RES_SIM_46)
[----] end for;
"
[Timeout remaining time 659]
[Calling sys.exit(0), Time elapsed: 3.3720261082053185]
Failed to read output from testmodel.py, exit status != 0:
0.6068705022335052 0.6171237350000001 0.069785866
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7486535ddb20>