Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_IDEAS.Fluid.Chillers.ModularReversible.Validation.TableData2D.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.0008061/0.0008061, allocations: 84.44 kB / 19.91 MB, free: 4 kB / 13.93 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.000874/0.000874, allocations: 169.2 kB / 23.19 MB, free: 1.375 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.9266/0.9266, allocations: 177.2 MB / 203.6 MB, free: 5.77 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-531:686: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-531:694: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.7521/0.7521, allocations: 154.7 MB / 414.7 MB, free: 0.7539 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.ModularReversible.Validation.TableData2D,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.Chillers.ModularReversible.Validation.TableData2D")
translateModel(IDEAS.Fluid.Chillers.ModularReversible.Validation.TableData2D,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.Chillers.ModularReversible.Validation.TableData2D") [Timeout 660]
"Notification: IDEAS 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.0009949/0.0009949, allocations: 67.91 kB / 0.6197 GB, free: 13.05 MB / 0.5886 GB
[/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.
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4699/0.4709, allocations: 102 MB / 0.7192 GB, free: 23.66 MB / 0.6355 GB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Chillers.ModularReversible.Validation.TableData2D): time 0.05935/0.5302, allocations: 75.45 MB / 0.7929 GB, free: 3.164 MB / 0.6667 GB
Notification: Performance of NFInst.instExpressions: time 0.02349/0.5537, allocations: 17.56 MB / 0.81 GB, free: 4.891 MB / 0.6823 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004278/0.558, allocations: 131 kB / 0.8102 GB, free: 4.762 MB / 0.6823 GB
Notification: Performance of NFTyping.typeComponents: time 0.003271/0.5613, allocations: 1.289 MB / 0.8114 GB, free: 3.465 MB / 0.6823 GB
Notification: Performance of NFTyping.typeBindings: time 0.008748/0.57, allocations: 3.086 MB / 0.8144 GB, free: 372 kB / 0.6823 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004601/0.5746, allocations: 2.312 MB / 0.8167 GB, free: 14.08 MB / 0.698 GB
Notification: Performance of NFFlatten.flatten: time 0.006347/0.581, allocations: 5.622 MB / 0.8222 GB, free: 8.438 MB / 0.698 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002654/0.5836, allocations: 2.027 MB / 0.8242 GB, free: 6.355 MB / 0.698 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005471/0.5891, allocations: 3.378 MB / 0.8275 GB, free: 2.973 MB / 0.698 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002136/0.5912, allocations: 1.656 MB / 0.8291 GB, free: 1.309 MB / 0.698 GB
Notification: Performance of NFPackage.collectConstants: time 0.0005117/0.5917, allocations: 308 kB / 0.8294 GB, free: 1.008 MB / 0.698 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002818/0.5946, allocations: 1.645 MB / 0.831 GB, free: 15.36 MB / 0.7136 GB
Notification: Performance of NFScalarize.scalarize: time 0.0006433/0.5952, allocations: 0.7669 MB / 0.8317 GB, free: 14.59 MB / 0.7136 GB
Notification: Performance of NFVerifyModel.verify: time 0.001381/0.5966, allocations: 1.166 MB / 0.8329 GB, free: 13.42 MB / 0.7136 GB
Notification: Performance of NFConvertDAE.convert: time 0.007183/0.6038, allocations: 5.458 MB / 0.8382 GB, free: 7.941 MB / 0.7136 GB
Notification: Performance of FrontEnd - DAE generated: time 6.913e-06/0.6038, allocations: 0 / 0.8382 GB, free: 7.941 MB / 0.7136 GB
Notification: Performance of FrontEnd: time 3.217e-06/0.6038, allocations: 0 / 0.8382 GB, free: 7.941 MB / 0.7136 GB
Notification: Performance of Transformations before backend: time 0.0001482/0.6039, allocations: 4 kB / 0.8382 GB, free: 7.938 MB / 0.7136 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 425
 * Number of variables: 425
Notification: Performance of Generate backend data structure: time 0.005646/0.6096, allocations: 3.232 MB / 0.8414 GB, free: 4.629 MB / 0.7136 GB
Notification: Performance of prepare preOptimizeDAE: time 5.218e-05/0.6096, allocations: 8.031 kB / 0.8414 GB, free: 4.621 MB / 0.7136 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0009138/0.6105, allocations: 386 kB / 0.8417 GB, free: 4.242 MB / 0.7136 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003776/0.6143, allocations: 2.538 MB / 0.8442 GB, free: 1.621 MB / 0.7136 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 9.259e-05/0.6144, allocations: 139.3 kB / 0.8444 GB, free: 1.484 MB / 0.7136 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001916/0.6146, allocations: 172 kB / 0.8445 GB, free: 1.316 MB / 0.7136 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002689/0.6173, allocations: 2.005 MB / 0.8465 GB, free: 15.19 MB / 0.7292 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.515e-05/0.6173, allocations: 8 kB / 0.8465 GB, free: 15.18 MB / 0.7292 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.819e-05/0.6174, allocations: 68 kB / 0.8465 GB, free: 15.12 MB / 0.7292 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.545e-05/0.6175, allocations: 43.94 kB / 0.8466 GB, free: 15.07 MB / 0.7292 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001797/0.6193, allocations: 1.297 MB / 0.8479 GB, free: 13.77 MB / 0.7292 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.006915/0.6262, allocations: 5.918 MB / 0.8536 GB, free: 7.676 MB / 0.7292 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0009816/0.6272, allocations: 0.6115 MB / 0.8542 GB, free: 7.055 MB / 0.7292 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0004088/0.6276, allocations: 327.9 kB / 0.8545 GB, free: 6.734 MB / 0.7292 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002537/0.6278, allocations: 108.4 kB / 0.8547 GB, free: 6.629 MB / 0.7292 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.84e-05/0.6279, allocations: 50.94 kB / 0.8547 GB, free: 6.57 MB / 0.7292 GB
Notification: Performance of pre-optimization done (n=57): time 2.425e-06/0.6279, allocations: 0 / 0.8547 GB, free: 6.57 MB / 0.7292 GB
Notification: Performance of matching and sorting (n=57): time 0.001592/0.6294, allocations: 1.027 MB / 0.8557 GB, free: 5.539 MB / 0.7292 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.248e-05/0.6295, allocations: 111.2 kB / 0.8558 GB, free: 5.406 MB / 0.7292 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001794/0.6313, allocations: 1.865 MB / 0.8576 GB, free: 3.5 MB / 0.7292 GB
Notification: Performance of collectPreVariables (initialization): time 6.409e-05/0.6313, allocations: 37.7 kB / 0.8577 GB, free: 3.457 MB / 0.7292 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0009593/0.6323, allocations: 1.172 MB / 0.8588 GB, free: 2.273 MB / 0.7292 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001438/0.6325, allocations: 214.2 kB / 0.859 GB, free: 2.062 MB / 0.7292 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002051/0.6327, allocations: 214.5 kB / 0.8592 GB, free: 1.848 MB / 0.7292 GB
Notification: Performance of setup shared object (initialization): time 8.584e-05/0.6327, allocations: 338 kB / 0.8595 GB, free: 1.512 MB / 0.7292 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.000435/0.6332, allocations: 332.6 kB / 0.8599 GB, free: 1.184 MB / 0.7292 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007742/0.634, allocations: 1.202 MB / 0.861 GB, free: 15.7 MB / 0.7448 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.000966/0.6349, allocations: 0.9442 MB / 0.862 GB, free: 14.64 MB / 0.7448 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.969e-06/0.6349, allocations: 0 / 0.862 GB, free: 14.64 MB / 0.7448 GB
Notification: Performance of matching and sorting (n=111) (initialization): time 0.001404/0.6363, allocations: 1.148 MB / 0.8631 GB, free: 13.48 MB / 0.7448 GB
Notification: Performance of prepare postOptimizeDAE: time 2.159e-05/0.6363, allocations: 11.94 kB / 0.8631 GB, free: 13.47 MB / 0.7448 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.227e-05/0.6364, allocations: 11.98 kB / 0.8631 GB, free: 13.46 MB / 0.7448 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.294e-05/0.6364, allocations: 16.56 kB / 0.8631 GB, free: 13.45 MB / 0.7448 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004346/0.6368, allocations: 223.6 kB / 0.8633 GB, free: 13.23 MB / 0.7448 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.808e-05/0.6368, allocations: 19.94 kB / 0.8633 GB, free: 13.21 MB / 0.7448 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0006747/0.6375, allocations: 151.8 kB / 0.8635 GB, free: 13.06 MB / 0.7448 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.78e-05/0.6376, allocations: 47.98 kB / 0.8635 GB, free: 13.01 MB / 0.7448 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0009725/0.6385, allocations: 0.787 MB / 0.8643 GB, free: 12.22 MB / 0.7448 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.001013/0.6396, allocations: 1.187 MB / 0.8655 GB, free: 10.75 MB / 0.7448 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0009497/0.6405, allocations: 0.9403 MB / 0.8664 GB, free: 9.688 MB / 0.7448 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.735e-06/0.6405, allocations: 0 / 0.8664 GB, free: 9.688 MB / 0.7448 GB
Notification: Performance of matching and sorting (n=111) (initialization_lambda0): time 0.001388/0.6419, allocations: 1.148 MB / 0.8675 GB, free: 8.531 MB / 0.7448 GB
Notification: Performance of prepare postOptimizeDAE: time 1.364e-05/0.6419, allocations: 8 kB / 0.8675 GB, free: 8.523 MB / 0.7448 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.813e-06/0.6419, allocations: 11.94 kB / 0.8675 GB, free: 8.512 MB / 0.7448 GB
Notification: Performance of postOpt tearingSystem (initialization): time 9.958e-06/0.6419, allocations: 16 kB / 0.8675 GB, free: 8.496 MB / 0.7448 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004074/0.6423, allocations: 219.8 kB / 0.8677 GB, free: 8.281 MB / 0.7448 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.028e-05/0.6423, allocations: 23.94 kB / 0.8678 GB, free: 8.258 MB / 0.7448 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.000644/0.643, allocations: 143.4 kB / 0.8679 GB, free: 8.117 MB / 0.7448 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.091e-05/0.643, allocations: 47.98 kB / 0.868 GB, free: 8.07 MB / 0.7448 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 41
 * Number of states: 0 ()
 * Number of discrete variables: 9 (chi.eff.swiCoo.u2,chi.eff.swiHea.u2,chi.eff.hea,chi.notCoo.y,chi.conCoo.y,chi.hys.y,$PRE.chi.hys.y,chi.eff.hys.y,$PRE.chi.eff.hys.y)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (111):
 * Single equations (assignments): 111
 * 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.000193/0.6432, allocations: 251.3 kB / 0.8682 GB, free: 7.82 MB / 0.7448 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000199/0.6434, allocations: 202.7 kB / 0.8684 GB, free: 7.621 MB / 0.7448 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001302/0.6447, allocations: 0.9999 MB / 0.8694 GB, free: 6.617 MB / 0.7448 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0008182/0.6455, allocations: 0.7684 MB / 0.8701 GB, free: 5.82 MB / 0.7448 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.006e-06/0.6456, allocations: 0 / 0.8701 GB, free: 5.82 MB / 0.7448 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004161/0.646, allocations: 282.5 kB / 0.8704 GB, free: 5.543 MB / 0.7448 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001287/0.6461, allocations: 12 kB / 0.8704 GB, free: 5.531 MB / 0.7448 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0003889/0.6465, allocations: 55.94 kB / 0.8704 GB, free: 5.477 MB / 0.7448 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001323/0.6466, allocations: 56.39 kB / 0.8705 GB, free: 5.422 MB / 0.7448 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.001086/0.6477, allocations: 0.8631 MB / 0.8713 GB, free: 4.547 MB / 0.7448 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.001966/0.6497, allocations: 1.482 MB / 0.8728 GB, free: 3.051 MB / 0.7448 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0004236/0.6501, allocations: 339.9 kB / 0.8731 GB, free: 2.719 MB / 0.7448 GB
Notification: Performance of sorting global known variables: time 0.001454/0.6515, allocations: 1.254 MB / 0.8743 GB, free: 1.445 MB / 0.7448 GB
Notification: Performance of Backend: time 5.91e-07/0.6515, allocations: 0 / 0.8743 GB, free: 1.445 MB / 0.7448 GB
Notification: Performance of simCode: created initialization part: time 0.003686/0.6552, allocations: 1.894 MB / 0.8762 GB, free: 15.77 MB / 0.7605 GB
Notification: Performance of SimCode: time 0.006539/0.6618, allocations: 4.497 MB / 0.8806 GB, free: 11.24 MB / 0.7605 GB
Notification: Performance of Templates: time 0.04538/0.7072, allocations: 39.62 MB / 0.9193 GB, free: 4.203 MB / 0.7917 GB
"
[Timeout remaining time 659]
make -j1 -f IDEAS_IDEAS.Fluid.Chillers.ModularReversible.Validation.TableData2D.makefile [Timeout 660]
(rm -f IDEAS_IDEAS.Fluid.Chillers.ModularReversible.Validation.TableData2D.pipe ; mkfifo IDEAS_IDEAS.Fluid.Chillers.ModularReversible.Validation.TableData2D.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.Chillers.ModularReversible.Validation.TableData2D.pipe >> ../files/IDEAS_IDEAS.Fluid.Chillers.ModularReversible.Validation.TableData2D.sim & ./IDEAS_IDEAS.Fluid.Chillers.ModularReversible.Validation.TableData2D  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_IDEAS.Fluid.Chillers.ModularReversible.Validation.TableData2D.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 5.059410247951746]
Failed to read output from testmodel.py, exit status != 0:
0.7143573821522295 0.727724601 0.12128973100000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x76cc40d01730>