Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.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.0012/0.0012, allocations: 104.3 kB / 19.72 MB, free: 2.145 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.001444/0.001444, allocations: 216.2 kB / 23 MB, free: 4.973 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.344/1.344, allocations: 230.6 MB / 256.8 MB, free: 7.805 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo): time 2.91/2.91, allocations: 0.49 GB / 0.7958 GB, free: 35.32 MB / 0.6231 GB
"
[Timeout remaining time 177]
Using package Buildings with version 12.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/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(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow,tolerance=1e-08,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|volDyn.ports.1..m_flow|gain.y|volDyn.C.1.|volSte.C.1.",fileNamePrefix="Buildings_12_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow")
translateModel(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow,tolerance=1e-08,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|volDyn.ports.1..m_flow|gain.y|volDyn.C.1.|volSte.C.1.",fileNamePrefix="Buildings_12_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.854e-05/1.854e-05, allocations: 2.281 kB / 1.155 GB, free: 25.24 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow): time 0.2147/0.2147, allocations: 168.5 MB / 1.32 GB, free: 8.387 MB / 1.014 GB
Notification: Performance of NFInst.instExpressions: time 0.01005/0.2248, allocations: 5.426 MB / 1.325 GB, free: 2.953 MB / 1.014 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001399/0.2262, allocations: 55.5 kB / 1.325 GB, free: 2.898 MB / 1.014 GB
Notification: Performance of NFTyping.typeComponents: time 0.001629/0.2278, allocations: 0.5436 MB / 1.325 GB, free: 2.352 MB / 1.014 GB
Notification: Performance of NFTyping.typeBindings: time 0.005148/0.2329, allocations: 1.925 MB / 1.327 GB, free: 428 kB / 1.014 GB
Notification: Performance of NFTyping.typeClassSections: time 0.00342/0.2363, allocations: 1.213 MB / 1.328 GB, free: 15.21 MB / 1.029 GB
Notification: Performance of NFFlatten.flatten: time 0.00512/0.2415, allocations: 3.529 MB / 1.332 GB, free: 11.67 MB / 1.029 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001721/0.2432, allocations: 0.9668 MB / 1.333 GB, free: 10.68 MB / 1.029 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003201/0.2464, allocations: 1.569 MB / 1.334 GB, free: 9.109 MB / 1.029 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002684/0.2491, allocations: 1.037 MB / 1.335 GB, free: 8.074 MB / 1.029 GB
Notification: Performance of NFPackage.collectConstants: time 0.0002472/0.2493, allocations: 116 kB / 1.336 GB, free: 7.961 MB / 1.029 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002693/0.252, allocations: 1.18 MB / 1.337 GB, free: 6.777 MB / 1.029 GB
Notification: Performance of combineBinaries: time 0.002929/0.2549, allocations: 2.879 MB / 1.339 GB, free: 3.875 MB / 1.029 GB
Notification: Performance of replaceArrayConstructors: time 0.001676/0.2566, allocations: 1.789 MB / 1.341 GB, free: 2.07 MB / 1.029 GB
Notification: Performance of NFVerifyModel.verify: time 0.000319/0.2569, allocations: 120 kB / 1.341 GB, free: 1.953 MB / 1.029 GB
Notification: Performance of FrontEnd: time 0.0002641/0.2572, allocations: 51.72 kB / 1.341 GB, free: 1.902 MB / 1.029 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.007732/0.2649, allocations: 7.069 MB / 1.348 GB, free: 10.62 MB / 1.045 GB
Notification: Performance of [SIM] FunctionAlias: time 0.001006/0.2659, allocations: 0.8043 MB / 1.349 GB, free: 9.785 MB / 1.045 GB
Notification: Performance of [SIM] Early Inline: time 0.003709/0.2697, allocations: 3.428 MB / 1.352 GB, free: 6.309 MB / 1.045 GB
Notification: Performance of [SIM] Simplify 1: time 0.00109/0.2707, allocations: 0.6302 MB / 1.353 GB, free: 5.602 MB / 1.045 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.007845/0.2786, allocations: 4.938 MB / 1.358 GB, free: 236 kB / 1.045 GB
Notification: Performance of [SIM] Simplify 2: time 0.0008652/0.2795, allocations: 0.5004 MB / 1.358 GB, free: 15.66 MB / 1.061 GB
Notification: Performance of [SIM] Remove Stream: time 0.000413/0.2799, allocations: 329.3 kB / 1.359 GB, free: 15.3 MB / 1.061 GB
Notification: Performance of [SIM] Detect States: time 0.0007809/0.2806, allocations: 0.6482 MB / 1.359 GB, free: 14.6 MB / 1.061 GB
Notification: Performance of [SIM] Events: time 0.0003279/0.281, allocations: 228.8 kB / 1.36 GB, free: 14.37 MB / 1.061 GB
Notification: Performance of [SIM] Partitioning: time 0.001125/0.2821, allocations: 0.9829 MB / 1.36 GB, free: 13.36 MB / 1.061 GB
Notification: Performance of [SIM] Causalize: time 0.007398/0.2895, allocations: 5.041 MB / 1.365 GB, free: 8.199 MB / 1.061 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.002406/0.2919, allocations: 2.233 MB / 1.368 GB, free: 5.965 MB / 1.061 GB
Notification: Performance of [INI] Simplify: time 0.003075/0.295, allocations: 1.633 MB / 1.369 GB, free: 4.219 MB / 1.061 GB
Notification: Performance of [INI] Inline: time 0.004397/0.2994, allocations: 4.157 MB / 1.373 GB, free: 52 kB / 1.061 GB
Notification: Performance of [INI] Partitioning: time 0.0001826/0.2996, allocations: 71.98 kB / 1.373 GB, free: 15.96 MB / 1.076 GB
Notification: Performance of [INI] Cleanup: time 0.0007907/0.3003, allocations: 0.5385 MB / 1.374 GB, free: 15.43 MB / 1.076 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_329);
Notification: Performance of [INI] Causalize: time 0.008902/0.3092, allocations: 6.239 MB / 1.38 GB, free: 9.086 MB / 1.076 GB
Notification: Performance of [INI] Tearing: time 0.0002473/0.3095, allocations: 208.6 kB / 1.38 GB, free: 8.887 MB / 1.076 GB
Notification: Performance of [SIM] Initialization: time 1.624e-06/0.3095, allocations: 0 / 1.38 GB, free: 8.887 MB / 1.076 GB
Notification: Performance of [SIM] Remove Dummies: time 2.3e-05/0.3095, allocations: 1.312 kB / 1.38 GB, free: 8.883 MB / 1.076 GB
Notification: Performance of [SIM] Tearing: time 0.0001991/0.3097, allocations: 177.8 kB / 1.38 GB, free: 8.711 MB / 1.076 GB
Notification: Performance of [SIM] Categorize: time 3.614e-05/0.3098, allocations: 4 kB / 1.38 GB, free: 8.707 MB / 1.076 GB
Notification: Performance of [SIM] Solve: time 0.002999/0.3128, allocations: 1.907 MB / 1.382 GB, free: 6.805 MB / 1.076 GB
Notification: Performance of [SIM] Jacobian: time 0.002884/0.3156, allocations: 2.232 MB / 1.384 GB, free: 4.383 MB / 1.076 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 2
 * 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: ................. 8 (8) {$SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $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: ........... 135 (scalar:117, array:18, 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: ........... 181 (scalar:149, array:32, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:2)
 * 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: Performance of backend: time 8.769e-05/0.3157, allocations: 75.88 kB / 1.384 GB, free: 4.316 MB / 1.076 GB
Notification: Performance of SimCode: time 0.01268/0.3284, allocations: 8.7 MB / 1.393 GB, free: 11.5 MB / 1.092 GB
Notification: Performance of Templates: time 0.03129/0.3597, allocations: 18.97 MB / 1.411 GB, free: 8.664 MB / 1.107 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_12_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow.pipe ; mkfifo Buildings_12_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow.pipe ; head -c 1048576 < Buildings_12_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow.pipe >> ../files/Buildings_12_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow.sim & ./Buildings_12_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 8.756165677215904]
Failed to read output from testmodel.py, exit status != 0:
0.36789682786911726 0.381572574 0.121336754
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f63f28aaf20>