Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildSysPro_BuildSysPro.IBPSA.Fluid.Chillers.Validation.Carnot_TEva_2ndLaw.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.001269/0.001269, allocations: 117.2 kB / 17.17 MB, free: 5.754 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.00134/0.00134, allocations: 207.3 kB / 20.13 MB, free: 2.914 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.467/1.467, allocations: 230.6 MB / 253.5 MB, free: 15.25 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/Building/BuildingEnvelope/HeatTransfer/package.mo:2:1-40:17:writable] Warning: Soil_case_nappe was referenced in the package.order file, but was not found in package.mo, Soil_case_nappe/package.mo or Soil_case_nappe.mo.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/package.mo): time 0.9395/0.9395, allocations: 144.9 MB / 454.5 MB, free: 10.07 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package BuildSysPro with version 3.6.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/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: "" <> buildModelFMU(BuildSysPro.IBPSA.Fluid.Chillers.Validation.Carnot_TEva_2ndLaw,fileNamePrefix="BuildSysPro_BuildSysPro_IBPSA_Fluid_Chillers_Validation_Carnot_TEva_2ndLaw",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(BuildSysPro.IBPSA.Fluid.Chillers.Validation.Carnot_TEva_2ndLaw,fileNamePrefix="BuildSysPro_BuildSysPro_IBPSA_Fluid_Chillers_Validation_Carnot_TEva_2ndLaw",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: BuildSysPro requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.001993/0.001993, allocations: 67.66 kB / 0.6287 GB, free: 33.59 MB / 0.4981 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1801/0.1821, allocations: 87.83 MB / 0.7145 GB, free: 11.71 MB / 0.5294 GB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Fluid.Chillers.Validation.Carnot_TEva_2ndLaw): time 0.08397/0.2661, allocations: 71.25 MB / 0.7841 GB, free: 4.203 MB / 0.5919 GB
Notification: Performance of NFInst.instExpressions: time 0.02767/0.2937, allocations: 19.03 MB / 0.8026 GB, free: 1.117 MB / 0.6075 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003285/0.297, allocations: 91.19 kB / 0.8027 GB, free: 1.027 MB / 0.6075 GB
Notification: Performance of NFTyping.typeComponents: time 0.002923/0.3, allocations: 0.8502 MB / 0.8036 GB, free: 176 kB / 0.6075 GB
Notification: Performance of NFTyping.typeBindings: time 0.008949/0.3089, allocations: 3.016 MB / 0.8065 GB, free: 13.14 MB / 0.6231 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004621/0.3135, allocations: 1.667 MB / 0.8081 GB, free: 11.48 MB / 0.6231 GB
Notification: Performance of NFFlatten.flatten: time 0.00621/0.3197, allocations: 4.04 MB / 0.8121 GB, free: 7.438 MB / 0.6231 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001986/0.3217, allocations: 1.172 MB / 0.8132 GB, free: 6.23 MB / 0.6231 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.004423/0.3261, allocations: 1.836 MB / 0.815 GB, free: 4.391 MB / 0.6231 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002377/0.3285, allocations: 1.241 MB / 0.8162 GB, free: 3.145 MB / 0.6231 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003135/0.3288, allocations: 176 kB / 0.8164 GB, free: 2.973 MB / 0.6231 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.003187/0.332, allocations: 1.466 MB / 0.8178 GB, free: 1.504 MB / 0.6231 GB
Notification: Performance of NFScalarize.scalarize: time 0.000411/0.3324, allocations: 398.4 kB / 0.8182 GB, free: 1.113 MB / 0.6231 GB
Notification: Performance of NFVerifyModel.verify: time 0.001149/0.3336, allocations: 0.9216 MB / 0.8191 GB, free: 192 kB / 0.6231 GB
Notification: Performance of NFConvertDAE.convert: time 0.005198/0.3388, allocations: 3.313 MB / 0.8223 GB, free: 12.87 MB / 0.6387 GB
Notification: Performance of FrontEnd - DAE generated: time 4.328e-06/0.3388, allocations: 0 / 0.8223 GB, free: 12.87 MB / 0.6387 GB
Notification: Performance of FrontEnd: time 1.734e-06/0.3388, allocations: 0 / 0.8223 GB, free: 12.87 MB / 0.6387 GB
Notification: Performance of Transformations before backend: time 3.457e-05/0.3388, allocations: 3.938 kB / 0.8223 GB, free: 12.86 MB / 0.6387 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 257
 * Number of variables: 257
Notification: Performance of Generate backend data structure: time 0.004163/0.343, allocations: 2.094 MB / 0.8244 GB, free: 10.7 MB / 0.6387 GB
Notification: Performance of prepare preOptimizeDAE: time 5.095e-05/0.343, allocations: 16.03 kB / 0.8244 GB, free: 10.69 MB / 0.6387 GB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 4.3e-05/0.3431, allocations: 26.22 kB / 0.8244 GB, free: 10.66 MB / 0.6387 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0001864/0.3433, allocations: 152.6 kB / 0.8246 GB, free: 10.5 MB / 0.6387 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002362/0.3456, allocations: 459 kB / 0.825 GB, free: 10.05 MB / 0.6387 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002916/0.3485, allocations: 1.56 MB / 0.8265 GB, free: 8.438 MB / 0.6387 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.96e-05/0.3486, allocations: 82.03 kB / 0.8266 GB, free: 8.355 MB / 0.6387 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001634/0.3488, allocations: 108 kB / 0.8267 GB, free: 8.25 MB / 0.6387 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002846/0.3516, allocations: 1.325 MB / 0.828 GB, free: 6.816 MB / 0.6387 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.403e-05/0.3517, allocations: 0 / 0.828 GB, free: 6.816 MB / 0.6387 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001096/0.3518, allocations: 44 kB / 0.828 GB, free: 6.773 MB / 0.6387 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.49e-05/0.3518, allocations: 28 kB / 0.8281 GB, free: 6.746 MB / 0.6387 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002821/0.3546, allocations: 1.101 MB / 0.8292 GB, free: 5.641 MB / 0.6387 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.01015/0.3648, allocations: 7.53 MB / 0.8365 GB, free: 13.59 MB / 0.6544 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.003277/0.368, allocations: 0.7681 MB / 0.8373 GB, free: 12.82 MB / 0.6544 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0006282/0.3687, allocations: 262 kB / 0.8375 GB, free: 12.56 MB / 0.6544 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001552/0.3702, allocations: 1.722 MB / 0.8392 GB, free: 10.45 MB / 0.6544 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.79e-05/0.3703, allocations: 46.94 kB / 0.8392 GB, free: 10.4 MB / 0.6544 GB
Notification: Performance of pre-optimization done (n=48): time 5.07e-06/0.3703, allocations: 3.984 kB / 0.8392 GB, free: 10.39 MB / 0.6544 GB
Notification: Performance of matching and sorting (n=48): time 0.005204/0.3755, allocations: 1.339 MB / 0.8405 GB, free: 9.027 MB / 0.6544 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.538e-05/0.3755, allocations: 59.78 kB / 0.8406 GB, free: 8.957 MB / 0.6544 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002701/0.3782, allocations: 1.549 MB / 0.8421 GB, free: 7.398 MB / 0.6544 GB
Notification: Performance of collectPreVariables (initialization): time 9.238e-05/0.3783, allocations: 37.7 kB / 0.8421 GB, free: 7.355 MB / 0.6544 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0006389/0.3789, allocations: 0.6819 MB / 0.8428 GB, free: 6.668 MB / 0.6544 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002/0.3791, allocations: 178.5 kB / 0.843 GB, free: 6.492 MB / 0.6544 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003292/0.3795, allocations: 178.5 kB / 0.8432 GB, free: 6.309 MB / 0.6544 GB
Notification: Performance of setup shared object (initialization): time 0.0001682/0.3796, allocations: 337.9 kB / 0.8435 GB, free: 5.973 MB / 0.6544 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001031/0.3807, allocations: 361.8 kB / 0.8438 GB, free: 5.621 MB / 0.6544 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001324/0.382, allocations: 0.6624 MB / 0.8445 GB, free: 4.832 MB / 0.6544 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001882/0.3839, allocations: 0.8829 MB / 0.8453 GB, free: 3.848 MB / 0.6544 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.032e-06/0.3839, allocations: 0 / 0.8453 GB, free: 3.848 MB / 0.6544 GB
Notification: Performance of matching and sorting (n=92) (initialization): time 0.003189/0.3871, allocations: 1.19 MB / 0.8465 GB, free: 2.645 MB / 0.6544 GB
Notification: Performance of prepare postOptimizeDAE: time 2.848e-05/0.3871, allocations: 12 kB / 0.8465 GB, free: 2.633 MB / 0.6544 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.457e-05/0.3871, allocations: 12.03 kB / 0.8465 GB, free: 2.621 MB / 0.6544 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0009201/0.388, allocations: 216.6 kB / 0.8467 GB, free: 2.406 MB / 0.6544 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007798/0.3888, allocations: 199.7 kB / 0.8469 GB, free: 2.211 MB / 0.6544 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0009571/0.3898, allocations: 0.7201 MB / 0.8476 GB, free: 1.465 MB / 0.6544 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001564/0.3913, allocations: 207.1 kB / 0.8478 GB, free: 1.262 MB / 0.6544 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.966e-05/0.3914, allocations: 51.97 kB / 0.8479 GB, free: 1.211 MB / 0.6544 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 35
 * 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 (84):
 * Single equations (assignments): 83
 * 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,8)}
