Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Valves.TestValveDiscrete.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.001471/0.001471, allocations: 109.1 kB / 21.22 MB, free: 0.7539 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.00184/0.00184, allocations: 216.9 kB / 24.53 MB, free: 4.012 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.373/1.373, allocations: 230.5 MB / 258.2 MB, free: 8.008 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo): time 0.2137/0.2137, allocations: 46.93 MB / 361.6 MB, free: 4.219 MB / 286.1 MB
"
[Timeout remaining time 180]
Using package ModelicaTest with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/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: "" <> buildModelFMU(ModelicaTest.Fluid.TestComponents.Valves.TestValveDiscrete,fileNamePrefix="ModelicaTest_trunk_ModelicaTest_Fluid_TestComponents_Valves_TestValveDiscrete",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.Fluid.TestComponents.Valves.TestValveDiscrete,fileNamePrefix="ModelicaTest_trunk_ModelicaTest_Fluid_TestComponents_Valves_TestValveDiscrete",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.002156/0.002156, allocations: 79.88 kB / 0.497 GB, free: 7.789 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2859/0.2881, allocations: 66.08 MB / 0.5615 GB, free: 4.355 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Fluid.TestComponents.Valves.TestValveDiscrete): time 0.03226/0.3204, allocations: 28.65 MB / 0.5895 GB, free: 7.605 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01679/0.3371, allocations: 13.39 MB / 0.6026 GB, free: 10.17 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.000319/0.3375, allocations: 11.88 kB / 0.6026 GB, free: 10.16 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0007427/0.3382, allocations: 254.4 kB / 0.6028 GB, free: 9.906 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.008702/0.3469, allocations: 3.675 MB / 0.6064 GB, free: 6.234 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.005749/0.3527, allocations: 2.399 MB / 0.6087 GB, free: 3.836 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.001151/0.3538, allocations: 0.8964 MB / 0.6096 GB, free: 2.938 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0003569/0.3542, allocations: 216.9 kB / 0.6098 GB, free: 2.719 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0007402/0.3549, allocations: 454.2 kB / 0.6103 GB, free: 2.273 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0003673/0.3553, allocations: 282.8 kB / 0.6105 GB, free: 1.996 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 5.398e-05/0.3553, allocations: 40 kB / 0.6106 GB, free: 1.957 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.01285/0.3682, allocations: 7.254 MB / 0.6176 GB, free: 10.69 MB / 462.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0001544/0.3683, allocations: 103.7 kB / 0.6177 GB, free: 10.59 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0002816/0.3686, allocations: 227 kB / 0.618 GB, free: 10.37 MB / 462.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.008313/0.3769, allocations: 4.427 MB / 0.6223 GB, free: 5.93 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.278e-06/0.3769, allocations: 0 / 0.6223 GB, free: 5.93 MB / 462.1 MB
Notification: Performance of FrontEnd: time 1.333e-06/0.3769, allocations: 0 / 0.6223 GB, free: 5.93 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 7.865e-06/0.3769, allocations: 0 / 0.6223 GB, free: 5.93 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 73
 * Number of variables: 73
