Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionVolume.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.00124/0.00124, allocations: 113.9 kB / 21.18 MB, free: 0.6289 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001353/0.001353, allocations: 209.8 kB / 24.48 MB, free: 4.035 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.435/1.435, allocations: 230.2 MB / 257.8 MB, free: 8.504 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo): time 0.223/0.223, allocations: 46.89 MB / 361.1 MB, free: 4.629 MB / 286.1 MB
"
[Timeout remaining time 180]
Using package ModelicaTest with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo)
Using package Modelica with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo)
Using package Complex with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo)
Running command: translateModel(ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionVolume,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5003,variableFilter="time|junction.medium.T|junction.medium.p",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionVolume")
translateModel(ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionVolume,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5003,variableFilter="time|junction.medium.T|junction.medium.p",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionVolume") [Timeout 660]
"Warning: Requested package Modelica of version 4.1.0, but this package was already loaded with version trunk. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Notification: Performance of FrontEnd - loaded program: time 0.002311/0.002311, allocations: 75.88 kB / 0.4968 GB, free: 7.816 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3092/0.3116, allocations: 65.99 MB / 0.5612 GB, free: 5.035 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionVolume): time 0.5828/0.8944, allocations: 204.1 MB / 0.7605 GB, free: 1.652 MB / 0.5606 GB
Notification: Performance of NFInst.instExpressions: time 0.01186/0.9062, allocations: 11.29 MB / 0.7715 GB, free: 1.52 MB / 0.5606 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001259/0.9075, allocations: 38.31 kB / 0.7716 GB, free: 1.52 MB / 0.5606 GB
Notification: Performance of NFTyping.typeComponents: time 0.001106/0.9086, allocations: 489.6 kB / 0.772 GB, free: 1.512 MB / 0.5606 GB
Notification: Performance of NFTyping.typeBindings: time 0.006228/0.9148, allocations: 2.58 MB / 0.7746 GB, free: 1.418 MB / 0.5606 GB
Notification: Performance of NFTyping.typeClassSections: time 0.007202/0.922, allocations: 3.373 MB / 0.7778 GB, free: 1.324 MB / 0.5606 GB
Notification: Performance of NFFlatten.flatten: time 0.003688/0.9257, allocations: 3.251 MB / 0.781 GB, free: 1.32 MB / 0.5606 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001025/0.9267, allocations: 0.7014 MB / 0.7817 GB, free: 1.277 MB / 0.5606 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.00206/0.9288, allocations: 1.297 MB / 0.783 GB, free: 1.277 MB / 0.5606 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001801/0.9306, allocations: 1.42 MB / 0.7844 GB, free: 1.273 MB / 0.5606 GB
Notification: Performance of NFPackage.collectConstants: time 0.000188/0.9308, allocations: 119.7 kB / 0.7845 GB, free: 1.27 MB / 0.5606 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.005468/0.9363, allocations: 2.859 MB / 0.7873 GB, free: 1.242 MB / 0.5606 GB
Notification: Performance of NFScalarize.scalarize: time 0.0005169/0.9368, allocations: 442.9 kB / 0.7877 GB, free: 1.238 MB / 0.5606 GB
Notification: Performance of NFVerifyModel.verify: time 0.0007245/0.9375, allocations: 0.6449 MB / 0.7883 GB, free: 1.109 MB / 0.5606 GB
Notification: Performance of NFConvertDAE.convert: time 0.005796/0.9433, allocations: 3.434 MB / 0.7917 GB, free: 0.7539 MB / 0.5606 GB
Notification: Performance of FrontEnd - DAE generated: time 3.346e-06/0.9433, allocations: 0.875 kB / 0.7917 GB, free: 0.7539 MB / 0.5606 GB
Notification: Performance of FrontEnd: time 1.743e-06/0.9433, allocations: 0 / 0.7917 GB, free: 0.7539 MB / 0.5606 GB
Notification: Performance of Transformations before backend: time 1.517e-05/0.9433, allocations: 0 / 0.7917 GB, free: 0.7539 MB / 0.5606 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 192
 * Number of variables: 192
Notification: Performance of Generate backend data structure: time 0.003778/0.9471, allocations: 1.941 MB / 0.7936 GB, free: 15.72 MB / 0.5762 GB
Notification: Performance of prepare preOptimizeDAE: time 3.529e-05/0.9471, allocations: 8.25 kB / 0.7936 GB, free: 15.72 MB / 0.5762 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001194/0.9483, allocations: 230 kB / 0.7938 GB, free: 15.65 MB / 0.5762 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001391/0.9497, allocations: 0.8455 MB / 0.7946 GB, free: 15.32 MB / 0.5762 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 4.864e-05/0.9498, allocations: 59.34 kB / 0.7947 GB, free: 15.32 MB / 0.5762 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001364/0.9499, allocations: 74.66 kB / 0.7948 GB, free: 15.32 MB / 0.5762 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001821/0.9517, allocations: 1.239 MB / 0.796 GB, free: 15.14 MB / 0.5762 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.198e-05/0.9518, allocations: 1.062 kB / 0.796 GB, free: 15.14 MB / 0.5762 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001129/0.9519, allocations: 30.31 kB / 0.796 GB, free: 15.14 MB / 0.5762 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 9.933e-05/0.952, allocations: 82.98 kB / 0.7961 GB, free: 15.14 MB / 0.5762 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001752/0.9537, allocations: 1.218 MB / 0.7973 GB, free: 15.12 MB / 0.5762 GB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.007382/0.9611, allocations: 4.83 MB / 0.802 GB, free: 12.87 MB / 0.5762 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001206/0.9623, allocations: 0.7254 MB / 0.8027 GB, free: 12.83 MB / 0.5762 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0005611/0.9629, allocations: 362.7 kB / 0.803 GB, free: 12.82 MB / 0.5762 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003495/0.9632, allocations: 354.4 kB / 0.8034 GB, free: 12.45 MB / 0.5762 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.736e-05/0.9633, allocations: 47.31 kB / 0.8034 GB, free: 12.41 MB / 0.5762 GB
Notification: Performance of pre-optimization done (n=68): time 2.074e-06/0.9633, allocations: 0 / 0.8034 GB, free: 12.41 MB / 0.5762 GB
Notification: Performance of matching and sorting (n=73): time 0.007627/0.9709, allocations: 2.83 MB / 0.8062 GB, free: 11.66 MB / 0.5762 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001472/0.971, allocations: 219.2 kB / 0.8064 GB, free: 11.4 MB / 0.5762 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001353/0.9724, allocations: 0.9415 MB / 0.8073 GB, free: 11.11 MB / 0.5762 GB
Notification: Performance of collectPreVariables (initialization): time 0.000103/0.9725, allocations: 42.67 kB / 0.8073 GB, free: 11.07 MB / 0.5762 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003506/0.9728, allocations: 489.9 kB / 0.8078 GB, free: 10.83 MB / 0.5762 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002502/0.9731, allocations: 274.8 kB / 0.8081 GB, free: 10.63 MB / 0.5762 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003468/0.9734, allocations: 222.9 kB / 0.8083 GB, free: 10.59 MB / 0.5762 GB
Notification: Performance of setup shared object (initialization): time 0.0001592/0.9736, allocations: 345.4 kB / 0.8086 GB, free: 10.28 MB / 0.5762 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0007571/0.9743, allocations: 444.5 kB / 0.809 GB, free: 10.2 MB / 0.5762 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0009469/0.9753, allocations: 0.6819 MB / 0.8097 GB, free: 9.855 MB / 0.5762 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001599/0.9769, allocations: 1.083 MB / 0.8108 GB, free: 9.535 MB / 0.5762 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.128e-06/0.9769, allocations: 3.938 kB / 0.8108 GB, free: 9.531 MB / 0.5762 GB
Notification: Performance of matching and sorting (n=120) (initialization): time 0.002475/0.9794, allocations: 1.486 MB / 0.8122 GB, free: 9.262 MB / 0.5762 GB
Notification: Performance of prepare postOptimizeDAE: time 2.003e-05/0.9794, allocations: 8.25 kB / 0.8122 GB, free: 9.262 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.278e-05/0.9794, allocations: 10.27 kB / 0.8122 GB, free: 9.258 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003313/0.9827, allocations: 0.718 MB / 0.8129 GB, free: 9.074 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006412/0.9834, allocations: 184.5 kB / 0.8131 GB, free: 9.051 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001596/0.985, allocations: 1.286 MB / 0.8144 GB, free: 8.418 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.000881/0.9858, allocations: 43.5 kB / 0.8144 GB, free: 8.41 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001309/0.986, allocations: 95.38 kB / 0.8145 GB, free: 8.402 MB / 0.5762 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.00104/0.987, allocations: 0.6173 MB / 0.8151 GB, free: 8.242 MB / 0.5762 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0009357/0.9879, allocations: 0.6668 MB / 0.8158 GB, free: 7.816 MB / 0.5762 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.001705/0.9896, allocations: 1.024 MB / 0.8168 GB, free: 6.887 MB / 0.5762 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 6.241e-06/0.9897, allocations: 4 kB / 0.8168 GB, free: 6.883 MB / 0.5762 GB
Notification: Performance of matching and sorting (n=120) (initialization_lambda0): time 0.002571/0.9922, allocations: 1.329 MB / 0.8181 GB, free: 5.59 MB / 0.5762 GB
Notification: Performance of prepare postOptimizeDAE: time 1.691e-05/0.9922, allocations: 11.98 kB / 0.8181 GB, free: 5.578 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.336e-06/0.9923, allocations: 4 kB / 0.8181 GB, free: 5.574 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001514/0.9938, allocations: 368.9 kB / 0.8184 GB, free: 5.219 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007448/0.9945, allocations: 208.4 kB / 0.8186 GB, free: 5.027 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001191/0.9957, allocations: 0.9783 MB / 0.8196 GB, free: 4.031 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0008103/0.9965, allocations: 48.8 kB / 0.8196 GB, free: 3.984 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001494/0.9967, allocations: 88.73 kB / 0.8197 GB, free: 3.898 MB / 0.5762 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 28
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (91):
 * Single equations (assignments): 90
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(2,28)}
Notification: Performance of prepare postOptimizeDAE: time 0.0003864/0.997, allocations: 242.3 kB / 0.8199 GB, free: 3.664 MB / 0.5762 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002319/0.9973, allocations: 120.2 kB / 0.8201 GB, free: 3.547 MB / 0.5762 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003473/1.001, allocations: 1.899 MB / 0.8219 GB, free: 1.656 MB / 0.5762 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.021e-05/1.001, allocations: 3.984 kB / 0.8219 GB, free: 1.652 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.815e-06/1.001, allocations: 0 / 0.8219 GB, free: 1.652 MB / 0.5762 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002898/1.001, allocations: 184.3 kB / 0.8221 GB, free: 1.453 MB / 0.5762 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001799/1.001, allocations: 15.98 kB / 0.8221 GB, free: 1.438 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0005808/1.002, allocations: 39.97 kB / 0.8221 GB, free: 1.398 MB / 0.5762 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001423/1.002, allocations: 56.59 kB / 0.8222 GB, free: 1.344 MB / 0.5762 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.002189/1.004, allocations: 1.013 MB / 0.8232 GB, free: 344 kB / 0.5762 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.002745/1.007, allocations: 1.63 MB / 0.8248 GB, free: 14.69 MB / 0.5919 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0007033/1.008, allocations: 385 kB / 0.8251 GB, free: 14.31 MB / 0.5919 GB
Notification: Performance of sorting global known variables: time 0.0009816/1.009, allocations: 0.6535 MB / 0.8258 GB, free: 13.65 MB / 0.5919 GB
Notification: Performance of Backend: time 3.9e-07/1.009, allocations: 0 / 0.8258 GB, free: 13.65 MB / 0.5919 GB
Notification: Performance of simCode: created initialization part: time 0.005981/1.015, allocations: 2.227 MB / 0.828 GB, free: 11.53 MB / 0.5919 GB
Notification: Performance of SimCode: time 0.00438/1.019, allocations: 2.579 MB / 0.8305 GB, free: 8.914 MB / 0.5919 GB
Notification: Performance of Templates: time 0.06709/1.086, allocations: 43.2 MB / 0.8727 GB, free: 13.96 MB / 0.6387 GB
"
[Timeout remaining time 659]
make -j1 -f ModelicaTest_4.1.0-beta.om_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionVolume.makefile [Timeout 660]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionVolume.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionVolume.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionVolume.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionVolume.sim & ./ModelicaTest_4.1.0-beta.om_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionVolume  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0-beta.om_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionVolume.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 6.741717577911913]
Failed to read output from testmodel.py, exit status != 0:
['time', 'junction.medium.T', 'junction.medium.p']
1.0943118971772492 1.107955518 0.161536928
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f1cf554a050>