Notification: Performance of prepare postOptimizeDAE: time 0.0006241/0.3921, allocations: 361.5 kB / 0.8482 GB, free: 0.8477 MB / 0.6544 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002638/0.3923, allocations: 155 kB / 0.8484 GB, free: 0.6953 MB / 0.6544 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002539/0.3949, allocations: 1.141 MB / 0.8495 GB, free: 15.55 MB / 0.67 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.104e-05/0.3949, allocations: 11.98 kB / 0.8495 GB, free: 15.54 MB / 0.67 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.884e-06/0.3949, allocations: 4 kB / 0.8495 GB, free: 15.54 MB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.073e-06/0.3949, allocations: 0 / 0.8495 GB, free: 15.54 MB / 0.67 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.005356/0.4003, allocations: 2.514 MB / 0.8519 GB, free: 12.94 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.348e-05/0.4003, allocations: 4 kB / 0.8519 GB, free: 12.94 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0003726/0.4006, allocations: 83.88 kB / 0.852 GB, free: 12.86 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0007909/0.4014, allocations: 216.8 kB / 0.8522 GB, free: 12.64 MB / 0.67 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 4.41e-05/0.4015, allocations: 15.98 kB / 0.8522 GB, free: 12.62 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001371/0.4029, allocations: 0.7747 MB / 0.853 GB, free: 11.82 MB / 0.67 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.543e-06/0.4029, allocations: 7.922 kB / 0.853 GB, free: 11.82 MB / 0.67 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.003602/0.4065, allocations: 1.204 MB / 0.8542 GB, free: 10.57 MB / 0.67 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006905/0.4072, allocations: 363.8 kB / 0.8545 GB, free: 10.2 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001874/0.4073, allocations: 12 kB / 0.8545 GB, free: 10.19 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0008965/0.4082, allocations: 79.91 kB / 0.8546 GB, free: 10.11 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001001/0.4083, allocations: 40.52 kB / 0.8547 GB, free: 10.07 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.139e-05/0.4084, allocations: 23.98 kB / 0.8547 GB, free: 10.05 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.00189/0.4103, allocations: 1.082 MB / 0.8557 GB, free: 8.969 MB / 0.67 GB
Notification: Performance of sort global known variables: time 8.1e-08/0.4103, allocations: 0 / 0.8557 GB, free: 8.969 MB / 0.67 GB
Notification: Performance of remove unused functions: time 0.001518/0.4118, allocations: 459 kB / 0.8562 GB, free: 8.52 MB / 0.67 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 7
 * 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 (52):
 * Single equations (assignments): 41
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 10
 * 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,9)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.00483/0.4166, allocations: 2.132 MB / 0.8583 GB, free: 6.348 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.002315/0.419, allocations: 0.8283 MB / 0.8591 GB, free: 5.516 MB / 0.67 GB
