Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedLoadingUnloading.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.001007/0.001007, allocations: 93.06 kB / 19.65 MB, free: 280 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.001024/0.001024, allocations: 169 kB / 23.02 MB, free: 1.551 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.9407/0.9407, allocations: 177.1 MB / 203.4 MB, free: 5.609 MB / 186.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-535:34: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-535:42: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.7867/0.7867, allocations: 154.9 MB / 414.8 MB, free: 404 kB / 378.7 MB [/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/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 master/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 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.Fluid.Storage.Validation.StratifiedLoadingUnloading,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedLoadingUnloading") translateModel(IDEAS.Fluid.Storage.Validation.StratifiedLoadingUnloading,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedLoadingUnloading") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.292e-06/1.292e-06, allocations: 0 / 0.5207 GB, free: 1.832 MB / 490.7 MB Notification: Performance of FrontEnd - Absyn->SCode: time 1.378e-05/1.507e-05, allocations: 2.312 kB / 0.5207 GB, free: 1.828 MB / 490.7 MB Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Storage.Validation.StratifiedLoadingUnloading): time 0.01328/0.01329, allocations: 16.61 MB / 0.5369 GB, free: 1.176 MB / 0.4948 GB Notification: Performance of NFInst.instExpressions: time 0.009538/0.02283, allocations: 6.091 MB / 0.5428 GB, free: 11.12 MB / 0.5105 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.001305/0.02414, allocations: 99.19 kB / 0.5429 GB, free: 11.03 MB / 0.5105 GB Notification: Performance of NFTyping.typeComponents: time 0.001274/0.02541, allocations: 0.5357 MB / 0.5435 GB, free: 10.49 MB / 0.5105 GB Notification: Performance of NFTyping.typeBindings: time 0.002941/0.02835, allocations: 1.811 MB / 0.5452 GB, free: 8.684 MB / 0.5105 GB Notification: Performance of NFTyping.typeClassSections: time 0.002112/0.03046, allocations: 1.506 MB / 0.5467 GB, free: 7.18 MB / 0.5105 GB Notification: Performance of NFFlatten.flatten: time 0.01435/0.04481, allocations: 21.82 MB / 0.568 GB, free: 1.309 MB / 0.5261 GB Notification: Performance of NFFlatten.resolveConnections: time 0.008282/0.0531, allocations: 7.374 MB / 0.5752 GB, free: 9.867 MB / 0.5417 GB Notification: Performance of NFEvalConstants.evaluate: time 0.01508/0.06818, allocations: 10.15 MB / 0.5851 GB, free: 15.81 MB / 0.5573 GB Notification: Performance of NFSimplifyModel.simplify: time 0.009142/0.07732, allocations: 6.458 MB / 0.5914 GB, free: 9.344 MB / 0.5573 GB Notification: Performance of NFPackage.collectConstants: time 0.002306/0.07963, allocations: 0.9219 MB / 0.5923 GB, free: 8.422 MB / 0.5573 GB Notification: Performance of NFFlatten.collectFunctions: time 0.003893/0.08352, allocations: 1.191 MB / 0.5935 GB, free: 7.23 MB / 0.5573 GB Notification: Performance of NFScalarize.scalarize: time 0.001744/0.08526, allocations: 2.488 MB / 0.5959 GB, free: 4.734 MB / 0.5573 GB Notification: Performance of NFVerifyModel.verify: time 0.004444/0.08971, allocations: 3.338 MB / 0.5992 GB, free: 1.391 MB / 0.5573 GB Notification: Performance of NFConvertDAE.convert: time 0.006789/0.0965, allocations: 7.973 MB / 0.607 GB, free: 9.395 MB / 0.573 GB Notification: Performance of FrontEnd - DAE generated: time 4.048e-06/0.0965, allocations: 0.7188 kB / 0.607 GB, free: 9.395 MB / 0.573 GB Notification: Performance of FrontEnd: time 2.354e-06/0.0965, allocations: 0 / 0.607 GB, free: 9.395 MB / 0.573 GB Notification: Performance of Transformations before backend: time 8.43e-05/0.09659, allocations: 1 kB / 0.607 GB, free: 9.395 MB / 0.573 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 1564 * Number of variables: 1564 Notification: Performance of Generate backend data structure: time 0.008579/0.1052, allocations: 8.934 MB / 0.6157 GB, free: 404 kB / 0.573 GB Notification: Performance of prepare preOptimizeDAE: time 4.016e-05/0.1052, allocations: 12.91 kB / 0.6157 GB, free: 392 kB / 0.573 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00219/0.1074, allocations: 1.515 MB / 0.6172 GB, free: 14.87 MB / 0.5886 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.005773/0.1132, allocations: 5.438 MB / 0.6225 GB, free: 9.348 MB / 0.5886 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002159/0.1134, allocations: 460.6 kB / 0.6229 GB, free: 8.895 MB / 0.5886 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005811/0.114, allocations: 0.6136 MB / 0.6235 GB, free: 8.281 MB / 0.5886 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.007213/0.1212, allocations: 6.541 MB / 0.6299 GB, free: 1.059 MB / 0.5886 GB Notification: Performance of preOpt findStateOrder (simulation): time 9.002e-05/0.1213, allocations: 19.83 kB / 0.6299 GB, free: 1.039 MB / 0.5886 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003804/0.1216, allocations: 263.9 kB / 0.6302 GB, free: 0.7812 MB / 0.5886 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001249/0.1218, allocations: 183.9 kB / 0.6304 GB, free: 0.6016 MB / 0.5886 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004157/0.1259, allocations: 2.428 MB / 0.6327 GB, free: 14.17 MB / 0.6042 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.02514/0.1511, allocations: 27.14 MB / 0.6592 GB, free: 2.465 MB / 0.6198 GB Notification: Performance of preOpt comSubExp (simulation): time 0.002448/0.1535, allocations: 1.847 MB / 0.661 GB, free: 0.6289 MB / 0.6198 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.001771/0.1553, allocations: 1.479 MB / 0.6625 GB, free: 15.14 MB / 0.6355 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0004153/0.1557, allocations: 166.3 kB / 0.6626 GB, free: 14.98 MB / 0.6355 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.00216/0.1579, allocations: 2.207 MB / 0.6648 GB, free: 12.48 MB / 0.6355 GB Notification: Performance of preOpt simplifyInStream (simulation): time 0.001341/0.1592, allocations: 325.8 kB / 0.6651 GB, free: 12.16 MB / 0.6355 GB Notification: Performance of pre-optimization done (n=317): time 4.919e-06/0.1592, allocations: 0 / 0.6651 GB, free: 12.16 MB / 0.6355 GB Notification: Performance of matching and sorting (n=317): time 0.005064/0.1643, allocations: 3.407 MB / 0.6684 GB, free: 8.699 MB / 0.6355 GB Notification: Performance of inlineWhenForInitialization (initialization): time 6.904e-05/0.1643, allocations: 85.91 kB / 0.6685 GB, free: 8.602 MB / 0.6355 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004381/0.1687, allocations: 4.191 MB / 0.6726 GB, free: 4.395 MB / 0.6355 GB Notification: Performance of collectPreVariables (initialization): time 0.0001624/0.1689, allocations: 89.7 kB / 0.6727 GB, free: 4.301 MB / 0.6355 GB Notification: Performance of collectInitialEqns (initialization): time 0.002096/0.171, allocations: 3.5 MB / 0.6761 GB, free: 0.7773 MB / 0.6355 GB Notification: Performance of collectInitialBindings (initialization): time 0.5424/0.7134, allocations: 1.216 MB / 0.6773 GB, free: 157.9 MB / 0.6355 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0009727/0.7143, allocations: 0.9282 MB / 0.6782 GB, free: 157.8 MB / 0.6355 GB Notification: Performance of setup shared object (initialization): time 3.886e-05/0.7144, allocations: 479.4 kB / 0.6787 GB, free: 157.4 MB / 0.6355 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0009733/0.7154, allocations: 0.6768 MB / 0.6793 GB, free: 157.3 MB / 0.6355 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.001484/0.7168, allocations: 3.031 MB / 0.6823 GB, free: 154.5 MB / 0.6355 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.002669/0.7195, allocations: 3.973 MB / 0.6862 GB, free: 151.8 MB / 0.6355 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.698e-05/0.7195, allocations: 14.38 kB / 0.6862 GB, free: 151.8 MB / 0.6355 GB Notification: Performance of matching and sorting (n=569) (initialization): time 0.004475/0.724, allocations: 3.889 MB / 0.69 GB, free: 151.6 MB / 0.6355 GB Notification: Performance of prepare postOptimizeDAE: time 4.048e-05/0.724, allocations: 54.77 kB / 0.69 GB, free: 151.5 MB / 0.6355 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.927e-05/0.7241, allocations: 48.81 kB / 0.6901 GB, free: 151.5 MB / 0.6355 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.001018/0.7251, allocations: 420 kB / 0.6905 GB, free: 151.5 MB / 0.6355 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001868/0.727, allocations: 0.9948 MB / 0.6915 GB, free: 151.5 MB / 0.6355 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002552/0.7295, allocations: 5.287 MB / 0.6966 GB, free: 147.6 MB / 0.6355 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001953/0.7315, allocations: 279.8 kB / 0.6969 GB, free: 147.6 MB / 0.6355 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002508/0.7317, allocations: 217.7 kB / 0.6971 GB, free: 147.6 MB / 0.6355 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 222 * Number of states: 0 () * Number of discrete variables: 7 (pulse.count,$PRE.pulse.count,pulse.T_start,$PRE.pulse.T_start,$whenCondition1,dHTan.local_reset,dHTanEnh.local_reset) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (540): * Single equations (assignments): 537 * Array equations: 0 * Algorithm blocks: 1 * 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): 2 systems {(1,14,100.0%), (1,14,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.001285/0.733, allocations: 457.4 kB / 0.6975 GB, free: 147.5 MB / 0.6355 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005654/0.7336, allocations: 0.5928 MB / 0.6981 GB, free: 147.4 MB / 0.6355 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003038/0.7366, allocations: 2.632 MB / 0.7007 GB, free: 147.2 MB / 0.6355 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.696e-05/0.7366, allocations: 39.25 kB / 0.7007 GB, free: 147.2 MB / 0.6355 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 6.181e-06/0.7366, allocations: 9.125 kB / 0.7007 GB, free: 147.2 MB / 0.6355 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.002166/0.7388, allocations: 2.052 MB / 0.7027 GB, free: 146.7 MB / 0.6355 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01108/0.7499, allocations: 7.961 MB / 0.7105 GB, free: 146 MB / 0.6355 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.368e-06/0.7499, allocations: 1.406 kB / 0.7105 GB, free: 146 MB / 0.6355 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001566/0.7515, allocations: 0.636 MB / 0.7111 GB, free: 146 MB / 0.6355 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.001146/0.7526, allocations: 349.7 kB / 0.7115 GB, free: 146 MB / 0.6355 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001974/0.7528, allocations: 91.2 kB / 0.7115 GB, free: 146 MB / 0.6355 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.00263/0.7554, allocations: 5.189 MB / 0.7166 GB, free: 141.9 MB / 0.6355 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.675e-06/0.7554, allocations: 5.5 kB / 0.7166 GB, free: 141.9 MB / 0.6355 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.005554/0.761, allocations: 3.492 MB / 0.72 GB, free: 140.6 MB / 0.6355 GB Notification: Performance of postOpt removeConstants (simulation): time 0.002773/0.7638, allocations: 0.9644 MB / 0.721 GB, free: 140.1 MB / 0.6355 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001119/0.7649, allocations: 64.41 kB / 0.721 GB, free: 140 MB / 0.6355 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001869/0.7667, allocations: 184.8 kB / 0.7212 GB, free: 140 MB / 0.6355 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003713/0.7671, allocations: 206.8 kB / 0.7214 GB, free: 139.9 MB / 0.6355 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002204/0.7673, allocations: 143.1 kB / 0.7215 GB, free: 139.9 MB / 0.6355 GB Notification: Performance of sorting global known variables: time 0.002996/0.7703, allocations: 2.157 MB / 0.7236 GB, free: 139.2 MB / 0.6355 GB Notification: Performance of sort global known variables: time 1.71e-07/0.7703, allocations: 0.6562 kB / 0.7236 GB, free: 139.2 MB / 0.6355 GB Notification: Performance of remove unused functions: time 0.004693/0.775, allocations: 2.015 MB / 0.7256 GB, free: 138.9 MB / 0.6355 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 5 * Number of states: 16 (tanEnh.vol[1].dynBal.U,tanEnh.vol[2].dynBal.U,tanEnh.vol[3].dynBal.U,tanEnh.vol[4].dynBal.U,tanEnh.vol[5].dynBal.U,tanEnh.vol[6].dynBal.U,tanEnh.vol[7].dynBal.U,tan.vol[1].dynBal.U,tan.vol[2].dynBal.U,tan.vol[3].dynBal.U,tan.vol[4].dynBal.U,tan.vol[5].dynBal.U,tan.vol[6].dynBal.U,tan.vol[7].dynBal.U,dHTanEnh.y,dHTan.y) * Number of discrete variables: 3 ($whenCondition1,pulse.T_start,pulse.count) * Number of discrete states: 1 (pulse.count) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (296): * Single equations (assignments): 289 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 3 * When equations: 2 * 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 {(1,14,100.0%), (1,14,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.0007625/0.7758, allocations: 285.2 kB / 0.7259 GB, free: 138.8 MB / 0.6355 GB Notification: Performance of simCode: created initialization part: time 0.004006/0.7798, allocations: 3.036 MB / 0.7288 GB, free: 137.8 MB / 0.6355 GB Notification: Performance of simCode: created event and clocks part: time 7.955e-06/0.7798, allocations: 4.141 kB / 0.7289 GB, free: 137.8 MB / 0.6355 GB Notification: Performance of simCode: created simulation system equations: time 0.001541/0.7813, allocations: 1.562 MB / 0.7304 GB, free: 137.2 MB / 0.6355 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.007331/0.7887, allocations: 2.84 MB / 0.7332 GB, free: 136.5 MB / 0.6355 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01341/0.8021, allocations: 10.79 MB / 0.7437 GB, free: 131.3 MB / 0.6355 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001922/0.804, allocations: 1.776 MB / 0.7454 GB, free: 130.6 MB / 0.6355 GB Notification: Performance of simCode: alias equations: time 0.004489/0.8085, allocations: 1.525 MB / 0.7469 GB, free: 129.6 MB / 0.6355 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001131/0.8096, allocations: 434.9 kB / 0.7473 GB, free: 129.2 MB / 0.6355 GB Notification: Performance of SimCode: time 2.665e-06/0.8096, allocations: 4 kB / 0.7473 GB, free: 129.2 MB / 0.6355 GB Notification: Performance of Templates: time 0.0973/0.9069, allocations: 105.3 MB / 0.8502 GB, free: 30.12 MB / 0.6355 GB " [Timeout remaining time 659] make -j1 -f IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedLoadingUnloading.makefile [Timeout 660] (rm -f IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedLoadingUnloading.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedLoadingUnloading.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedLoadingUnloading.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedLoadingUnloading.sim & ./IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedLoadingUnloading -abortSlowSimulation -alarm=240 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedLoadingUnloading.pipe 2>&1) [Timeout 240] [Calling sys.exit(0), Time elapsed: 6.626362888608128]