Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionIdeal.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.00177/0.00177, allocations: 110.8 kB / 21.19 MB, free: 0.6641 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.002207/0.002207, allocations: 205.2 kB / 24.48 MB, free: 3.969 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.421/1.421, allocations: 230.6 MB / 258.3 MB, free: 7.816 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0+maint.om/package.mo): time 0.2173/0.2173, allocations: 46.97 MB / 361.6 MB, free: 4.129 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+maint.om/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(ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionIdeal,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5004,variableFilter="time|ramp.y|pipe1.port_b.m_flow|pipe1.port_b.p|pipe1.port_b.h_outflow|pipe2.port_b.m_flow|pipe2.port_b.p|pipe2.port_b.h_outflow|pipe3.port_b.m_flow|pipe3.port_b.p|pipe3.port_b.h_outflow",fileNamePrefix="ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionIdeal")
translateModel(ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionIdeal,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5004,variableFilter="time|ramp.y|pipe1.port_b.m_flow|pipe1.port_b.p|pipe1.port_b.h_outflow|pipe2.port_b.m_flow|pipe2.port_b.p|pipe2.port_b.h_outflow|pipe3.port_b.m_flow|pipe3.port_b.p|pipe3.port_b.h_outflow",fileNamePrefix="ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionIdeal") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.002218/0.002218, allocations: 63.91 kB / 0.4973 GB, free: 7.234 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3264/0.3286, allocations: 66.04 MB / 0.5618 GB, free: 4.172 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionIdeal): time 0.2161/0.5447, allocations: 174.6 MB / 0.7323 GB, free: 4.965 MB / 0.5606 GB
Notification: Performance of NFInst.instExpressions: time 0.3061/0.8508, allocations: 9.836 MB / 0.7419 GB, free: 1.816 MB / 0.5606 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0009316/0.8517, allocations: 49.69 kB / 0.7419 GB, free: 1.816 MB / 0.5606 GB
Notification: Performance of NFTyping.typeComponents: time 0.00104/0.8528, allocations: 428 kB / 0.7424 GB, free: 1.816 MB / 0.5606 GB
Notification: Performance of NFTyping.typeBindings: time 0.005178/0.858, allocations: 2.341 MB / 0.7446 GB, free: 1.812 MB / 0.5606 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006924/0.8649, allocations: 3.319 MB / 0.7479 GB, free: 1.746 MB / 0.5606 GB
Notification: Performance of NFFlatten.flatten: time 0.003215/0.8681, allocations: 3.077 MB / 0.7509 GB, free: 1.742 MB / 0.5606 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0009078/0.869, allocations: 0.6826 MB / 0.7516 GB, free: 1.699 MB / 0.5606 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001787/0.8708, allocations: 1.216 MB / 0.7527 GB, free: 1.695 MB / 0.5606 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001613/0.8724, allocations: 1.352 MB / 0.7541 GB, free: 1.691 MB / 0.5606 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001705/0.8726, allocations: 107.9 kB / 0.7542 GB, free: 1.691 MB / 0.5606 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.003856/0.8764, allocations: 2.117 MB / 0.7562 GB, free: 1.668 MB / 0.5606 GB
Notification: Performance of NFScalarize.scalarize: time 0.0004017/0.8768, allocations: 406.7 kB / 0.7566 GB, free: 1.668 MB / 0.5606 GB
Notification: Performance of NFVerifyModel.verify: time 0.0007882/0.8776, allocations: 0.4992 MB / 0.7571 GB, free: 1.668 MB / 0.5606 GB
Notification: Performance of NFConvertDAE.convert: time 0.005083/0.8827, allocations: 3.049 MB / 0.7601 GB, free: 1.473 MB / 0.5606 GB
Notification: Performance of FrontEnd - DAE generated: time 6.282e-06/0.8827, allocations: 5.266 kB / 0.7601 GB, free: 1.473 MB / 0.5606 GB
Notification: Performance of FrontEnd: time 1.463e-06/0.8827, allocations: 0 / 0.7601 GB, free: 1.473 MB / 0.5606 GB
Notification: Performance of Transformations before backend: time 2.348e-05/0.8827, allocations: 0 / 0.7601 GB, free: 1.473 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: 173
 * Number of variables: 173
