Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ThermoSysPro_ThermoSysPro.Examples.SimpleExamples.TestInvSingularPressureLoss.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/package.mo): time 0.8665/0.8665, allocations: 152.6 MB / 171.4 MB, free: 6.008 MB / 142.1 MB
"
[Timeout remaining time 179]
Using package ThermoSysPro with version 4.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/package.mo)
Running command: translateModel(ThermoSysPro.Examples.SimpleExamples.TestInvSingularPressureLoss,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ThermoSysPro_ThermoSysPro.Examples.SimpleExamples.TestInvSingularPressureLoss")
translateModel(ThermoSysPro.Examples.SimpleExamples.TestInvSingularPressureLoss,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ThermoSysPro_ThermoSysPro.Examples.SimpleExamples.TestInvSingularPressureLoss") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.0005383/0.0005383, allocations: 19.97 kB / 248.5 MB, free: 5.652 MB / 206.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1836/0.1841, allocations: 33.05 MB / 281.6 MB, free: 3.574 MB / 222.1 MB
Notification: Automatically loaded package Complex 4.0.0 due to uses annotation from Modelica.
Notification: Automatically loaded package ModelicaServices 4.0.0 due to uses annotation from Modelica.
Notification: Automatically loaded package Modelica 4.0.0 due to usage.
Notification: Performance of NFInst.instantiate(ThermoSysPro.Examples.SimpleExamples.TestInvSingularPressureLoss): time 1.586/1.77, allocations: 300.9 MB / 0.5688 GB, free: 5.812 MB / 478.1 MB
Notification: Performance of NFInst.instExpressions: time 0.2904/2.061, allocations: 34.56 MB / 0.6026 GB, free: 6.18 MB / 478.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.000162/2.061, allocations: 2.625 kB / 0.6026 GB, free: 6.18 MB / 478.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/WaterSteam/BoundaryConditions/SourcePQ.mo:29:3-30:52:writable] Warning: Connector C is not balanced: The number of potential variables (4) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/WaterSteam/BoundaryConditions/SinkP.mo:33:3-34:47:writable] Warning: Connector C is not balanced: The number of potential variables (4) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/WaterSteam/PressureLosses/InvSingularPressureLoss.mo:26:3-28:16:writable] Warning: Connector C1 is not balanced: The number of potential variables (4) is not equal to the number of flow variables (0).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/WaterSteam/PressureLosses/InvSingularPressureLoss.mo:29:3-30:83:writable] Warning: Connector C2 is not balanced: The number of potential variables (4) is not equal to the number of flow variables (0).
Notification: Performance of NFTyping.typeComponents: time 0.000359/2.061, allocations: 126 kB / 0.6027 GB, free: 6.117 MB / 478.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.000133/2.061, allocations: 88.28 kB / 0.6028 GB, free: 6.059 MB / 478.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/Properties/WaterSteam/IF97_packages.mo:783:9-783:27:writable] Warning: cv was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/Properties/WaterSteam/IF97_packages.mo:850:9-850:27:writable] Warning: cv was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/Properties/WaterSteam/IF97_packages.mo:1088:9-1088:27:writable] Warning: cv was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/Properties/WaterSteamSimple/prop4_Ph.mo:69:3-69:60:writable] Warning: dh1satp was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/Properties/WaterSteamSimple/prop4_Ph.mo:69:3-69:60:writable] Warning: dh2satp was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/Properties/WaterSteamSimple/prop4_Ph_der.mo:167:3-170:49:writable] Warning: du1satp_der was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoSysPro 4.0.0-master/Properties/WaterSteamSimple/prop4_Ph_der.mo:167:3-170:49:writable] Warning: du2satp_der was used before it was defined (given a value). Additional such uses may exist for the variable, but some messages were suppressed.
Notification: Performance of NFTyping.typeClassSections: time 0.02706/2.089, allocations: 9.814 MB / 0.6124 GB, free: 1.555 MB / 478.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0004343/2.089, allocations: 276.4 kB / 0.6127 GB, free: 1.391 MB / 478.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.000189/2.089, allocations: 85.33 kB / 0.6127 GB, free: 1.34 MB / 478.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0002062/2.089, allocations: 123.8 kB / 0.6129 GB, free: 1.266 MB / 478.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0001209/2.089, allocations: 94.34 kB / 0.6129 GB, free: 1.203 MB / 478.1 MB
Notification: Performance of NFPackage.collectConstants: time 2.541e-05/2.089, allocations: 17.5 kB / 0.613 GB, free: 1.203 MB / 478.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.03794/2.127, allocations: 17.92 MB / 0.6305 GB, free: 1.844 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 8.678e-05/2.128, allocations: 41.83 kB / 0.6305 GB, free: 1.805 MB / 494.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0002322/2.128, allocations: 107.5 kB / 0.6306 GB, free: 1.699 MB / 494.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.02572/2.153, allocations: 8.388 MB / 0.6388 GB, free: 9.285 MB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 8.967e-06/2.153, allocations: 4 kB / 0.6388 GB, free: 9.281 MB / 0.4981 GB
Notification: Performance of FrontEnd: time 2.214e-06/2.153, allocations: 1.031 kB / 0.6388 GB, free: 9.281 MB / 0.4981 GB
Notification: Performance of Transformations before backend: time 1.002e-05/2.153, allocations: 0 / 0.6388 GB, free: 9.281 MB / 0.4981 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 64
 * Number of variables: 64
