Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_IDEAS.Fluid.Chillers.Validation.CarnotVerifyEtaCarnot.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.0009632/0.0009632, allocations: 80.75 kB / 19.94 MB, free: 4.547 MB / 18.57 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.0009982/0.0009982, allocations: 181 kB / 23.36 MB, free: 1.133 MB / 18.57 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 0.9855/0.9855, allocations: 177.1 MB / 203.8 MB, free: 5.469 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-545:108:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-545:116:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.order:0:0-0:0:readonly] Warning: Found duplicate names in package.order file: A, B, C.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.mo:2:1-70:10:writable] Warning: C was referenced in the package.order file, but was not found in package.mo, C/package.mo or C.mo.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo): time 0.7629/0.7629, allocations: 154.6 MB / 414.8 MB, free: 0.5156 MB / 378.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/Cryptographics/BaseClasses/sha.mo:2:1-19:8:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/File/sha2.mo:2:1-9:9:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 179]
Using package IDEAS with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.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: translateModel(IDEAS.Fluid.Chillers.Validation.CarnotVerifyEtaCarnot,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.Chillers.Validation.CarnotVerifyEtaCarnot")
translateModel(IDEAS.Fluid.Chillers.Validation.CarnotVerifyEtaCarnot,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.Chillers.Validation.CarnotVerifyEtaCarnot") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.553e-06/1.553e-06, allocations: 0 / 480 MB, free: 6.895 MB / 442.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.329e-05/2.485e-05, allocations: 8.969 kB / 480 MB, free: 6.891 MB / 442.7 MB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Chillers.Validation.CarnotVerifyEtaCarnot): time 0.02358/0.0236, allocations: 32.23 MB / 0.5002 GB, free: 6.621 MB / 474.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01604/0.03965, allocations: 9.877 MB / 0.5099 GB, free: 12.83 MB / 490.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00338/0.04303, allocations: 172.3 kB / 0.51 GB, free: 12.66 MB / 490.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001743/0.04477, allocations: 0.7752 MB / 0.5108 GB, free: 11.88 MB / 490.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.0055/0.05027, allocations: 2.643 MB / 0.5134 GB, free: 9.25 MB / 490.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004768/0.05504, allocations: 2.266 MB / 0.5156 GB, free: 7.004 MB / 490.7 MB
Notification: Performance of NFFlatten.flatten: time 0.007663/0.0627, allocations: 6.509 MB / 0.5219 GB, free: 484 kB / 490.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.003432/0.06613, allocations: 1.943 MB / 0.5238 GB, free: 14.46 MB / 0.4948 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006403/0.07254, allocations: 3.31 MB / 0.5271 GB, free: 11.17 MB / 0.4948 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002621/0.07516, allocations: 1.485 MB / 0.5285 GB, free: 9.688 MB / 0.4948 GB
Notification: Performance of NFPackage.collectConstants: time 0.000516/0.07567, allocations: 236 kB / 0.5287 GB, free: 9.457 MB / 0.4948 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001692/0.07737, allocations: 0.7098 MB / 0.5294 GB, free: 8.746 MB / 0.4948 GB
Notification: Performance of NFScalarize.scalarize: time 0.0004915/0.07786, allocations: 0.6189 MB / 0.53 GB, free: 8.125 MB / 0.4948 GB
Notification: Performance of NFVerifyModel.verify: time 0.001475/0.07933, allocations: 0.9624 MB / 0.531 GB, free: 7.16 MB / 0.4948 GB
Notification: Performance of NFConvertDAE.convert: time 0.003317/0.08265, allocations: 2.364 MB / 0.5333 GB, free: 4.789 MB / 0.4948 GB
Notification: Performance of FrontEnd - DAE generated: time 5.199e-06/0.08266, allocations: 0 / 0.5333 GB, free: 4.789 MB / 0.4948 GB
Notification: Performance of FrontEnd: time 1.092e-06/0.08266, allocations: 0 / 0.5333 GB, free: 4.789 MB / 0.4948 GB
Notification: Performance of Transformations before backend: time 6.052e-05/0.08272, allocations: 0 / 0.5333 GB, free: 4.789 MB / 0.4948 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 388
 * Number of variables: 388
Notification: Performance of Generate backend data structure: time 0.004058/0.08678, allocations: 3.002 MB / 0.5362 GB, free: 1.73 MB / 0.4948 GB
Notification: Performance of prepare preOptimizeDAE: time 3.627e-05/0.08681, allocations: 8.031 kB / 0.5362 GB, free: 1.723 MB / 0.4948 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002041/0.08885, allocations: 0.715 MB / 0.5369 GB, free: 1.023 MB / 0.4948 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002665/0.09152, allocations: 1.531 MB / 0.5384 GB, free: 15.41 MB / 0.5105 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 9.81e-05/0.09162, allocations: 123 kB / 0.5385 GB, free: 15.29 MB / 0.5105 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001897/0.09181, allocations: 164.5 kB / 0.5387 GB, free: 15.13 MB / 0.5105 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001957/0.09376, allocations: 1.315 MB / 0.54 GB, free: 13.74 MB / 0.5105 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.695e-05/0.09379, allocations: 5.344 kB / 0.54 GB, free: 13.73 MB / 0.5105 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001084/0.0939, allocations: 61.69 kB / 0.54 GB, free: 13.68 MB / 0.5105 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.965e-05/0.09393, allocations: 40 kB / 0.5401 GB, free: 13.64 MB / 0.5105 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.000915/0.09484, allocations: 0.5174 MB / 0.5406 GB, free: 13.12 MB / 0.5105 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.006595/0.1014, allocations: 4.49 MB / 0.545 GB, free: 8.531 MB / 0.5105 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001273/0.1027, allocations: 0.6674 MB / 0.5456 GB, free: 7.855 MB / 0.5105 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0002339/0.1029, allocations: 160.5 kB / 0.5458 GB, free: 7.695 MB / 0.5105 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001132/0.1031, allocations: 40.53 kB / 0.5458 GB, free: 7.656 MB / 0.5105 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.198e-05/0.1031, allocations: 43.41 kB / 0.5459 GB, free: 7.605 MB / 0.5105 GB
Notification: Performance of pre-optimization done (n=76): time 4.088e-06/0.1031, allocations: 0 / 0.5459 GB, free: 7.605 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=76): time 0.002855/0.1059, allocations: 1.043 MB / 0.5469 GB, free: 6.582 MB / 0.5105 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.936e-05/0.106, allocations: 89.73 kB / 0.547 GB, free: 6.477 MB / 0.5105 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001668/0.1077, allocations: 1.548 MB / 0.5485 GB, free: 4.895 MB / 0.5105 GB
Notification: Performance of collectPreVariables (initialization): time 7.254e-05/0.1077, allocations: 45.7 kB / 0.5485 GB, free: 4.844 MB / 0.5105 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000479/0.1082, allocations: 0.7798 MB / 0.5493 GB, free: 4.055 MB / 0.5105 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001956/0.1084, allocations: 277.5 kB / 0.5495 GB, free: 3.781 MB / 0.5105 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003208/0.1087, allocations: 242.6 kB / 0.5498 GB, free: 3.539 MB / 0.5105 GB
Notification: Performance of setup shared object (initialization): time 0.000365/0.1091, allocations: 349.9 kB / 0.5501 GB, free: 3.191 MB / 0.5105 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0003661/0.1095, allocations: 150.6 kB / 0.5502 GB, free: 3.043 MB / 0.5105 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007795/0.1102, allocations: 1.18 MB / 0.5514 GB, free: 1.547 MB / 0.5105 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0008369/0.1111, allocations: 0.6574 MB / 0.552 GB, free: 0.7852 MB / 0.5105 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.653e-06/0.1111, allocations: 2.156 kB / 0.552 GB, free: 0.7852 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=127) (initialization): time 0.002615/0.1137, allocations: 1.047 MB / 0.5531 GB, free: 15.73 MB / 0.5261 GB
Notification: Performance of prepare postOptimizeDAE: time 2.358e-05/0.1137, allocations: 10.03 kB / 0.5531 GB, free: 15.73 MB / 0.5261 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.457e-05/0.1137, allocations: 8 kB / 0.5531 GB, free: 15.72 MB / 0.5261 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0009808/0.1147, allocations: 393.9 kB / 0.5535 GB, free: 15.33 MB / 0.5261 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006198/0.1153, allocations: 227.7 kB / 0.5537 GB, free: 15.11 MB / 0.5261 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0008714/0.1162, allocations: 1.233 MB / 0.5549 GB, free: 13.82 MB / 0.5261 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001119/0.1173, allocations: 215.2 kB / 0.5551 GB, free: 13.61 MB / 0.5261 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.624e-05/0.1174, allocations: 51.91 kB / 0.5551 GB, free: 13.56 MB / 0.5261 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 38
 * 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 (105):
 * Single equations (assignments): 103
 * 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): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(1,8), (1,14)}