Notification: Performance of Generate backend data structure: time 0.003697/0.8864, allocations: 1.745 MB / 0.7618 GB, free: 16.6 MB / 0.5762 GB
Notification: Performance of prepare preOptimizeDAE: time 4.803e-05/0.8865, allocations: 8.188 kB / 0.7618 GB, free: 16.6 MB / 0.5762 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001149/0.8876, allocations: 210.9 kB / 0.762 GB, free: 16.54 MB / 0.5762 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001255/0.8889, allocations: 0.726 MB / 0.7627 GB, free: 16.29 MB / 0.5762 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.938e-05/0.8889, allocations: 50.12 kB / 0.7628 GB, free: 16.29 MB / 0.5762 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001284/0.8891, allocations: 66.12 kB / 0.7628 GB, free: 16.29 MB / 0.5762 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001903/0.891, allocations: 1.533 MB / 0.7643 GB, free: 15.67 MB / 0.5762 GB
Notification: Performance of preOpt findStateOrder (simulation): time 1.937e-05/0.891, allocations: 3.094 kB / 0.7643 GB, free: 15.67 MB / 0.5762 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001051/0.8911, allocations: 32.56 kB / 0.7644 GB, free: 15.67 MB / 0.5762 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.99e-05/0.8912, allocations: 82.67 kB / 0.7644 GB, free: 15.67 MB / 0.5762 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001698/0.8929, allocations: 1.142 MB / 0.7655 GB, free: 15.67 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.006425/0.8993, allocations: 3.404 MB / 0.7689 GB, free: 14.59 MB / 0.5762 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001191/0.9005, allocations: 0.7156 MB / 0.7696 GB, free: 14.56 MB / 0.5762 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0005261/0.901, allocations: 340.7 kB / 0.7699 GB, free: 14.55 MB / 0.5762 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001715/0.9012, allocations: 48.62 kB / 0.7699 GB, free: 14.55 MB / 0.5762 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.403e-05/0.9012, allocations: 43.66 kB / 0.77 GB, free: 14.51 MB / 0.5762 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.0004494/0.9017, allocations: 98.8 kB / 0.7701 GB, free: 14.48 MB / 0.5762 GB
Notification: Performance of pre-optimization done (n=61): time 2.655e-06/0.9017, allocations: 3.344 kB / 0.7701 GB, free: 14.48 MB / 0.5762 GB
Notification: Performance of matching and sorting (n=61): time 0.006156/0.9078, allocations: 1.934 MB / 0.772 GB, free: 14.38 MB / 0.5762 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001197/0.9079, allocations: 215.5 kB / 0.7722 GB, free: 14.12 MB / 0.5762 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0007655/0.9087, allocations: 0.7433 MB / 0.7729 GB, free: 13.97 MB / 0.5762 GB
Notification: Performance of collectPreVariables (initialization): time 8.021e-05/0.9088, allocations: 37.7 kB / 0.7729 GB, free: 13.94 MB / 0.5762 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002937/0.9091, allocations: 463.6 kB / 0.7734 GB, free: 13.71 MB / 0.5762 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002049/0.9093, allocations: 225.2 kB / 0.7736 GB, free: 13.57 MB / 0.5762 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003146/0.9096, allocations: 197.9 kB / 0.7738 GB, free: 13.55 MB / 0.5762 GB
Notification: Performance of setup shared object (initialization): time 0.0001879/0.9098, allocations: 391.1 kB / 0.7742 GB, free: 13.23 MB / 0.5762 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0006632/0.9105, allocations: 414.2 kB / 0.7745 GB, free: 13.23 MB / 0.5762 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0009135/0.9114, allocations: 0.7672 MB / 0.7753 GB, free: 12.8 MB / 0.5762 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001434/0.9128, allocations: 0.9846 MB / 0.7763 GB, free: 12.62 MB / 0.5762 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.48e-06/0.9128, allocations: 7.922 kB / 0.7763 GB, free: 12.62 MB / 0.5762 GB
Notification: Performance of matching and sorting (n=101) (initialization): time 0.002223/0.915, allocations: 1.323 MB / 0.7776 GB, free: 12.52 MB / 0.5762 GB
Notification: Performance of prepare postOptimizeDAE: time 1.847e-05/0.915, allocations: 6.344 kB / 0.7776 GB, free: 12.52 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.488e-06/0.9151, allocations: 6.188 kB / 0.7776 GB, free: 12.52 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.004972/0.92, allocations: 0.8469 MB / 0.7784 GB, free: 12.51 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006121/0.9206, allocations: 165.2 kB / 0.7786 GB, free: 12.51 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001618/0.9223, allocations: 1.2 MB / 0.7797 GB, free: 11.97 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0007393/0.923, allocations: 44.45 kB / 0.7798 GB, free: 11.97 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001247/0.9231, allocations: 70.8 kB / 0.7798 GB, free: 11.97 MB / 0.5762 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0009766/0.9241, allocations: 0.5701 MB / 0.7804 GB, free: 11.89 MB / 0.5762 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0009197/0.925, allocations: 0.7487 MB / 0.7811 GB, free: 11.45 MB / 0.5762 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.001464/0.9265, allocations: 0.9385 MB / 0.782 GB, free: 11.22 MB / 0.5762 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 6.062e-06/0.9265, allocations: 2.906 kB / 0.782 GB, free: 11.22 MB / 0.5762 GB
Notification: Performance of matching and sorting (n=101) (initialization_lambda0): time 0.002315/0.9288, allocations: 1.227 MB / 0.7832 GB, free: 11.12 MB / 0.5762 GB
Notification: Performance of prepare postOptimizeDAE: time 1.179e-05/0.9288, allocations: 5.781 kB / 0.7832 GB, free: 11.12 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.722e-06/0.9288, allocations: 9.25 kB / 0.7833 GB, free: 11.12 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.00063/0.9295, allocations: 167.1 kB / 0.7834 GB, free: 11.11 MB / 0.5762 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007147/0.9302, allocations: 200.6 kB / 0.7836 GB, free: 11.11 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00259/0.9328, allocations: 2.173 MB / 0.7857 GB, free: 9.898 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0007397/0.9335, allocations: 44.56 kB / 0.7858 GB, free: 9.898 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001258/0.9336, allocations: 74.09 kB / 0.7858 GB, free: 9.898 MB / 0.5762 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 23
 * 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 (80):
 * Single equations (assignments): 79
 * 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
   {(3,19)}