Notification: Performance of Generate backend data structure: time 0.002919/0.3799, allocations: 1.196 MB / 0.6235 GB, free: 4.699 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.717e-05/0.3799, allocations: 12.03 kB / 0.6235 GB, free: 4.688 MB / 462.1 MB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 1.809e-05/0.3799, allocations: 12.78 kB / 0.6235 GB, free: 4.676 MB / 462.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 8.804e-05/0.38, allocations: 70.73 kB / 0.6235 GB, free: 4.598 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001646/0.3816, allocations: 212.5 kB / 0.6237 GB, free: 4.391 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0005387/0.3822, allocations: 321.2 kB / 0.6241 GB, free: 4.047 MB / 462.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.511e-05/0.3822, allocations: 25.03 kB / 0.6241 GB, free: 4.023 MB / 462.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 4.883e-05/0.3823, allocations: 31.94 kB / 0.6241 GB, free: 3.992 MB / 462.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001551/0.3838, allocations: 0.5785 MB / 0.6247 GB, free: 3.371 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.089e-05/0.3838, allocations: 4 kB / 0.6247 GB, free: 3.367 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 4.04e-05/0.3839, allocations: 12 kB / 0.6247 GB, free: 3.355 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.156e-05/0.3839, allocations: 11.98 kB / 0.6247 GB, free: 3.344 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001585/0.3855, allocations: 0.5293 MB / 0.6252 GB, free: 2.816 MB / 462.1 MB
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.003147/0.3886, allocations: 2.519 MB / 0.6277 GB, free: 88 kB / 462.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.00162/0.3902, allocations: 438.2 kB / 0.6281 GB, free: 15.65 MB / 478.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0004503/0.3907, allocations: 184.7 kB / 0.6283 GB, free: 15.46 MB / 478.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.04536/0.436, allocations: 13.14 MB / 0.6411 GB, free: 2.305 MB / 478.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.544e-05/0.4361, allocations: 38.56 kB / 0.6411 GB, free: 2.258 MB / 478.1 MB
Notification: Performance of pre-optimization done (n=25): time 1.694e-06/0.4361, allocations: 0 / 0.6411 GB, free: 2.258 MB / 478.1 MB
Notification: Performance of matching and sorting (n=25): time 0.002876/0.439, allocations: 0.7896 MB / 0.6419 GB, free: 1.461 MB / 478.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.875e-05/0.439, allocations: 118.8 kB / 0.642 GB, free: 1.32 MB / 478.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0003541/0.4394, allocations: 308.5 kB / 0.6423 GB, free: 1.012 MB / 478.1 MB
Notification: Performance of collectPreVariables (initialization): time 3.489e-05/0.4394, allocations: 29.7 kB / 0.6423 GB, free: 0.9766 MB / 478.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0001532/0.4396, allocations: 212.5 kB / 0.6425 GB, free: 0.7656 MB / 478.1 MB
Notification: Performance of collectInitialBindings (initialization): time 7.916e-05/0.4397, allocations: 83.27 kB / 0.6426 GB, free: 0.6836 MB / 478.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001156/0.4398, allocations: 69.69 kB / 0.6427 GB, free: 0.6094 MB / 478.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001479/0.4399, allocations: 342.8 kB / 0.643 GB, free: 272 kB / 478.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.000403/0.4403, allocations: 143.9 kB / 0.6432 GB, free: 128 kB / 478.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0004882/0.4408, allocations: 235.8 kB / 0.6434 GB, free: 15.85 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0008362/0.4417, allocations: 357.1 kB / 0.6437 GB, free: 15.47 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.915e-06/0.4417, allocations: 0 / 0.6437 GB, free: 15.47 MB / 494.1 MB
Notification: Performance of matching and sorting (n=35) (initialization): time 0.001017/0.4427, allocations: 429.8 kB / 0.6441 GB, free: 15.04 MB / 494.1 MB
Notification: Performance of prepare postOptimizeDAE: time 1.871e-05/0.4427, allocations: 10.33 kB / 0.6441 GB, free: 15.04 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.178e-06/0.4427, allocations: 7.938 kB / 0.6442 GB, free: 15.03 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 1.583e-05/0.4427, allocations: 4 kB / 0.6442 GB, free: 15.02 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0003423/0.4431, allocations: 79.89 kB / 0.6442 GB, free: 14.95 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 8.937e-06/0.4431, allocations: 4 kB / 0.6442 GB, free: 14.94 MB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0002722/0.4433, allocations: 52.06 kB / 0.6443 GB, free: 14.89 MB / 494.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 3.397e-05/0.4434, allocations: 19.98 kB / 0.6443 GB, free: 14.87 MB / 494.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 11
 * Number of states: 0 ()
 * Number of discrete variables: 5 (Opening.y,SinkP1.medium.phase,SourceP1.medium.phase,V1.state_b.phase,V1.state_a.phase)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (35):
 * Single equations (assignments): 35
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0001776/0.4435, allocations: 154 kB / 0.6445 GB, free: 14.71 MB / 494.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 8.284e-05/0.4436, allocations: 35.77 kB / 0.6445 GB, free: 14.68 MB / 494.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002556/0.4462, allocations: 1.99 MB / 0.6464 GB, free: 12.68 MB / 494.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.755e-05/0.4462, allocations: 27.95 kB / 0.6465 GB, free: 12.65 MB / 494.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.048e-06/0.4462, allocations: 4 kB / 0.6465 GB, free: 12.64 MB / 494.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.288e-06/0.4462, allocations: 0 / 0.6465 GB, free: 12.64 MB / 494.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001044/0.4473, allocations: 1.011 MB / 0.6474 GB, free: 11.59 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.837e-06/0.4473, allocations: 3.938 kB / 0.6474 GB, free: 11.59 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0003068/0.4476, allocations: 71.88 kB / 0.6475 GB, free: 11.52 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 4.769e-06/0.4476, allocations: 3.984 kB / 0.6475 GB, free: 11.51 MB / 494.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.208e-05/0.4476, allocations: 11.94 kB / 0.6475 GB, free: 11.5 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 2.044e-06/0.4476, allocations: 0 / 0.6475 GB, free: 11.5 MB / 494.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.072e-06/0.4476, allocations: 0 / 0.6475 GB, free: 11.5 MB / 494.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001538/0.4491, allocations: 1.116 MB / 0.6486 GB, free: 10.37 MB / 494.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0001036/0.4493, allocations: 87.16 kB / 0.6487 GB, free: 10.28 MB / 494.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 6.695e-05/0.4493, allocations: 27.78 kB / 0.6487 GB, free: 10.25 MB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0002585/0.4496, allocations: 27.97 kB / 0.6488 GB, free: 10.23 MB / 494.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 8.197e-05/0.4497, allocations: 31.94 kB / 0.6488 GB, free: 10.2 MB / 494.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 2.538e-05/0.4497, allocations: 12 kB / 0.6488 GB, free: 10.18 MB / 494.1 MB
Notification: Performance of sorting global known variables: time 0.0003638/0.45, allocations: 331.1 kB / 0.6491 GB, free: 9.859 MB / 494.1 MB
Notification: Performance of sort global known variables: time 1e-07/0.45, allocations: 3.938 kB / 0.6491 GB, free: 9.855 MB / 494.1 MB
Notification: Performance of remove unused functions: time 0.002534/0.4526, allocations: 1.002 MB / 0.6501 GB, free: 8.852 MB / 494.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 0 ()
 * Number of discrete variables: 19 ($cse1.phase,$cse1.region,$cse2.phase,$cse2.region,$cse4.phase,$cse4.region,$cse5.phase,$cse5.region,$cse6.region3boundary,$cse7.region3boundary,$cse8.phase,$cse8.region,$cse9.phase,$cse9.region,$cse11.phase,$cse11.region,SourceP1.medium.phase,SinkP1.medium.phase,Opening.y)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (35):
 * Single equations (assignments): 26
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 9
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.005484/0.4581, allocations: 2.735 MB / 0.6528 GB, free: 6.09 MB / 494.1 MB
Notification: Performance of simCode: created initialization part: time 0.001185/0.4593, allocations: 0.5905 MB / 0.6533 GB, free: 5.496 MB / 494.1 MB
Notification: Performance of simCode: created event and clocks part: time 2.535e-06/0.4593, allocations: 0 / 0.6533 GB, free: 5.496 MB / 494.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.0006222/0.4599, allocations: 417.6 kB / 0.6537 GB, free: 5.086 MB / 494.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001129/0.461, allocations: 220.3 kB / 0.654 GB, free: 4.875 MB / 494.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00793/0.4689, allocations: 5.013 MB / 0.6588 GB, free: 15.71 MB / 0.4981 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0005981/0.4695, allocations: 0.9599 MB / 0.6598 GB, free: 14.73 MB / 0.4981 GB
Notification: Performance of simCode: alias equations: time 0.0004799/0.47, allocations: 277.2 kB / 0.6601 GB, free: 14.46 MB / 0.4981 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000346/0.4704, allocations: 255.3 kB / 0.6603 GB, free: 14.21 MB / 0.4981 GB
Notification: Performance of SimCode: time 5.71e-07/0.4704, allocations: 0 / 0.6603 GB, free: 14.21 MB / 0.4981 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.08378/0.5541, allocations: 47.62 MB / 0.7068 GB, free: 14.69 MB / 0.545 GB
Notification: Performance of buildModelFMU: Generate platform static: time 13.75/14.31, allocations: 14.81 kB / 0.7068 GB, free: 14.68 MB / 0.545 GB
"
[Timeout remaining time 645]
(rm -f ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Valves.TestValveDiscrete.pipe ; mkfifo ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Valves.TestValveDiscrete.pipe ; head -c 1048576 < ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Valves.TestValveDiscrete.pipe >> ../files/ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Valves.TestValveDiscrete.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Valves.TestValveDiscrete_res.mat --tempDir=temp_ModelicaTest_trunk_ModelicaTest_Fluid_TestComponents_Valves_TestValveDiscrete_fmu --startTime=0 --stopTime=3 --stepSize=0.000599281 --timeout=50 --tolerance=1e-06 ModelicaTest_trunk_ModelicaTest_Fluid_TestComponents_Valves_TestValveDiscrete.fmu > ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Valves.TestValveDiscrete.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 17.20077278401004]
Failed to read output from testmodel.py, exit status != 0:
['time', 'SourceP1.ports[1].m_flow', 'SourceP1.ports[1].p', 'SourceP1.ports[1].h_outflow', 'V1.port_b.m_flow', 'V1.port_b.p', 'V1.port_b.h_outflow', 'SinkP1.ports[1].m_flow', 'SinkP1.ports[1].p', 'SinkP1.ports[1].h_outflow', 'Opening.y', 'downstreamPressure.y']
14.512925371003803 14.540234892 14.158391632
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fa9e026f3a0>