Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_10_Buildings.Experimental.DHC.Plants.Steam.BaseClasses.Examples.ControlVolumeEvaporation.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001574/0.001574, allocations: 102.8 kB / 16.45 MB, free: 6.445 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001634/0.001634, allocations: 187.7 kB / 19.4 MB, free: 3.66 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.438/1.438, allocations: 225.6 MB / 247.8 MB, free: 3.734 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo): time 2.581/2.581, allocations: 443.5 MB / 0.7287 GB, free: 14.47 MB / 0.5762 GB
"
[Timeout remaining time 177]
Using package Buildings with version 10.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: "" <> buildModelFMU(Buildings.Experimental.DHC.Plants.Steam.BaseClasses.Examples.ControlVolumeEvaporation,fileNamePrefix="Buildings_10_Buildings_Experimental_DHC_Plants_Steam_BaseClasses_Examples_ControlVolumeEvaporation",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.Experimental.DHC.Plants.Steam.BaseClasses.Examples.ControlVolumeEvaporation,fileNamePrefix="Buildings_10_Buildings_Experimental_DHC_Plants_Steam_BaseClasses_Examples_ControlVolumeEvaporation",fmuType="me",version="2.0",platforms={"static"}) [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 0.002171/0.002171, allocations: 63.89 kB / 1.056 GB, free: 2.43 MB / 0.795 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3485/0.3507, allocations: 160.7 MB / 1.213 GB, free: 1.402 MB / 0.9512 GB
Notification: Performance of NFInst.instantiate(Buildings.Experimental.DHC.Plants.Steam.BaseClasses.Examples.ControlVolumeEvaporation): time 0.901/1.252, allocations: 163.2 MB / 1.372 GB, free: 14.63 MB / 1.061 GB
Notification: Performance of NFInst.instExpressions: time 0.01382/1.265, allocations: 8.879 MB / 1.381 GB, free: 8.77 MB / 1.061 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0009053/1.266, allocations: 15.03 kB / 1.381 GB, free: 8.766 MB / 1.061 GB
Notification: Performance of NFTyping.typeComponents: time 0.001309/1.268, allocations: 366.3 kB / 1.381 GB, free: 8.555 MB / 1.061 GB
Notification: Performance of NFTyping.typeBindings: time 0.003604/1.271, allocations: 1.141 MB / 1.382 GB, free: 7.688 MB / 1.061 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006848/1.278, allocations: 2.138 MB / 1.385 GB, free: 6.148 MB / 1.061 GB
Notification: Performance of NFFlatten.flatten: time 0.002351/1.281, allocations: 1.378 MB / 1.386 GB, free: 5.355 MB / 1.061 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008364/1.281, allocations: 378 kB / 1.386 GB, free: 5.098 MB / 1.061 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006017/1.287, allocations: 2.137 MB / 1.388 GB, free: 3.68 MB / 1.061 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003177/1.291, allocations: 1.318 MB / 1.39 GB, free: 2.793 MB / 1.061 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001825/1.291, allocations: 55.06 kB / 1.39 GB, free: 2.789 MB / 1.061 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.007036/1.298, allocations: 2.901 MB / 1.393 GB, free: 1.297 MB / 1.061 GB
Notification: Performance of NFScalarize.scalarize: time 0.0003135/1.298, allocations: 155.1 kB / 1.393 GB, free: 1.176 MB / 1.061 GB
Notification: Performance of NFVerifyModel.verify: time 0.0009026/1.299, allocations: 351.4 kB / 1.393 GB, free: 0.9648 MB / 1.061 GB
Notification: Performance of NFConvertDAE.convert: time 0.004936/1.304, allocations: 2.237 MB / 1.395 GB, free: 15.49 MB / 1.076 GB
Notification: Performance of FrontEnd - DAE generated: time 4.519e-06/1.304, allocations: 0 / 1.395 GB, free: 15.49 MB / 1.076 GB
Notification: Performance of FrontEnd: time 1.452e-06/1.304, allocations: 2.781 kB / 1.395 GB, free: 15.49 MB / 1.076 GB
Notification: Performance of Transformations before backend: time 2.984e-05/1.304, allocations: 0 / 1.395 GB, free: 15.49 MB / 1.076 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 133
 * Number of variables: 133
Notification: Performance of Generate backend data structure: time 0.002915/1.307, allocations: 1.018 MB / 1.396 GB, free: 14.69 MB / 1.076 GB
Notification: Performance of prepare preOptimizeDAE: time 4.434e-05/1.307, allocations: 9.031 kB / 1.396 GB, free: 14.69 MB / 1.076 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0001611/1.307, allocations: 97.7 kB / 1.396 GB, free: 14.61 MB / 1.076 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001188/1.308, allocations: 148.8 kB / 1.396 GB, free: 14.53 MB / 1.076 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0009202/1.309, allocations: 439.8 kB / 1.397 GB, free: 14.14 MB / 1.076 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 4.528e-05/1.309, allocations: 38.97 kB / 1.397 GB, free: 14.12 MB / 1.076 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 9.071e-05/1.309, allocations: 49.34 kB / 1.397 GB, free: 14.11 MB / 1.076 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001298/1.311, allocations: 0.6655 MB / 1.398 GB, free: 13.65 MB / 1.076 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.616e-05/1.311, allocations: 5.312 kB / 1.398 GB, free: 13.65 MB / 1.076 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 6.49e-05/1.311, allocations: 26.88 kB / 1.398 GB, free: 13.63 MB / 1.076 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.12e-05/1.311, allocations: 17.69 kB / 1.398 GB, free: 13.62 MB / 1.076 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001138/1.312, allocations: 490.5 kB / 1.398 GB, free: 13.39 MB / 1.076 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.004158/1.316, allocations: 2.996 MB / 1.401 GB, free: 10.59 MB / 1.076 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0006205/1.317, allocations: 264.7 kB / 1.401 GB, free: 10.45 MB / 1.076 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003869/1.317, allocations: 160.6 kB / 1.401 GB, free: 10.36 MB / 1.076 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.01732/1.334, allocations: 5.808 MB / 1.407 GB, free: 7.32 MB / 1.076 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.229e-05/1.334, allocations: 45.38 kB / 1.407 GB, free: 7.273 MB / 1.076 GB
Notification: Performance of pre-optimization done (n=36): time 2.534e-06/1.334, allocations: 2.312 kB / 1.407 GB, free: 7.273 MB / 1.076 GB
Warning: Some equations could not be differentiated for following variables having attribute stateSelect=StateSelect.prefer. They will be treated as if they had stateSelect=StateSelect.default
========================================
1: volDyn.p
Please use -d=bltdump for more information.
Notification: Performance of matching and sorting (n=52): time 0.08981/1.424, allocations: 31.89 MB / 1.438 GB, free: 10.01 MB / 1.107 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001006/1.424, allocations: 133 kB / 1.438 GB, free: 9.848 MB / 1.107 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0005579/1.425, allocations: 373.6 kB / 1.439 GB, free: 9.477 MB / 1.107 GB
Notification: Performance of collectPreVariables (initialization): time 7.256e-05/1.425, allocations: 37.7 kB / 1.439 GB, free: 9.434 MB / 1.107 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002539/1.425, allocations: 304.8 kB / 1.439 GB, free: 9.129 MB / 1.107 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001848/1.425, allocations: 186.4 kB / 1.439 GB, free: 8.945 MB / 1.107 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002081/1.426, allocations: 114.2 kB / 1.439 GB, free: 8.828 MB / 1.107 GB
Notification: Performance of setup shared object (initialization): time 0.0001968/1.426, allocations: 354.8 kB / 1.44 GB, free: 8.473 MB / 1.107 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.000572/1.426, allocations: 240.9 kB / 1.44 GB, free: 8.234 MB / 1.107 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007431/1.427, allocations: 372.5 kB / 1.44 GB, free: 7.82 MB / 1.107 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001183/1.428, allocations: 0.598 MB / 1.441 GB, free: 7.18 MB / 1.107 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.825e-06/1.428, allocations: 7.938 kB / 1.441 GB, free: 7.172 MB / 1.107 GB
Notification: Performance of matching and sorting (n=70) (initialization): time 0.002011/1.43, allocations: 0.794 MB / 1.442 GB, free: 6.363 MB / 1.107 GB
Notification: Performance of prepare postOptimizeDAE: time 2.408e-05/1.43, allocations: 11.94 kB / 1.442 GB, free: 6.352 MB / 1.107 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.726e-06/1.43, allocations: 0 / 1.442 GB, free: 6.352 MB / 1.107 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0005073/1.431, allocations: 122.4 kB / 1.442 GB, free: 6.227 MB / 1.107 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005635/1.431, allocations: 124 kB / 1.442 GB, free: 6.105 MB / 1.107 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003662/1.435, allocations: 3.437 MB / 1.445 GB, free: 2.395 MB / 1.107 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0006052/1.436, allocations: 35.91 kB / 1.445 GB, free: 2.359 MB / 1.107 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.2e-05/1.436, allocations: 32.08 kB / 1.445 GB, free: 2.328 MB / 1.107 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 14
 * 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 (65):
 * Single equations (assignments): 63
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,4)}
Notification: Performance of prepare postOptimizeDAE: time 0.0003082/1.436, allocations: 193.6 kB / 1.446 GB, free: 2.129 MB / 1.107 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000216/1.436, allocations: 51.67 kB / 1.446 GB, free: 2.078 MB / 1.107 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.00186/1.438, allocations: 0.7826 MB / 1.446 GB, free: 1.285 MB / 1.107 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.194e-06/1.438, allocations: 4 kB / 1.446 GB, free: 1.281 MB / 1.107 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.526e-06/1.438, allocations: 0 / 1.446 GB, free: 1.281 MB / 1.107 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.294e-06/1.438, allocations: 3.984 kB / 1.446 GB, free: 1.277 MB / 1.107 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.003334/1.441, allocations: 1.618 MB / 1.448 GB, free: 15.56 MB / 1.123 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.498e-06/1.441, allocations: 4.812 kB / 1.448 GB, free: 15.55 MB / 1.123 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0005052/1.442, allocations: 107.8 kB / 1.448 GB, free: 15.45 MB / 1.123 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0002588/1.442, allocations: 77.2 kB / 1.448 GB, free: 15.37 MB / 1.123 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 4.755e-05/1.442, allocations: 19.98 kB / 1.448 GB, free: 15.35 MB / 1.123 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001878/1.444, allocations: 1.769 MB / 1.45 GB, free: 13.45 MB / 1.123 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.144e-06/1.444, allocations: 7.938 kB / 1.45 GB, free: 13.44 MB / 1.123 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002209/1.446, allocations: 1.024 MB / 1.451 GB, free: 12.35 MB / 1.123 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002391/1.447, allocations: 132.8 kB / 1.451 GB, free: 12.2 MB / 1.123 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001418/1.447, allocations: 19.94 kB / 1.451 GB, free: 12.18 MB / 1.123 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0005279/1.447, allocations: 28.09 kB / 1.451 GB, free: 12.16 MB / 1.123 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 9.95e-05/1.447, allocations: 47.89 kB / 1.451 GB, free: 12.11 MB / 1.123 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 5.393e-05/1.447, allocations: 23.94 kB / 1.451 GB, free: 12.09 MB / 1.123 GB
Notification: Performance of sorting global known variables: time 0.0005149/1.448, allocations: 358.8 kB / 1.451 GB, free: 11.73 MB / 1.123 GB
Notification: Performance of sort global known variables: time 8e-08/1.448, allocations: 0 / 1.451 GB, free: 11.73 MB / 1.123 GB
Notification: Performance of remove unused functions: time 0.004994/1.453, allocations: 1.398 MB / 1.453 GB, free: 10.34 MB / 1.123 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 14
 * Number of states: 2 (volDyn.VWat,volSte.VWat)
 * 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 (48):
 * Single equations (assignments): 47
 * 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
   {(1,3)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.01194/1.465, allocations: 2.217 MB / 1.455 GB, free: 8.059 MB / 1.123 GB
Notification: Performance of simCode: created initialization part: time 0.002151/1.467, allocations: 0.7759 MB / 1.456 GB, free: 7.258 MB / 1.123 GB
Notification: Performance of simCode: created event and clocks part: time 6.473e-06/1.467, allocations: 384 / 1.456 GB, free: 7.258 MB / 1.123 GB
Notification: Performance of simCode: created simulation system equations: time 0.0008537/1.468, allocations: 386.2 kB / 1.456 GB, free: 6.867 MB / 1.123 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00198/1.47, allocations: 339.7 kB / 1.456 GB, free: 6.543 MB / 1.123 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01362/1.484, allocations: 5.79 MB / 1.462 GB, free: 0.5 MB / 1.123 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0006193/1.484, allocations: 0.879 MB / 1.463 GB, free: 15.59 MB / 1.139 GB
Notification: Performance of simCode: alias equations: time 0.0006494/1.485, allocations: 140 kB / 1.463 GB, free: 15.47 MB / 1.139 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003685/1.485, allocations: 219.4 kB / 1.463 GB, free: 15.25 MB / 1.139 GB
Notification: Performance of SimCode: time 9.71e-07/1.485, allocations: 0 / 1.463 GB, free: 15.25 MB / 1.139 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1377/1.623, allocations: 70.19 MB / 1.532 GB, free: 9.973 MB / 1.201 GB
Notification: Performance of buildModelFMU: Generate platform static: time 14.85/16.47, allocations: 10.66 kB / 1.532 GB, free: 9.965 MB / 1.201 GB
"
[Timeout remaining time 283]
(rm -f Buildings_10_Buildings.Experimental.DHC.Plants.Steam.BaseClasses.Examples.ControlVolumeEvaporation.pipe ; mkfifo Buildings_10_Buildings.Experimental.DHC.Plants.Steam.BaseClasses.Examples.ControlVolumeEvaporation.pipe ; head -c 1048576 < Buildings_10_Buildings.Experimental.DHC.Plants.Steam.BaseClasses.Examples.ControlVolumeEvaporation.pipe >> ../files/Buildings_10_Buildings.Experimental.DHC.Plants.Steam.BaseClasses.Examples.ControlVolumeEvaporation.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=Buildings_10_Buildings.Experimental.DHC.Plants.Steam.BaseClasses.Examples.ControlVolumeEvaporation_res.mat --tempDir=temp_Buildings_10_Buildings_Experimental_DHC_Plants_Steam_BaseClasses_Examples_ControlVolumeEvaporation_fmu --startTime=0 --stopTime=1 --stepSize=0.0004 --timeout=400 --tolerance=1e-06 Buildings_10_Buildings_Experimental_DHC_Plants_Steam_BaseClasses_Examples_ControlVolumeEvaporation.fmu > Buildings_10_Buildings.Experimental.DHC.Plants.Steam.BaseClasses.Examples.ControlVolumeEvaporation.pipe 2>&1) [Timeout 420.0]
[Calling os._exit(0), Time elapsed: 22.59503645868972]
Failed to read output from testmodel.py, exit status != 0:
16.604153487831354 16.624939861 15.31693867
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f2c82776020>