Notification: Performance of prepare postOptimizeDAE: time 0.0004222/0.934, allocations: 227 kB / 0.7861 GB, free: 9.715 MB / 0.5762 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001982/0.9342, allocations: 96.78 kB / 0.7862 GB, free: 9.664 MB / 0.5762 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003394/0.9376, allocations: 1.893 MB / 0.788 GB, free: 9.523 MB / 0.5762 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.03e-05/0.9376, allocations: 5.344 kB / 0.788 GB, free: 9.523 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.845e-06/0.9377, allocations: 0.9375 kB / 0.788 GB, free: 9.523 MB / 0.5762 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002563/0.9379, allocations: 75.55 kB / 0.7881 GB, free: 9.477 MB / 0.5762 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001634/0.9381, allocations: 20.64 kB / 0.7881 GB, free: 9.473 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0004975/0.9386, allocations: 28.16 kB / 0.7881 GB, free: 9.473 MB / 0.5762 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001386/0.9387, allocations: 49.78 kB / 0.7882 GB, free: 9.469 MB / 0.5762 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.002702/0.9414, allocations: 1.187 MB / 0.7893 GB, free: 9.328 MB / 0.5762 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.002165/0.9436, allocations: 1.431 MB / 0.7907 GB, free: 9.043 MB / 0.5762 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0005284/0.9441, allocations: 354.7 kB / 0.7911 GB, free: 9.043 MB / 0.5762 GB
Notification: Performance of sorting global known variables: time 0.0006175/0.9447, allocations: 0.5083 MB / 0.7916 GB, free: 8.953 MB / 0.5762 GB
Notification: Performance of Backend: time 3.6e-07/0.9447, allocations: 0 / 0.7916 GB, free: 8.953 MB / 0.5762 GB
Notification: Performance of simCode: created initialization part: time 0.005098/0.9498, allocations: 2.113 MB / 0.7936 GB, free: 8.566 MB / 0.5762 GB
Notification: Performance of SimCode: time 0.003725/0.9535, allocations: 2.438 MB / 0.796 GB, free: 7.355 MB / 0.5762 GB
Notification: Performance of Templates: time 0.05705/1.011, allocations: 36.24 MB / 0.8314 GB, free: 11.8 MB / 0.6075 GB
"
[Timeout remaining time 659]
make -j1 -f ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionIdeal.makefile [Timeout 660]
(rm -f ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionIdeal.pipe ; mkfifo ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionIdeal.pipe ; head -c 1048576 < ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionIdeal.pipe >> ../files/ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionIdeal.sim & ./ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionIdeal  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0_ModelicaTest.Fluid.TestComponents.Fittings.TestJunctionIdeal.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 6.709140194005158]
Failed to read output from testmodel.py, exit status != 0:
['time', 'ramp.y', 'pipe1.port_b.m_flow', 'pipe1.port_b.p', 'pipe1.port_b.h_outflow', 'pipe2.port_b.m_flow', 'pipe2.port_b.p', 'pipe2.port_b.h_outflow', 'pipe3.port_b.m_flow', 'pipe3.port_b.p', 'pipe3.port_b.h_outflow']
1.0194855759982602 1.032521685 0.146519469
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f1a00fb6f50>