Notification: Performance of prepare postOptimizeDAE: time 0.0004606/0.1179, allocations: 176.4 kB / 0.5553 GB, free: 13.38 MB / 0.5261 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002453/0.1181, allocations: 135.8 kB / 0.5554 GB, free: 13.24 MB / 0.5261 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001158/0.1193, allocations: 0.7452 MB / 0.5562 GB, free: 12.5 MB / 0.5261 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.408e-05/0.1193, allocations: 15.92 kB / 0.5562 GB, free: 12.48 MB / 0.5261 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.775e-06/0.1193, allocations: 0 / 0.5562 GB, free: 12.48 MB / 0.5261 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.928e-06/0.1193, allocations: 7.984 kB / 0.5562 GB, free: 12.48 MB / 0.5261 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.003359/0.1227, allocations: 1.909 MB / 0.558 GB, free: 10.48 MB / 0.5261 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.634e-06/0.1227, allocations: 4 kB / 0.5581 GB, free: 10.48 MB / 0.5261 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0003219/0.123, allocations: 116.2 kB / 0.5582 GB, free: 10.36 MB / 0.5261 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.000853/0.1239, allocations: 371.6 kB / 0.5585 GB, free: 9.996 MB / 0.5261 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.308e-05/0.1239, allocations: 23.98 kB / 0.5585 GB, free: 9.973 MB / 0.5261 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.000934/0.1248, allocations: 1.278 MB / 0.5598 GB, free: 8.645 MB / 0.5261 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.438e-06/0.1248, allocations: 4 kB / 0.5598 GB, free: 8.641 MB / 0.5261 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001841/0.1267, allocations: 1.116 MB / 0.5609 GB, free: 7.453 MB / 0.5261 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005332/0.1272, allocations: 253 kB / 0.5611 GB, free: 7.207 MB / 0.5261 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001845/0.1274, allocations: 27.94 kB / 0.5611 GB, free: 7.18 MB / 0.5261 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0006932/0.1281, allocations: 75.94 kB / 0.5612 GB, free: 7.105 MB / 0.5261 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 7.079e-05/0.1282, allocations: 59.84 kB / 0.5613 GB, free: 7.047 MB / 0.5261 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.118e-05/0.1282, allocations: 31.95 kB / 0.5613 GB, free: 7.016 MB / 0.5261 GB
Notification: Performance of sorting global known variables: time 0.0008823/0.1291, allocations: 0.8043 MB / 0.5621 GB, free: 6.188 MB / 0.5261 GB
Notification: Performance of sort global known variables: time 1.7e-07/0.1291, allocations: 0 / 0.5621 GB, free: 6.188 MB / 0.5261 GB
Notification: Performance of remove unused functions: time 0.001363/0.1305, allocations: 0.5846 MB / 0.5627 GB, free: 5.602 MB / 0.5261 GB
Notification: Model statistics after passing the back-end for simulation:
 * 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 simulation (62):
 * Single equations (assignments): 52
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 8
 * 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): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(1,16), (1,9)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0003798/0.1309, allocations: 193.9 kB / 0.5629 GB, free: 5.402 MB / 0.5261 GB