Notification: Performance of Generate backend data structure: time 0.004793/2.158, allocations: 1.376 MB / 0.6401 GB, free: 7.867 MB / 0.4981 GB
Notification: Performance of prepare preOptimizeDAE: time 4.558e-05/2.158, allocations: 12.03 kB / 0.6401 GB, free: 7.855 MB / 0.4981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 6.972e-05/2.158, allocations: 23.81 kB / 0.6402 GB, free: 7.832 MB / 0.4981 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0002368/2.159, allocations: 165.4 kB / 0.6403 GB, free: 7.645 MB / 0.4981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.516e-05/2.159, allocations: 16.31 kB / 0.6403 GB, free: 7.629 MB / 0.4981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 3.451e-05/2.159, allocations: 20 kB / 0.6404 GB, free: 7.609 MB / 0.4981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0005183/2.159, allocations: 262.9 kB / 0.6406 GB, free: 7.332 MB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 1.773e-05/2.159, allocations: 0.5938 kB / 0.6406 GB, free: 7.332 MB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 2.835e-05/2.159, allocations: 8.75 kB / 0.6406 GB, free: 7.324 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.043e-05/2.159, allocations: 12.55 kB / 0.6406 GB, free: 7.312 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0003283/2.16, allocations: 146.2 kB / 0.6408 GB, free: 7.172 MB / 0.4981 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.02103/2.181, allocations: 6.081 MB / 0.6467 GB, free: 0.9961 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0002771/2.181, allocations: 71.2 kB / 0.6468 GB, free: 0.918 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0001268/2.181, allocations: 48.88 kB / 0.6468 GB, free: 0.8672 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.1608/2.342, allocations: 42.46 MB / 0.6883 GB, free: 6.375 MB / 0.545 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.795e-05/2.342, allocations: 31.12 kB / 0.6883 GB, free: 6.336 MB / 0.545 GB
Notification: Performance of pre-optimization done (n=22): time 1.863e-06/2.342, allocations: 0 / 0.6883 GB, free: 6.336 MB / 0.545 GB
Notification: Performance of matching and sorting (n=22): time 0.000593/2.343, allocations: 201.4 kB / 0.6885 GB, free: 6.133 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.249e-05/2.343, allocations: 67.28 kB / 0.6886 GB, free: 6.055 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0002891/2.343, allocations: 228.1 kB / 0.6888 GB, free: 5.824 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 1.814e-05/2.343, allocations: 25.7 kB / 0.6888 GB, free: 5.793 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0001164/2.343, allocations: 145.1 kB / 0.689 GB, free: 5.648 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 7.173e-05/2.343, allocations: 79.31 kB / 0.689 GB, free: 5.57 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 8.423e-05/2.343, allocations: 41.7 kB / 0.6891 GB, free: 5.523 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 0.0001619/2.343, allocations: 330.8 kB / 0.6894 GB, free: 5.191 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001263/2.343, allocations: 47.98 kB / 0.6894 GB, free: 5.145 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0001728/2.344, allocations: 93.94 kB / 0.6895 GB, free: 5.039 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0002833/2.344, allocations: 157.2 kB / 0.6897 GB, free: 4.871 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.505e-06/2.344, allocations: 1.406 kB / 0.6897 GB, free: 4.871 MB / 0.545 GB
Notification: Performance of matching and sorting (n=29) (initialization): time 0.0004124/2.344, allocations: 198.1 kB / 0.6899 GB, free: 4.672 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 2.235e-05/2.344, allocations: 11.94 kB / 0.6899 GB, free: 4.66 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.362e-06/2.344, allocations: 0 / 0.6899 GB, free: 4.66 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.726e-05/2.344, allocations: 4 kB / 0.6899 GB, free: 4.656 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007716/2.345, allocations: 99.97 kB / 0.69 GB, free: 4.559 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.095e-05/2.345, allocations: 4 kB / 0.69 GB, free: 4.555 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0001462/2.345, allocations: 27.73 kB / 0.69 GB, free: 4.527 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 1.669e-05/2.345, allocations: 7.984 kB / 0.69 GB, free: 4.52 MB / 0.545 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 5
 * Number of states: 0 ()
 * Number of discrete variables: 4 (invSingularPressureLoss.C2.b,invSingularPressureLoss.C1.a,sinkQ.C.a,sourcePQ.C.b)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (11):
 * Single equations (assignments): 9
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 2
 * 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.0001414/2.345, allocations: 130.5 kB / 0.6901 GB, free: 4.383 MB / 0.545 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 6.529e-05/2.346, allocations: 23.8 kB / 0.6902 GB, free: 4.359 MB / 0.545 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0005557/2.346, allocations: 329.9 kB / 0.6905 GB, free: 3.996 MB / 0.545 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0003049/2.346, allocations: 165.5 kB / 0.6906 GB, free: 3.828 MB / 0.545 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.551e-06/2.346, allocations: 0 / 0.6906 GB, free: 3.828 MB / 0.545 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.596e-06/2.346, allocations: 0 / 0.6906 GB, free: 3.828 MB / 0.545 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001021/2.347, allocations: 0.5791 MB / 0.6912 GB, free: 3.199 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.307e-06/2.347, allocations: 0 / 0.6912 GB, free: 3.199 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0005862/2.348, allocations: 95.86 kB / 0.6913 GB, free: 3.105 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (simulation): time 6.211e-06/2.348, allocations: 3.984 kB / 0.6913 GB, free: 3.102 MB / 0.545 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 1.254e-05/2.348, allocations: 5.312 kB / 0.6913 GB, free: 3.098 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 2.374e-06/2.348, allocations: 0 / 0.6913 GB, free: 3.098 MB / 0.545 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.313e-06/2.348, allocations: 0 / 0.6913 GB, free: 3.098 MB / 0.545 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0004738/2.348, allocations: 302.3 kB / 0.6916 GB, free: 2.785 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 7.794e-05/2.349, allocations: 27.78 kB / 0.6916 GB, free: 2.758 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 3.837e-05/2.349, allocations: 8 kB / 0.6916 GB, free: 2.75 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0001283/2.349, allocations: 4.75 kB / 0.6916 GB, free: 2.746 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 3.413e-05/2.349, allocations: 7.984 kB / 0.6916 GB, free: 2.738 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 1.496e-05/2.349, allocations: 11.98 kB / 0.6916 GB, free: 2.727 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.0002304/2.349, allocations: 205.2 kB / 0.6918 GB, free: 2.527 MB / 0.545 GB