Notification: Performance of simCode: created event and clocks part: time 4.709e-06/0.419, allocations: 1.125 kB / 0.8591 GB, free: 5.516 MB / 0.67 GB
Notification: Performance of simCode: created simulation system equations: time 0.001326/0.4203, allocations: 415.3 kB / 0.8595 GB, free: 5.109 MB / 0.67 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003455/0.4237, allocations: 0.7365 MB / 0.8602 GB, free: 4.383 MB / 0.67 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.3883/0.8121, allocations: 10.48 MB / 0.8704 GB, free: 142.5 MB / 0.67 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0008314/0.8129, allocations: 1.171 MB / 0.8716 GB, free: 141.8 MB / 0.67 GB
Notification: Performance of simCode: alias equations: time 0.001169/0.8141, allocations: 260.8 kB / 0.8718 GB, free: 141.8 MB / 0.67 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0007099/0.8148, allocations: 0.5464 MB / 0.8723 GB, free: 141.4 MB / 0.67 GB
Notification: Performance of SimCode: time 1.022e-06/0.8148, allocations: 0 / 0.8723 GB, free: 141.4 MB / 0.67 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.07528/0.8901, allocations: 44.28 MB / 0.9156 GB, free: 109 MB / 0.67 GB
Notification: Performance of buildModelFMU: Generate platform static: time 15.12/16.01, allocations: 6.172 kB / 0.9156 GB, free: 108.9 MB / 0.67 GB
"
[Timeout remaining time 644]
(rm -f BuildSysPro_BuildSysPro.IBPSA.Fluid.Chillers.Validation.Carnot_TEva_2ndLaw.pipe ; mkfifo BuildSysPro_BuildSysPro.IBPSA.Fluid.Chillers.Validation.Carnot_TEva_2ndLaw.pipe ; head -c 1048576 < BuildSysPro_BuildSysPro.IBPSA.Fluid.Chillers.Validation.Carnot_TEva_2ndLaw.pipe >> ../files/BuildSysPro_BuildSysPro.IBPSA.Fluid.Chillers.Validation.Carnot_TEva_2ndLaw.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --tempDir=temp_BuildSysPro_BuildSysPro_IBPSA_Fluid_Chillers_Validation_Carnot_TEva_2ndLaw_fmu --startTime=0 --stopTime=1 --stepSize=0.0004 --timeout=50 --tolerance=1e-06 BuildSysPro_BuildSysPro_IBPSA_Fluid_Chillers_Validation_Carnot_TEva_2ndLaw.fmu > BuildSysPro_BuildSysPro.IBPSA.Fluid.Chillers.Validation.Carnot_TEva_2ndLaw.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 20.00381148001179]
Failed to read output from testmodel.py, exit status != 0:
16.18733093800256 16.20496396 15.862398708
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f6744f9f3a0>