Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerSystems_PowerSystems.Examples.PowerWorld.Test.HydroPlantTest2.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.001464/0.001464, allocations: 88.84 kB / 20.68 MB, free: 1.031 MB / 14.72 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.00163/0.00163, allocations: 163.9 kB / 23.96 MB, free: 4.152 MB / 14.72 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 1.473/1.473, allocations: 177.2 MB / 204.3 MB, free: 9.184 MB / 190.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.0.0/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.0.0/package.mo): time 0.1823/0.1823, allocations: 29.43 MB / 290.1 MB, free: 3.285 MB / 270.1 MB " [Timeout remaining time 180] Using package PowerSystems with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.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(PowerSystems.Examples.PowerWorld.Test.HydroPlantTest2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="PowerSystems_PowerSystems.Examples.PowerWorld.Test.HydroPlantTest2") translateModel(PowerSystems.Examples.PowerWorld.Test.HydroPlantTest2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="PowerSystems_PowerSystems.Examples.PowerWorld.Test.HydroPlantTest2") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.753e-06/1.753e-06, allocations: 0 / 430.7 MB, free: 1.957 MB / 350.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 3.057e-05/3.232e-05, allocations: 6.219 kB / 430.7 MB, free: 1.949 MB / 350.1 MB Notification: Performance of NFInst.instantiate(PowerSystems.Examples.PowerWorld.Test.HydroPlantTest2): time 0.007761/0.007794, allocations: 5.327 MB / 436 MB, free: 12.59 MB / 366.1 MB Notification: Performance of NFInst.instExpressions: time 0.004441/0.01224, allocations: 2.477 MB / 438.5 MB, free: 10.1 MB / 366.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.0005156/0.01275, allocations: 31.75 kB / 438.5 MB, free: 10.07 MB / 366.1 MB Notification: Performance of NFTyping.typeComponents: time 0.001333/0.01408, allocations: 369.9 kB / 438.9 MB, free: 9.703 MB / 366.1 MB Notification: Performance of NFTyping.typeBindings: time 0.001102/0.01519, allocations: 481.2 kB / 439.4 MB, free: 9.23 MB / 366.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.001177/0.01636, allocations: 493.9 kB / 439.9 MB, free: 8.75 MB / 366.1 MB Notification: Performance of NFFlatten.flatten: time 0.001235/0.0176, allocations: 1.041 MB / 440.9 MB, free: 7.707 MB / 366.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.0007455/0.01834, allocations: 496.5 kB / 441.4 MB, free: 7.215 MB / 366.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.0008265/0.01917, allocations: 0.5572 MB / 441.9 MB, free: 6.656 MB / 366.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.0005742/0.01974, allocations: 362.9 kB / 442.3 MB, free: 6.301 MB / 366.1 MB Notification: Performance of NFPackage.collectConstants: time 0.0001192/0.01986, allocations: 76 kB / 442.4 MB, free: 6.227 MB / 366.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.0008026/0.02067, allocations: 442.6 kB / 442.8 MB, free: 5.793 MB / 366.1 MB Notification: Performance of NFScalarize.scalarize: time 0.0002654/0.02093, allocations: 251.2 kB / 443.1 MB, free: 5.547 MB / 366.1 MB Notification: Performance of NFVerifyModel.verify: time 0.0003208/0.02125, allocations: 251.3 kB / 443.3 MB, free: 5.301 MB / 366.1 MB Notification: Performance of NFConvertDAE.convert: time 0.001411/0.02266, allocations: 1.118 MB / 444.4 MB, free: 4.18 MB / 366.1 MB Notification: Performance of FrontEnd - DAE generated: time 3.847e-06/0.02267, allocations: 0 / 444.4 MB, free: 4.18 MB / 366.1 MB Notification: Performance of FrontEnd: time 1.252e-06/0.02267, allocations: 0 / 444.4 MB, free: 4.18 MB / 366.1 MB Notification: Performance of Transformations before backend: time 9.197e-06/0.02268, allocations: 0 / 444.4 MB, free: 4.18 MB / 366.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 146 * Number of variables: 146 Notification: Performance of Generate backend data structure: time 0.002217/0.02489, allocations: 1.048 MB / 445.5 MB, free: 3.094 MB / 366.1 MB Notification: Performance of prepare preOptimizeDAE: time 3.783e-05/0.02493, allocations: 15.98 kB / 445.5 MB, free: 3.078 MB / 366.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001308/0.02624, allocations: 188.3 kB / 445.7 MB, free: 2.895 MB / 366.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.0009947/0.02724, allocations: 0.494 MB / 446.2 MB, free: 2.371 MB / 366.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.26e-05/0.02729, allocations: 49.67 kB / 446.2 MB, free: 2.324 MB / 366.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001181/0.02741, allocations: 64.31 kB / 446.3 MB, free: 2.262 MB / 366.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.001559/0.02896, allocations: 0.8419 MB / 447.1 MB, free: 1.367 MB / 366.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 2.936e-05/0.02899, allocations: 4.172 kB / 447.1 MB, free: 1.363 MB / 366.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 7.402e-05/0.02907, allocations: 36.06 kB / 447.1 MB, free: 1.328 MB / 366.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.405e-05/0.02912, allocations: 29.3 kB / 447.2 MB, free: 1.297 MB / 366.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001646/0.03077, allocations: 0.7272 MB / 447.9 MB, free: 0.5703 MB / 366.1 MB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.1954/0.2262, allocations: 2.453 MB / 450.4 MB, free: 28.75 MB / 366.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.001322/0.2275, allocations: 0.552 MB / 450.9 MB, free: 28.54 MB / 366.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.0006669/0.2282, allocations: 326.4 kB / 451.2 MB, free: 28.43 MB / 366.1 MB Notification: Performance of preOpt evalFunc (simulation): time 5.111e-05/0.2282, allocations: 12.8 kB / 451.2 MB, free: 28.42 MB / 366.1 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0007885/0.229, allocations: 422.3 kB / 451.7 MB, free: 28.24 MB / 366.1 MB Notification: Performance of pre-optimization done (n=56): time 2.765e-06/0.229, allocations: 3.531 kB / 451.7 MB, free: 28.24 MB / 366.1 MB Notification: Performance of matching and sorting (n=58): time 0.004216/0.2332, allocations: 1.972 MB / 453.6 MB, free: 27.38 MB / 366.1 MB Notification: Performance of inlineWhenForInitialization (initialization): time 7.402e-05/0.2333, allocations: 137.3 kB / 453.8 MB, free: 27.22 MB / 366.1 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0008166/0.2341, allocations: 447.9 kB / 454.2 MB, free: 27.02 MB / 366.1 MB Notification: Performance of collectPreVariables (initialization): time 5.544e-05/0.2342, allocations: 37.45 kB / 454.2 MB, free: 26.98 MB / 366.1 MB Notification: Performance of collectInitialEqns (initialization): time 0.0002851/0.2345, allocations: 326.5 kB / 454.6 MB, free: 26.75 MB / 366.1 MB Notification: Performance of collectInitialBindings (initialization): time 0.0001743/0.2347, allocations: 200.5 kB / 454.8 MB, free: 26.62 MB / 366.1 MB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002298/0.2349, allocations: 116.9 kB / 454.9 MB, free: 26.55 MB / 366.1 MB Notification: Performance of setup shared object (initialization): time 0.0001454/0.235, allocations: 368.4 kB / 455.2 MB, free: 26.22 MB / 366.1 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0007379/0.2358, allocations: 319.9 kB / 455.5 MB, free: 26.08 MB / 366.1 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007706/0.2365, allocations: 488.6 kB / 456 MB, free: 25.76 MB / 366.1 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.001282/0.2378, allocations: 0.721 MB / 456.7 MB, free: 25.38 MB / 366.1 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.885e-06/0.2378, allocations: 4 kB / 456.7 MB, free: 25.38 MB / 366.1 MB Notification: Performance of matching and sorting (n=74) (initialization): time 0.002371/0.2402, allocations: 1.033 MB / 457.8 MB, free: 24.94 MB / 366.1 MB Notification: Performance of prepare postOptimizeDAE: time 5.482e-05/0.2402, allocations: 34.16 kB / 457.8 MB, free: 24.91 MB / 366.1 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.306e-05/0.2403, allocations: 7.188 kB / 457.8 MB, free: 24.91 MB / 366.1 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.0007631/0.241, allocations: 182.8 kB / 458 MB, free: 24.85 MB / 366.1 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005671/0.2416, allocations: 116.3 kB / 458.1 MB, free: 24.84 MB / 366.1 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004015/0.2456, allocations: 3.845 MB / 462 MB, free: 21.69 MB / 366.1 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005969/0.2462, allocations: 59.95 kB / 462 MB, free: 21.68 MB / 366.1 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.69e-05/0.2463, allocations: 23.62 kB / 462 MB, free: 21.67 MB / 366.1 MB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0008924/0.2472, allocations: 475.3 kB / 462.5 MB, free: 21.42 MB / 366.1 MB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0006597/0.2478, allocations: 475.6 kB / 463 MB, free: 21.11 MB / 366.1 MB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.001187/0.249, allocations: 0.6974 MB / 463.7 MB, free: 20.73 MB / 366.1 MB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 3.637e-06/0.249, allocations: 1.094 kB / 463.7 MB, free: 20.73 MB / 366.1 MB Notification: Performance of matching and sorting (n=74) (initialization_lambda0): time 0.001884/0.2509, allocations: 0.9584 MB / 464.6 MB, free: 20.29 MB / 366.1 MB Notification: Performance of prepare postOptimizeDAE: time 2.767e-05/0.2509, allocations: 39.41 kB / 464.7 MB, free: 20.25 MB / 366.1 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.681e-06/0.2509, allocations: 2.344 kB / 464.7 MB, free: 20.25 MB / 366.1 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.0005159/0.2514, allocations: 171.4 kB / 464.8 MB, free: 20.2 MB / 366.1 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005053/0.2519, allocations: 112.5 kB / 464.9 MB, free: 20.19 MB / 366.1 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00297/0.2549, allocations: 3.657 MB / 468.6 MB, free: 17.08 MB / 366.1 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005694/0.2555, allocations: 54.25 kB / 468.6 MB, free: 17.07 MB / 366.1 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.391e-05/0.2555, allocations: 27.03 kB / 468.7 MB, free: 17.07 MB / 366.1 MB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 13 * Number of states: 0 () * Number of discrete variables: 7 (system.initime,secondary.count,$PRE.secondary.count,secondary.T_start,$PRE.secondary.T_start,$whenCondition1,hydroPlant.reservoirLevel.local_reset) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (59): * Single equations (assignments): 56 * 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): 1 system {(1,1,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(1,13)} Notification: Performance of prepare postOptimizeDAE: time 0.0002096/0.2557, allocations: 214.2 kB / 468.9 MB, free: 16.87 MB / 366.1 MB Notification: Performance of postOpt lateInlineFunction (simulation): time 9.402e-05/0.2558, allocations: 62.41 kB / 468.9 MB, free: 16.83 MB / 366.1 MB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.00182/0.2577, allocations: 0.8128 MB / 469.8 MB, free: 16.49 MB / 366.1 MB Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.856e-06/0.2577, allocations: 6.969 kB / 469.8 MB, free: 16.49 MB / 366.1 MB Notification: Performance of postOpt constantLinearSystem (simulation): time 5.651e-06/0.2577, allocations: 6.344 kB / 469.8 MB, free: 16.49 MB / 366.1 MB Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.136e-05/0.2577, allocations: 7.375 kB / 469.8 MB, free: 16.49 MB / 366.1 MB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.002723/0.2604, allocations: 1.477 MB / 471.3 MB, free: 15.75 MB / 366.1 MB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.801e-06/0.2604, allocations: 6.219 kB / 471.3 MB, free: 15.75 MB / 366.1 MB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0006374/0.261, allocations: 126.6 kB / 471.4 MB, free: 15.74 MB / 366.1 MB Notification: Performance of postOpt tearingSystem (simulation): time 0.0002272/0.2613, allocations: 64.89 kB / 471.4 MB, free: 15.71 MB / 366.1 MB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.816e-05/0.2613, allocations: 18.98 kB / 471.5 MB, free: 15.7 MB / 366.1 MB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.00145/0.2628, allocations: 2.634 MB / 474.1 MB, free: 13.16 MB / 366.1 MB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.499e-06/0.2628, allocations: 6.688 kB / 474.1 MB, free: 13.15 MB / 366.1 MB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002333/0.2651, allocations: 1.297 MB / 475.4 MB, free: 12.52 MB / 366.1 MB Notification: Performance of postOpt removeConstants (simulation): time 0.0002569/0.2654, allocations: 111.7 kB / 475.5 MB, free: 12.46 MB / 366.1 MB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 8.297e-05/0.2655, allocations: 14.55 kB / 475.5 MB, free: 12.46 MB / 366.1 MB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0005045/0.266, allocations: 62.56 kB / 475.6 MB, free: 12.45 MB / 366.1 MB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001534/0.2661, allocations: 61.64 kB / 475.6 MB, free: 12.43 MB / 366.1 MB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.725e-05/0.2662, allocations: 18.69 kB / 475.7 MB, free: 12.43 MB / 366.1 MB Notification: Performance of sorting global known variables: time 0.0004779/0.2666, allocations: 309.8 kB / 476 MB, free: 12.28 MB / 366.1 MB Notification: Performance of sort global known variables: time 7e-08/0.2666, allocations: 0 / 476 MB, free: 12.28 MB / 366.1 MB Notification: Performance of remove unused functions: time 0.001131/0.2678, allocations: 259.2 kB / 476.2 MB, free: 12.25 MB / 366.1 MB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 8 * Number of states: 4 (hydroPlant.reservoirLevel.y,hydroPlant.rotor.phi,hydroPlant.rotor.w,hydroPlant.controlDynamics.x_scaled[1]) * Number of discrete variables: 4 (system.initime,$whenCondition1,secondary.T_start,secondary.count) * Number of discrete states: 1 (secondary.count) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (62): * Single equations (assignments): 57 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 3 * 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,2,100.0%), (1,1,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.0001486/0.2679, allocations: 119.3 kB / 476.3 MB, free: 12.15 MB / 366.1 MB Notification: Performance of simCode: created initialization part: time 0.002968/0.2709, allocations: 1.749 MB / 478.1 MB, free: 11.11 MB / 366.1 MB Notification: Performance of simCode: created event and clocks part: time 3.417e-06/0.2709, allocations: 0 / 478.1 MB, free: 11.11 MB / 366.1 MB Notification: Performance of simCode: created simulation system equations: time 0.0008881/0.2718, allocations: 0.5752 MB / 478.7 MB, free: 10.77 MB / 366.1 MB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0007848/0.2726, allocations: 122.9 kB / 478.8 MB, free: 10.73 MB / 366.1 MB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.002365/0.2749, allocations: 1.424 MB / 480.2 MB, free: 9.945 MB / 366.1 MB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.000342/0.2753, allocations: 347.3 kB / 480.5 MB, free: 9.754 MB / 366.1 MB Notification: Performance of simCode: alias equations: time 0.0003998/0.2757, allocations: 145.8 kB / 480.7 MB, free: 9.699 MB / 366.1 MB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001602/0.2758, allocations: 54.33 kB / 480.7 MB, free: 9.68 MB / 366.1 MB Notification: Performance of SimCode: time 4.31e-07/0.2758, allocations: 3.938 kB / 480.7 MB, free: 9.676 MB / 366.1 MB Notification: Performance of Templates: time 0.01507/0.2909, allocations: 9.761 MB / 490.5 MB, free: 0.9219 MB / 366.1 MB " [Timeout remaining time 660] make -j1 -f PowerSystems_PowerSystems.Examples.PowerWorld.Test.HydroPlantTest2.makefile [Timeout 660] (rm -f PowerSystems_PowerSystems.Examples.PowerWorld.Test.HydroPlantTest2.pipe ; mkfifo PowerSystems_PowerSystems.Examples.PowerWorld.Test.HydroPlantTest2.pipe ; head -c 1048576 < PowerSystems_PowerSystems.Examples.PowerWorld.Test.HydroPlantTest2.pipe >> ../files/PowerSystems_PowerSystems.Examples.PowerWorld.Test.HydroPlantTest2.sim & ./PowerSystems_PowerSystems.Examples.PowerWorld.Test.HydroPlantTest2 -abortSlowSimulation -alarm=480 -lv LOG_STATS > PowerSystems_PowerSystems.Examples.PowerWorld.Test.HydroPlantTest2.pipe 2>&1) [Timeout 480] [Calling sys.exit(0), Time elapsed: 5.043198693543673] Failed to read output from testmodel.py, exit status != 0: 0.30019785836338997 0.31435258299999996 0.28861925599999994 Calling exit ...