Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.ThermalZones.ReducedOrder.Validation.VDI6007.TestCase6.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.0007918/0.0007918, allocations: 100.4 kB / 19.7 MB, free: 452 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.0008437/0.0008437, allocations: 212.7 kB / 23 MB, free: 2.984 MB / 13.93 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.8517/0.8517, allocations: 230.6 MB / 256.8 MB, free: 9.59 MB / 202.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/package.mo", uses=false) [Timeout 180] "[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Examples/PPD12/Structure.mo:531:21-531:190:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Examples/PPD12/Structure.mo:531:21-531:198:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence. Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/package.mo): time 0.7243/0.7243, allocations: 203.8 MB / 0.5049 GB, free: 10.48 MB / 410.7 MB " [Timeout remaining time 179] Using package IDEAS with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/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.ThermalZones.ReducedOrder.Validation.VDI6007.TestCase6,tolerance=1e-06,outputFormat="empty",numberOfIntervals=86400,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.ThermalZones.ReducedOrder.Validation.VDI6007.TestCase6") translateModel(IDEAS.ThermalZones.ReducedOrder.Validation.VDI6007.TestCase6,tolerance=1e-06,outputFormat="empty",numberOfIntervals=86400,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.ThermalZones.ReducedOrder.Validation.VDI6007.TestCase6") [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.0008618/0.0008618, allocations: 71.45 kB / 0.7194 GB, free: 3.609 MB / 0.5261 GB Notification: Performance of FrontEnd - Absyn->SCode: time 0.4534/0.4543, allocations: 102 MB / 0.819 GB, free: 1.355 MB / 0.6042 GB Notification: Performance of NFInst.instantiate(IDEAS.ThermalZones.ReducedOrder.Validation.VDI6007.TestCase6): time 0.1183/0.5726, allocations: 138.8 MB / 0.9545 GB, free: 10.42 MB / 0.7292 GB Notification: Performance of NFInst.instExpressions: time 0.01546/0.588, allocations: 19.84 MB / 0.9739 GB, free: 6.523 MB / 0.7448 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.001055/0.5891, allocations: 71.44 kB / 0.974 GB, free: 6.453 MB / 0.7448 GB Notification: Performance of NFTyping.typeComponents: time 0.001165/0.5902, allocations: 1.352 MB / 0.9753 GB, free: 5.098 MB / 0.7448 GB Notification: Performance of NFTyping.typeBindings: time 0.0009734/0.5912, allocations: 1.039 MB / 0.9763 GB, free: 4.051 MB / 0.7448 GB Notification: Performance of NFTyping.typeClassSections: time 0.0005448/0.5918, allocations: 433.5 kB / 0.9767 GB, free: 3.629 MB / 0.7448 GB Notification: Performance of NFFlatten.flatten: time 0.0008398/0.5926, allocations: 1.465 MB / 0.9782 GB, free: 2.156 MB / 0.7448 GB Notification: Performance of NFFlatten.resolveConnections: time 0.0006935/0.5933, allocations: 0.6332 MB / 0.9788 GB, free: 1.5 MB / 0.7448 GB Notification: Performance of NFEvalConstants.evaluate: time 0.0004883/0.5938, allocations: 0.6698 MB / 0.9794 GB, free: 0.8281 MB / 0.7448 GB Notification: Performance of NFSimplifyModel.simplify: time 0.00079/0.5946, allocations: 1.187 MB / 0.9806 GB, free: 15.64 MB / 0.7605 GB Notification: Performance of NFPackage.collectConstants: time 7.184e-05/0.5946, allocations: 75.94 kB / 0.9807 GB, free: 15.57 MB / 0.7605 GB Notification: Performance of NFFlatten.collectFunctions: time 0.0001698/0.5948, allocations: 124 kB / 0.9808 GB, free: 15.45 MB / 0.7605 GB Notification: Performance of NFScalarize.scalarize: time 0.0002031/0.595, allocations: 380.2 kB / 0.9811 GB, free: 15.07 MB / 0.7605 GB Notification: Performance of NFVerifyModel.verify: time 0.0002463/0.5953, allocations: 441 kB / 0.9816 GB, free: 14.64 MB / 0.7605 GB Notification: Performance of NFConvertDAE.convert: time 0.0008727/0.5961, allocations: 1.407 MB / 0.9829 GB, free: 13.23 MB / 0.7605 GB Notification: Performance of FrontEnd - DAE generated: time 4.098e-06/0.5961, allocations: 8 kB / 0.9829 GB, free: 13.22 MB / 0.7605 GB Notification: Performance of FrontEnd: time 1.813e-06/0.5961, allocations: 0 / 0.9829 GB, free: 13.22 MB / 0.7605 GB Notification: Performance of Transformations before backend: time 1.06e-05/0.5962, allocations: 0 / 0.9829 GB, free: 13.22 MB / 0.7605 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 121 * Number of variables: 121 Notification: Performance of Generate backend data structure: time 0.001574/0.5977, allocations: 1.724 MB / 0.9846 GB, free: 11.45 MB / 0.7605 GB Notification: Performance of prepare preOptimizeDAE: time 3.978e-05/0.5978, allocations: 12.02 kB / 0.9846 GB, free: 11.44 MB / 0.7605 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0001882/0.598, allocations: 171.9 kB / 0.9848 GB, free: 11.27 MB / 0.7605 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.0006378/0.5986, allocations: 0.6995 MB / 0.9855 GB, free: 10.54 MB / 0.7605 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000102/0.5987, allocations: 100.4 kB / 0.9856 GB, free: 10.43 MB / 0.7605 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002997/0.599, allocations: 52 kB / 0.9856 GB, free: 10.38 MB / 0.7605 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.0006431/0.5996, allocations: 0.5769 MB / 0.9862 GB, free: 9.789 MB / 0.7605 GB Notification: Performance of preOpt findStateOrder (simulation): time 1.547e-05/0.5997, allocations: 7.938 kB / 0.9862 GB, free: 9.781 MB / 0.7605 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 3.112e-05/0.5997, allocations: 19.98 kB / 0.9862 GB, free: 9.762 MB / 0.7605 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 9.658e-06/0.5997, allocations: 16 kB / 0.9862 GB, free: 9.746 MB / 0.7605 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0005487/0.6002, allocations: 451.6 kB / 0.9867 GB, free: 9.309 MB / 0.7605 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.001969/0.6022, allocations: 1.97 MB / 0.9886 GB, free: 7.285 MB / 0.7605 GB Notification: Performance of preOpt comSubExp (simulation): time 0.0006591/0.6029, allocations: 475.7 kB / 0.989 GB, free: 6.809 MB / 0.7605 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0003556/0.6032, allocations: 297 kB / 0.9893 GB, free: 6.52 MB / 0.7605 GB Notification: Performance of preOpt evalFunc (simulation): time 2.382e-05/0.6033, allocations: 8.375 kB / 0.9893 GB, free: 6.512 MB / 0.7605 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0003915/0.6036, allocations: 337.1 kB / 0.9897 GB, free: 6.176 MB / 0.7605 GB Notification: Performance of pre-optimization done (n=52): time 1.683e-06/0.6036, allocations: 0 / 0.9897 GB, free: 6.176 MB / 0.7605 GB Notification: Performance of matching and sorting (n=52): time 0.001743/0.6054, allocations: 1.202 MB / 0.9908 GB, free: 4.957 MB / 0.7605 GB Notification: Performance of inlineWhenForInitialization (initialization): time 3.974e-05/0.6054, allocations: 67.78 kB / 0.9909 GB, free: 4.879 MB / 0.7605 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001598/0.607, allocations: 2.136 MB / 0.993 GB, free: 2.727 MB / 0.7605 GB Notification: Performance of collectPreVariables (initialization): time 3.253e-05/0.6071, allocations: 45.69 kB / 0.993 GB, free: 2.676 MB / 0.7605 GB Notification: Performance of collectInitialEqns (initialization): time 0.0003138/0.6074, allocations: 0.6171 MB / 0.9936 GB, free: 2.055 MB / 0.7605 GB Notification: Performance of collectInitialBindings (initialization): time 0.0001016/0.6075, allocations: 178.6 kB / 0.9938 GB, free: 1.879 MB / 0.7605 GB Notification: Performance of simplifyInitialFunctions (initialization): time 9.836e-05/0.6076, allocations: 106.3 kB / 0.9939 GB, free: 1.77 MB / 0.7605 GB Notification: Performance of setup shared object (initialization): time 7.522e-05/0.6077, allocations: 333.9 kB / 0.9942 GB, free: 1.438 MB / 0.7605 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0002976/0.6079, allocations: 233.2 kB / 0.9944 GB, free: 1.211 MB / 0.7605 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0003413/0.6083, allocations: 357.9 kB / 0.9948 GB, free: 0.8242 MB / 0.7605 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.0005743/0.6089, allocations: 0.5492 MB / 0.9953 GB, free: 244 kB / 0.7605 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.095e-06/0.6089, allocations: 0 / 0.9953 GB, free: 244 kB / 0.7605 GB Notification: Performance of matching and sorting (n=75) (initialization): time 0.001001/0.6099, allocations: 0.9303 MB / 0.9962 GB, free: 15.29 MB / 0.7761 GB Notification: Performance of prepare postOptimizeDAE: time 1.556e-05/0.6099, allocations: 4.547 kB / 0.9962 GB, free: 15.29 MB / 0.7761 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.633e-06/0.6099, allocations: 4 kB / 0.9962 GB, free: 15.29 MB / 0.7761 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.0003281/0.6102, allocations: 189.7 kB / 0.9964 GB, free: 15.1 MB / 0.7761 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0002094/0.6104, allocations: 103.8 kB / 0.9965 GB, free: 15 MB / 0.7761 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002196/0.6126, allocations: 2.888 MB / 0.9993 GB, free: 12.01 MB / 0.7761 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0002217/0.6128, allocations: 87.56 kB / 0.9994 GB, free: 11.93 MB / 0.7761 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 2.649e-05/0.6129, allocations: 20 kB / 0.9994 GB, free: 11.91 MB / 0.7761 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 13 * Number of states: 0 () * Number of discrete variables: 24 (assEqu.nFai,$PRE.assEqu.nFai,$whenCondition6,$PRE.intGai.nextTimeEvent,$whenCondition5,$PRE.reference.nextTimeEvent,$whenCondition4,$PRE.setTemp.nextTimeEvent,$whenCondition3,$whenCondition1,reference.nextTimeEvent,reference.nextTimeEventScaled,$PRE.reference.nextTimeEventScaled,assEqu.satisfied,$PRE.assEqu.satisfied,mean.y_last,$PRE.mean.y_last,$whenCondition2,intGai.nextTimeEvent,intGai.nextTimeEventScaled,$PRE.intGai.nextTimeEventScaled,setTemp.nextTimeEvent,setTemp.nextTimeEventScaled,$PRE.setTemp.nextTimeEventScaled) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (57): * Single equations (assignments): 56 * 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): 1 system {(2,17,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.0006682/0.6135, allocations: 444.1 kB / 0.9999 GB, free: 11.46 MB / 0.7761 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 8.971e-05/0.6136, allocations: 143 kB / 1 GB, free: 11.32 MB / 0.7761 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0009829/0.6146, allocations: 0.8645 MB / 1.001 GB, free: 10.44 MB / 0.7761 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.819e-06/0.6146, allocations: 11.94 kB / 1.001 GB, free: 10.43 MB / 0.7761 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 4.448e-06/0.6146, allocations: 4 kB / 1.001 GB, free: 10.43 MB / 0.7761 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.17e-06/0.6146, allocations: 0 / 1.001 GB, free: 10.43 MB / 0.7761 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001596/0.6162, allocations: 1.612 MB / 1.002 GB, free: 8.77 MB / 0.7761 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.415e-06/0.6162, allocations: 0 / 1.002 GB, free: 8.77 MB / 0.7761 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0001423/0.6164, allocations: 55.89 kB / 1.002 GB, free: 8.715 MB / 0.7761 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.000277/0.6166, allocations: 197.6 kB / 1.003 GB, free: 8.516 MB / 0.7761 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.658e-05/0.6167, allocations: 19.98 kB / 1.003 GB, free: 8.496 MB / 0.7761 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.002941/0.6196, allocations: 4.285 MB / 1.007 GB, free: 4.004 MB / 0.7761 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.325e-06/0.6196, allocations: 3.938 kB / 1.007 GB, free: 4 MB / 0.7761 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001207/0.6208, allocations: 1.2 MB / 1.008 GB, free: 2.777 MB / 0.7761 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0001668/0.621, allocations: 167 kB / 1.008 GB, free: 2.613 MB / 0.7761 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 5.67e-05/0.621, allocations: 23.92 kB / 1.008 GB, free: 2.59 MB / 0.7761 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.000169/0.6212, allocations: 31.97 kB / 1.008 GB, free: 2.559 MB / 0.7761 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001066/0.6213, allocations: 64.39 kB / 1.008 GB, free: 2.496 MB / 0.7761 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 2.982e-05/0.6214, allocations: 19.98 kB / 1.008 GB, free: 2.477 MB / 0.7761 GB Notification: Performance of sorting global known variables: time 0.001008/0.6224, allocations: 1.564 MB / 1.01 GB, free: 0.9141 MB / 0.7761 GB Notification: Performance of sort global known variables: time 8e-08/0.6224, allocations: 0 / 1.01 GB, free: 0.9141 MB / 0.7761 GB Notification: Performance of remove unused functions: time 0.0004871/0.6228, allocations: 235.6 kB / 1.01 GB, free: 0.6836 MB / 0.7761 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 1 * Number of states: 4 (mean.x,assEqu.diff,thermalZoneTwoElements.intWallRC.thermCapInt[1].T,thermalZoneTwoElements.extWallRC.thermCapExt[1].T) * Number of discrete variables: 15 ($whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,mean.y_last,assEqu.nFai,assEqu.satisfied,setTemp.nextTimeEventScaled,setTemp.nextTimeEvent,reference.nextTimeEventScaled,reference.nextTimeEvent,intGai.nextTimeEventScaled,intGai.nextTimeEvent) * Number of discrete states: 9 (intGai.nextTimeEvent,reference.nextTimeEvent,setTemp.nextTimeEvent,mean.x,assEqu.satisfied,assEqu.nFai,setTemp.nextTimeEventScaled,reference.nextTimeEventScaled,intGai.nextTimeEventScaled) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (37): * Single equations (assignments): 27 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 8 * 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): 2 systems {(2,14,100.0%), (1,3,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.0008177/0.6237, allocations: 0.5002 MB / 1.011 GB, free: 440 kB / 0.7761 GB Notification: Performance of simCode: created initialization part: time 0.0009713/0.6246, allocations: 0.742 MB / 1.011 GB, free: 15.67 MB / 0.7917 GB Notification: Performance of simCode: created event and clocks part: time 3.707e-06/0.6246, allocations: 0 / 1.011 GB, free: 15.67 MB / 0.7917 GB Notification: Performance of simCode: created simulation system equations: time 0.0005137/0.6252, allocations: 0.5861 MB / 1.012 GB, free: 15.06 MB / 0.7917 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0006422/0.6258, allocations: 227 kB / 1.012 GB, free: 14.86 MB / 0.7917 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.002807/0.6286, allocations: 2.933 MB / 1.015 GB, free: 11.89 MB / 0.7917 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0004229/0.629, allocations: 0.6476 MB / 1.016 GB, free: 11.21 MB / 0.7917 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 8.674e-05/0.6291, allocations: 59.36 kB / 1.016 GB, free: 11.15 MB / 0.7917 GB Notification: Performance of SimCode: time 5.81e-07/0.6291, allocations: 0 / 1.016 GB, free: 11.15 MB / 0.7917 GB Notification: Performance of Templates: time 0.01596/0.6451, allocations: 21.25 MB / 1.036 GB, free: 5.922 MB / 0.8073 GB " [Timeout remaining time 659] make -j1 -f IDEAS_dev_IDEAS.ThermalZones.ReducedOrder.Validation.VDI6007.TestCase6.makefile [Timeout 660] (rm -f IDEAS_dev_IDEAS.ThermalZones.ReducedOrder.Validation.VDI6007.TestCase6.pipe ; mkfifo IDEAS_dev_IDEAS.ThermalZones.ReducedOrder.Validation.VDI6007.TestCase6.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.ThermalZones.ReducedOrder.Validation.VDI6007.TestCase6.pipe >> ../files/IDEAS_dev_IDEAS.ThermalZones.ReducedOrder.Validation.VDI6007.TestCase6.sim & ./IDEAS_dev_IDEAS.ThermalZones.ReducedOrder.Validation.VDI6007.TestCase6 -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.ThermalZones.ReducedOrder.Validation.VDI6007.TestCase6.pipe 2>&1) [Timeout 480] [Calling sys.exit(0), Time elapsed: 7.422553731128573] Failed to read output from testmodel.py, exit status != 0: 0.6506941821426153 0.658850731 0.060829908 Calling exit ...