Notification: Performance of simCode: created initialization part: time 0.0013/0.1322, allocations: 0.5748 MB / 0.5634 GB, free: 4.828 MB / 0.5261 GB
Notification: Performance of simCode: created event and clocks part: time 3.061e-05/0.1322, allocations: 20.5 kB / 0.5634 GB, free: 4.809 MB / 0.5261 GB
Notification: Performance of simCode: created simulation system equations: time 0.0007182/0.1329, allocations: 353.9 kB / 0.5638 GB, free: 4.469 MB / 0.5261 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002226/0.1351, allocations: 0.8965 MB / 0.5646 GB, free: 3.625 MB / 0.5261 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003855/0.139, allocations: 3.195 MB / 0.5678 GB, free: 340 kB / 0.5261 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0002551/0.1392, allocations: 350.3 kB / 0.5681 GB, free: 15.96 MB / 0.5417 GB
Notification: Performance of simCode: alias equations: time 0.0009116/0.1401, allocations: 318.7 kB / 0.5684 GB, free: 15.66 MB / 0.5417 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001146/0.1413, allocations: 0.657 MB / 0.569 GB, free: 14.98 MB / 0.5417 GB
Notification: Performance of SimCode: time 1.854e-06/0.1413, allocations: 0 / 0.569 GB, free: 14.98 MB / 0.5417 GB
Notification: Performance of Templates: time 0.04702/0.1883, allocations: 38.09 MB / 0.6062 GB, free: 9.832 MB / 0.573 GB
"
[Timeout remaining time 660]
make -j1 -f IDEAS_IDEAS.Fluid.Chillers.Validation.CarnotVerifyEtaCarnot.makefile [Timeout 660]
(rm -f IDEAS_IDEAS.Fluid.Chillers.Validation.CarnotVerifyEtaCarnot.pipe ; mkfifo IDEAS_IDEAS.Fluid.Chillers.Validation.CarnotVerifyEtaCarnot.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.Chillers.Validation.CarnotVerifyEtaCarnot.pipe >> ../files/IDEAS_IDEAS.Fluid.Chillers.Validation.CarnotVerifyEtaCarnot.sim & ./IDEAS_IDEAS.Fluid.Chillers.Validation.CarnotVerifyEtaCarnot -abortSlowSimulation -alarm=240  -lv LOG_STATS > IDEAS_IDEAS.Fluid.Chillers.Validation.CarnotVerifyEtaCarnot.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 4.581620011013001]