Notification: Performance of sort global known variables: time 1.1e-07/2.349, allocations: 0 / 0.6918 GB, free: 2.527 MB / 0.545 GB
Notification: Performance of remove unused functions: time 0.003123/2.352, allocations: 1.189 MB / 0.693 GB, free: 1.336 MB / 0.545 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * 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 simulation (13):
 * Single equations (assignments): 12
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 1
 * 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.006027/2.358, allocations: 1.741 MB / 0.6947 GB, free: 15.62 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.0005342/2.359, allocations: 173.5 kB / 0.6949 GB, free: 15.45 MB / 0.5606 GB
Notification: Performance of simCode: created event and clocks part: time 3.476e-06/2.359, allocations: 0 / 0.6949 GB, free: 15.45 MB / 0.5606 GB
Notification: Performance of simCode: created simulation system equations: time 0.0001757/2.359, allocations: 67.91 kB / 0.6949 GB, free: 15.38 MB / 0.5606 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0006961/2.36, allocations: 94.72 kB / 0.695 GB, free: 15.3 MB / 0.5606 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.0009546/2.361, allocations: 466.7 kB / 0.6955 GB, free: 14.82 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0004207/2.361, allocations: 0.7596 MB / 0.6962 GB, free: 14.04 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.0001146/2.361, allocations: 27.05 kB / 0.6962 GB, free: 14.01 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 5.668e-05/2.361, allocations: 27.98 kB / 0.6963 GB, free: 13.98 MB / 0.5606 GB
Notification: Performance of SimCode: time 5.91e-07/2.361, allocations: 0 / 0.6963 GB, free: 13.98 MB / 0.5606 GB
Notification: Performance of Templates: time 0.06403/2.425, allocations: 41.4 MB / 0.7367 GB, free: 4.984 MB / 0.5919 GB
"
[Timeout remaining time 657]
make -j1 -f ThermoSysPro_ThermoSysPro.Examples.SimpleExamples.TestInvSingularPressureLoss.makefile [Timeout 660]
(rm -f ThermoSysPro_ThermoSysPro.Examples.SimpleExamples.TestInvSingularPressureLoss.pipe ; mkfifo ThermoSysPro_ThermoSysPro.Examples.SimpleExamples.TestInvSingularPressureLoss.pipe ; head -c 1048576 < ThermoSysPro_ThermoSysPro.Examples.SimpleExamples.TestInvSingularPressureLoss.pipe >> ../files/ThermoSysPro_ThermoSysPro.Examples.SimpleExamples.TestInvSingularPressureLoss.sim & ./ThermoSysPro_ThermoSysPro.Examples.SimpleExamples.TestInvSingularPressureLoss  -abortSlowSimulation -alarm=480 -lv LOG_STATS > ThermoSysPro_ThermoSysPro.Examples.SimpleExamples.TestInvSingularPressureLoss.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 8.391593475826085]
Failed to read output from testmodel.py, exit status != 0:
2.5808225320652127 2.595482831 0.43877690099999994
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fd39da717b0>