Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Electrical.DC.Lines.Examples.DCLines.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001643/0.001643, allocations: 109.5 kB / 17.08 MB, free: 6.008 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001788/0.001788, allocations: 194.2 kB / 20.03 MB, free: 3.082 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.472/1.472, allocations: 222.9 MB / 245.7 MB, free: 4.215 MB / 190.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.0-master/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.0-master/package.mo): time 3.147/3.147, allocations: 489.3 MB / 0.7687 GB, free: 3.781 MB / 0.6075 GB " [Timeout remaining time 176] Using package Buildings with version 12.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.0-master/package.mo) Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo) Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo) Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo) Running command: translateModel(Buildings.Electrical.DC.Lines.Examples.DCLines,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|Sloads|E.S.1.",fileNamePrefix="Buildings_latest_Buildings.Electrical.DC.Lines.Examples.DCLines") translateModel(Buildings.Electrical.DC.Lines.Examples.DCLines,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|Sloads|E.S.1.",fileNamePrefix="Buildings_latest_Buildings.Electrical.DC.Lines.Examples.DCLines") [Timeout 300] "Notification: Performance of FrontEnd - loaded program: time 0.001933/0.001933, allocations: 67.91 kB / 1.103 GB, free: 0.832 MB / 0.8419 GB Notification: Performance of FrontEnd - Absyn->SCode: time 1.061/1.063, allocations: 163.8 MB / 1.263 GB, free: 8.008 MB / 0.9981 GB Notification: Performance of NFInst.instantiate(Buildings.Electrical.DC.Lines.Examples.DCLines): time 0.02759/1.09, allocations: 22.62 MB / 1.285 GB, free: 8.488 MB / 1.014 GB Notification: Performance of NFInst.instExpressions: time 0.03418/1.124, allocations: 17.62 MB / 1.302 GB, free: 12.25 MB / 1.029 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.005212/1.13, allocations: 111.1 kB / 1.302 GB, free: 12.21 MB / 1.029 GB Notification: Performance of NFTyping.typeComponents: time 0.01304/1.143, allocations: 4.181 MB / 1.306 GB, free: 9.695 MB / 1.029 GB Notification: Performance of NFTyping.typeBindings: time 0.01526/1.158, allocations: 3.509 MB / 1.309 GB, free: 7.586 MB / 1.029 GB Notification: Performance of NFTyping.typeClassSections: time 0.00523/1.163, allocations: 1.315 MB / 1.311 GB, free: 6.898 MB / 1.029 GB Notification: Performance of NFFlatten.flatten: time 0.0119/1.175, allocations: 5.62 MB / 1.316 GB, free: 4.25 MB / 1.029 GB Notification: Performance of NFFlatten.resolveConnections: time 0.008007/1.183, allocations: 3.542 MB / 1.32 GB, free: 2.582 MB / 1.029 GB Notification: Performance of NFEvalConstants.evaluate: time 0.006149/1.189, allocations: 2.322 MB / 1.322 GB, free: 1.582 MB / 1.029 GB Notification: Performance of NFSimplifyModel.simplify: time 0.006703/1.196, allocations: 2.427 MB / 1.324 GB, free: 0.5234 MB / 1.029 GB Notification: Performance of NFPackage.collectConstants: time 0.002021/1.198, allocations: 355.3 kB / 1.325 GB, free: 0.5234 MB / 1.029 GB Notification: Performance of NFFlatten.collectFunctions: time 0.00463/1.203, allocations: 1.02 MB / 1.326 GB, free: 260 kB / 1.029 GB Notification: Performance of NFScalarize.scalarize: time 0.002892/1.206, allocations: 1.165 MB / 1.327 GB, free: 15.67 MB / 1.045 GB Notification: Performance of NFVerifyModel.verify: time 0.006692/1.213, allocations: 1.97 MB / 1.329 GB, free: 14.46 MB / 1.045 GB Notification: Performance of NFConvertDAE.convert: time 0.01209/1.225, allocations: 5.106 MB / 1.334 GB, free: 12.55 MB / 1.045 GB Notification: Performance of FrontEnd - DAE generated: time 8.095e-06/1.225, allocations: 0.6094 kB / 1.334 GB, free: 12.55 MB / 1.045 GB Notification: Performance of FrontEnd: time 1.733e-06/1.225, allocations: 0 / 1.334 GB, free: 12.55 MB / 1.045 GB Notification: Performance of Transformations before backend: time 0.0002055/1.225, allocations: 0 / 1.334 GB, free: 12.55 MB / 1.045 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 635 * Number of variables: 635 Notification: Performance of Generate backend data structure: time 0.01391/1.239, allocations: 4.54 MB / 1.338 GB, free: 9.871 MB / 1.045 GB Notification: Performance of prepare preOptimizeDAE: time 4.698e-05/1.239, allocations: 8.625 kB / 1.338 GB, free: 9.871 MB / 1.045 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01729/1.256, allocations: 3.277 MB / 1.341 GB, free: 9.07 MB / 1.045 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.006214/1.262, allocations: 2.229 MB / 1.344 GB, free: 7.48 MB / 1.045 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003122/1.263, allocations: 178.4 kB / 1.344 GB, free: 7.398 MB / 1.045 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.001414/1.264, allocations: 242.8 kB / 1.344 GB, free: 7.312 MB / 1.045 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.01597/1.28, allocations: 4.718 MB / 1.349 GB, free: 4.52 MB / 1.045 GB Notification: Performance of preOpt findStateOrder (simulation): time 0.0001134/1.28, allocations: 1.156 kB / 1.349 GB, free: 4.52 MB / 1.045 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00102/1.281, allocations: 102.1 kB / 1.349 GB, free: 4.445 MB / 1.045 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002667/1.282, allocations: 171.6 kB / 1.349 GB, free: 4.402 MB / 1.045 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01625/1.298, allocations: 5.122 MB / 1.354 GB, free: 1.828 MB / 1.045 GB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.0295/1.327, allocations: 14 MB / 1.367 GB, free: 5.609 MB / 1.061 GB Notification: Performance of preOpt comSubExp (simulation): time 0.01475/1.342, allocations: 3.703 MB / 1.371 GB, free: 3.781 MB / 1.061 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.004347/1.347, allocations: 1.39 MB / 1.372 GB, free: 3.07 MB / 1.061 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0001812/1.347, allocations: 27.98 kB / 1.372 GB, free: 3.059 MB / 1.061 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.004857/1.352, allocations: 1.724 MB / 1.374 GB, free: 1.984 MB / 1.061 GB Notification: Performance of pre-optimization done (n=163): time 7.053e-06/1.352, allocations: 7.938 kB / 1.374 GB, free: 1.977 MB / 1.061 GB Notification: Performance of matching and sorting (n=250): time 0.1336/1.485, allocations: 27.85 MB / 1.401 GB, free: 8.539 MB / 1.092 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003236/1.486, allocations: 0.4889 MB / 1.402 GB, free: 7.941 MB / 1.092 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004629/1.49, allocations: 2.293 MB / 1.404 GB, free: 5.637 MB / 1.092 GB Notification: Performance of collectPreVariables (initialization): time 0.0004717/1.491, allocations: 77.7 kB / 1.404 GB, free: 5.555 MB / 1.092 GB Notification: Performance of collectInitialEqns (initialization): time 0.001604/1.492, allocations: 1.46 MB / 1.406 GB, free: 4.082 MB / 1.092 GB Notification: Performance of collectInitialBindings (initialization): time 0.001139/1.494, allocations: 0.8598 MB / 1.406 GB, free: 3.215 MB / 1.092 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.001234/1.495, allocations: 416.5 kB / 1.407 GB, free: 2.801 MB / 1.092 GB Notification: Performance of setup shared object (initialization): time 0.0001279/1.495, allocations: 317 kB / 1.407 GB, free: 2.488 MB / 1.092 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.005268/1.5, allocations: 1.798 MB / 1.409 GB, free: 0.6797 MB / 1.092 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.005524/1.506, allocations: 2.095 MB / 1.411 GB, free: 14.5 MB / 1.107 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.01144/1.517, allocations: 3.943 MB / 1.415 GB, free: 10.42 MB / 1.107 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.616e-05/1.517, allocations: 4 kB / 1.415 GB, free: 10.42 MB / 1.107 GB Notification: Performance of matching and sorting (n=299) (initialization): time 0.01929/1.537, allocations: 6.081 MB / 1.421 GB, free: 4.246 MB / 1.107 GB Notification: Performance of prepare postOptimizeDAE: time 0.0001435/1.537, allocations: 144.5 kB / 1.421 GB, free: 4.074 MB / 1.107 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.452e-05/1.537, allocations: 4 kB / 1.421 GB, free: 4.07 MB / 1.107 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.008113/1.545, allocations: 2.02 MB / 1.423 GB, free: 2.043 MB / 1.107 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003275/1.548, allocations: 499.2 kB / 1.423 GB, free: 1.555 MB / 1.107 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02902/1.577, allocations: 16.09 MB / 1.439 GB, free: 1.23 MB / 1.123 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004694/1.582, allocations: 303.6 kB / 1.439 GB, free: 0.9336 MB / 1.123 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0006211/1.583, allocations: 96.16 kB / 1.439 GB, free: 0.8398 MB / 1.123 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.007225/1.59, allocations: 2.366 MB / 1.442 GB, free: 14.46 MB / 1.139 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.005682/1.596, allocations: 2.083 MB / 1.444 GB, free: 12.3 MB / 1.139 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01092/1.607, allocations: 3.904 MB / 1.448 GB, free: 8.258 MB / 1.139 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.258e-05/1.607, allocations: 0 / 1.448 GB, free: 8.258 MB / 1.139 GB Notification: Performance of matching and sorting (n=299) (initialization_lambda0): time 0.01872/1.625, allocations: 5.979 MB / 1.453 GB, free: 2.191 MB / 1.139 GB Notification: Performance of prepare postOptimizeDAE: time 0.0001397/1.626, allocations: 148.5 kB / 1.454 GB, free: 2.016 MB / 1.139 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.16e-05/1.626, allocations: 8 kB / 1.454 GB, free: 2.008 MB / 1.139 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.007414/1.633, allocations: 1.907 MB / 1.455 GB, free: 92 kB / 1.139 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003223/1.636, allocations: 495.2 kB / 1.456 GB, free: 15.61 MB / 1.154 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02729/1.664, allocations: 15.61 MB / 1.471 GB, free: 15.71 MB / 1.17 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004762/1.668, allocations: 303.6 kB / 1.471 GB, free: 15.41 MB / 1.17 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0006261/1.669, allocations: 87.97 kB / 1.471 GB, free: 15.32 MB / 1.17 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 24 * Number of states: 0 () * Number of discrete variables: 25 (varLoad5.count,$PRE.varLoad5.count,varLoad5.T_start,$PRE.varLoad5.T_start,varLoad4.count,$PRE.varLoad4.count,varLoad4.T_start,$PRE.varLoad4.T_start,varLoad3.count,$PRE.varLoad3.count,varLoad3.T_start,$PRE.varLoad3.T_start,varLoad2.count,$PRE.varLoad2.count,varLoad2.T_start,$PRE.varLoad2.T_start,varLoad1.count,$PRE.varLoad1.count,varLoad1.T_start,$PRE.varLoad1.T_start,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (172): * Single equations (assignments): 165 * Array equations: 0 * Algorithm blocks: 5 * 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): 1 system {(10,52,45.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(10,52)} Notification: Performance of prepare postOptimizeDAE: time 0.001133/1.67, allocations: 326.1 kB / 1.472 GB, free: 15 MB / 1.17 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001125/1.671, allocations: 349.9 kB / 1.472 GB, free: 14.65 MB / 1.17 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001252/1.673, allocations: 404.1 kB / 1.473 GB, free: 14.25 MB / 1.17 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.702e-05/1.673, allocations: 24 kB / 1.473 GB, free: 14.23 MB / 1.17 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 4.982e-05/1.673, allocations: 12 kB / 1.473 GB, free: 14.21 MB / 1.17 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.134e-05/1.673, allocations: 11.95 kB / 1.473 GB, free: 14.2 MB / 1.17 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.006443/1.679, allocations: 3.227 MB / 1.476 GB, free: 10.92 MB / 1.17 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.025e-05/1.679, allocations: 4 kB / 1.476 GB, free: 10.91 MB / 1.17 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002724/1.682, allocations: 431.4 kB / 1.476 GB, free: 10.49 MB / 1.17 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.007351/1.689, allocations: 1.813 MB / 1.478 GB, free: 8.672 MB / 1.17 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.000495/1.69, allocations: 51.97 kB / 1.478 GB, free: 8.621 MB / 1.17 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02721/1.717, allocations: 15.7 MB / 1.493 GB, free: 8.641 MB / 1.186 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.745e-06/1.717, allocations: 0 / 1.493 GB, free: 8.641 MB / 1.186 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01807/1.735, allocations: 5.605 MB / 1.499 GB, free: 3.008 MB / 1.186 GB Notification: Performance of postOpt removeConstants (simulation): time 0.002244/1.737, allocations: 0.6939 MB / 1.499 GB, free: 2.293 MB / 1.186 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001177/1.739, allocations: 47.98 kB / 1.499 GB, free: 2.246 MB / 1.186 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003805/1.742, allocations: 299.6 kB / 1.5 GB, free: 1.953 MB / 1.186 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001046/1.743, allocations: 298.6 kB / 1.5 GB, free: 1.66 MB / 1.186 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002949/1.744, allocations: 67.97 kB / 1.5 GB, free: 1.594 MB / 1.186 GB Notification: Performance of sorting global known variables: time 0.003117/1.747, allocations: 1.536 MB / 1.502 GB, free: 60 kB / 1.186 GB Notification: Performance of sort global known variables: time 1.5e-07/1.747, allocations: 3.938 kB / 1.502 GB, free: 56 kB / 1.186 GB Notification: Performance of remove unused functions: time 0.006313/1.753, allocations: 1.672 MB / 1.503 GB, free: 14.38 MB / 1.201 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 1 * Number of states: 0 () * Number of discrete variables: 15 ($whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,varLoad1.T_start,varLoad1.count,varLoad2.T_start,varLoad2.count,varLoad3.T_start,varLoad3.count,varLoad4.T_start,varLoad4.count,varLoad5.T_start,varLoad5.count) * Number of discrete states: 5 (varLoad1.count,varLoad2.count,varLoad3.count,varLoad4.count,varLoad5.count) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (128): * Single equations (assignments): 116 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 10 * 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): 1 system {(10,52,60.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(10,52)} Notification: Performance of Backend phase and start with SimCode phase: time 0.0005129/1.754, allocations: 163.8 kB / 1.503 GB, free: 14.21 MB / 1.201 GB Notification: Performance of simCode: created initialization part: time 0.02535/1.779, allocations: 9.485 MB / 1.513 GB, free: 4.539 MB / 1.201 GB Notification: Performance of simCode: created event and clocks part: time 1.007e-05/1.779, allocations: 0 / 1.513 GB, free: 4.539 MB / 1.201 GB Notification: Performance of simCode: created simulation system equations: time 0.00923/1.788, allocations: 3.369 MB / 1.516 GB, free: 1.145 MB / 1.201 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.006557/1.795, allocations: 1.13 MB / 1.517 GB, free: 52 kB / 1.201 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01789/1.813, allocations: 6.845 MB / 1.524 GB, free: 9.176 MB / 1.217 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003619/1.817, allocations: 2.312 MB / 1.526 GB, free: 6.824 MB / 1.217 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002139/1.819, allocations: 199.4 kB / 1.526 GB, free: 6.629 MB / 1.217 GB Notification: Performance of SimCode: time 1.312e-06/1.819, allocations: 0 / 1.526 GB, free: 6.629 MB / 1.217 GB Notification: Performance of Templates: time 0.1256/1.944, allocations: 80.51 MB / 1.605 GB, free: 6.434 MB / 1.295 GB " [Timeout remaining time 298] make -j1 -f Buildings_latest_Buildings.Electrical.DC.Lines.Examples.DCLines.makefile [Timeout 300] (rm -f Buildings_latest_Buildings.Electrical.DC.Lines.Examples.DCLines.pipe ; mkfifo Buildings_latest_Buildings.Electrical.DC.Lines.Examples.DCLines.pipe ; head -c 1048576 < Buildings_latest_Buildings.Electrical.DC.Lines.Examples.DCLines.pipe >> ../files/Buildings_latest_Buildings.Electrical.DC.Lines.Examples.DCLines.sim & ./Buildings_latest_Buildings.Electrical.DC.Lines.Examples.DCLines -abortSlowSimulation -alarm=800 -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.Electrical.DC.Lines.Examples.DCLines.pipe 2>&1) [Timeout 800] [Calling os._exit(0), Time elapsed: 23.58483545994386] Failed to read output from testmodel.py, exit status != 0: 1.9519172420259565 1.965630921 0.738370002 Calling exit ...