Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.PSS.PSS2A.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 90] Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001322/0.001322, allocations: 105.3 kB / 18.05 MB, free: 4.289 MB / 14.72 MB [Timeout remaining time 90] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 90] Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.419/1.419, allocations: 225.6 MB / 246.6 MB, free: 13.88 MB / 206.1 MB [Timeout remaining time 88] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 90] Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.002389/0.002389, allocations: 190.9 kB / 301.8 MB, free: 9.281 MB / 238.1 MB [Timeout remaining time 90] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo", uses=false) [Timeout 90] Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.5381/0.5381, allocations: 94.16 MB / 451 MB, free: 9.949 MB / 318.1 MB [Timeout remaining time 89] Using package OpenIPSL with version 3.1.0-dev (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/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 Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 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(OpenIPSL.Tests.Controls.PSSE.PSS.PSS2A,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.PSS.PSS2A") translateModel(OpenIPSL.Tests.Controls.PSSE.PSS.PSS2A,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.PSS.PSS2A") [Timeout 660] Notification: Performance of FrontEnd - loaded program: time 0.003881/0.003881, allocations: 87.86 kB / 0.6025 GB, free: 15.36 MB / 414.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.1503/0.1542, allocations: 75.54 MB / 0.6762 GB, free: 3.66 MB / 478.1 MB Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSSE.PSS.PSS2A): time 0.0121/0.1663, allocations: 10.62 MB / 0.6866 GB, free: 8.988 MB / 494.1 MB Notification: Performance of NFInst.instExpressions: time 0.00747/0.1738, allocations: 3.809 MB / 0.6903 GB, free: 5.168 MB / 494.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.001135/0.1749, allocations: 27.94 kB / 0.6904 GB, free: 5.141 MB / 494.1 MB Notification: Performance of NFTyping.typeComponents: time 0.001282/0.1762, allocations: 0.5309 MB / 0.6909 GB, free: 4.605 MB / 494.1 MB Notification: Performance of NFTyping.typeBindings: time 0.003337/0.1795, allocations: 1.41 MB / 0.6923 GB, free: 3.191 MB / 494.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.003233/0.1827, allocations: 1.488 MB / 0.6937 GB, free: 1.711 MB / 494.1 MB Notification: Performance of NFFlatten.flatten: time 0.2744/0.4572, allocations: 4.588 MB / 0.6982 GB, free: 9.992 MB / 494.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.0008937/0.458, allocations: 0.5359 MB / 0.6987 GB, free: 9.855 MB / 494.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.002227/0.4603, allocations: 1.72 MB / 0.7004 GB, free: 9.645 MB / 494.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.002514/0.4628, allocations: 2.017 MB / 0.7024 GB, free: 9.309 MB / 494.1 MB Notification: Performance of NFPackage.collectConstants: time 0.0007838/0.4636, allocations: 350 kB / 0.7027 GB, free: 9.309 MB / 494.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.0009947/0.4646, allocations: 0.52 MB / 0.7032 GB, free: 9.309 MB / 494.1 MB Notification: Performance of NFScalarize.scalarize: time 0.000752/0.4653, allocations: 0.8933 MB / 0.7041 GB, free: 9.184 MB / 494.1 MB Notification: Performance of NFVerifyModel.verify: time 0.00185/0.4672, allocations: 1.533 MB / 0.7056 GB, free: 8.719 MB / 494.1 MB Notification: Performance of NFConvertDAE.convert: time 0.004779/0.4719, allocations: 4.161 MB / 0.7096 GB, free: 7.938 MB / 494.1 MB Notification: Performance of FrontEnd - DAE generated: time 5.24e-06/0.472, allocations: 1.469 kB / 0.7096 GB, free: 7.938 MB / 494.1 MB Notification: Performance of FrontEnd: time 1.352e-06/0.472, allocations: 0 / 0.7096 GB, free: 7.938 MB / 494.1 MB Notification: Performance of Transformations before backend: time 0.0001003/0.4721, allocations: 0 / 0.7096 GB, free: 7.938 MB / 494.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 385 * Number of variables: 385 Notification: Performance of Generate backend data structure: time 0.02087/0.4929, allocations: 5.72 MB / 0.7152 GB, free: 5.699 MB / 494.1 MB Notification: Performance of prepare preOptimizeDAE: time 4.307e-05/0.493, allocations: 9.031 kB / 0.7152 GB, free: 5.699 MB / 494.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005554/0.4985, allocations: 0.8989 MB / 0.7161 GB, free: 5.457 MB / 494.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.0061/0.5046, allocations: 2.451 MB / 0.7185 GB, free: 4.316 MB / 494.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005909/0.5052, allocations: 0.7397 MB / 0.7192 GB, free: 3.586 MB / 494.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004347/0.5057, allocations: 177.2 kB / 0.7194 GB, free: 3.523 MB / 494.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.004689/0.5103, allocations: 2.308 MB / 0.7216 GB, free: 2.641 MB / 494.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 5.028e-05/0.5104, allocations: 0 / 0.7216 GB, free: 2.641 MB / 494.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000265/0.5107, allocations: 61.28 kB / 0.7217 GB, free: 2.594 MB / 494.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.641e-05/0.5107, allocations: 36.81 kB / 0.7217 GB, free: 2.594 MB / 494.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005232/0.5159, allocations: 2.434 MB / 0.7241 GB, free: 1.477 MB / 494.1 MB 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.01775/0.5337, allocations: 7.82 MB / 0.7318 GB, free: 11.9 MB / 0.4981 GB Notification: Performance of preOpt comSubExp (simulation): time 0.01699/0.5507, allocations: 4.414 MB / 0.7361 GB, free: 9.48 MB / 0.4981 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.005116/0.5558, allocations: 1.302 MB / 0.7373 GB, free: 8.797 MB / 0.4981 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0003362/0.5561, allocations: 37.7 kB / 0.7374 GB, free: 8.789 MB / 0.4981 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.00609/0.5622, allocations: 1.66 MB / 0.739 GB, free: 7.809 MB / 0.4981 GB Notification: Performance of pre-optimization done (n=193): time 8.857e-06/0.5622, allocations: 0 / 0.739 GB, free: 7.809 MB / 0.4981 GB Notification: Performance of matching and sorting (n=193): time 0.05105/0.6133, allocations: 11.83 MB / 0.7505 GB, free: 15.17 MB / 0.5137 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001309/0.6134, allocations: 197.9 kB / 0.7507 GB, free: 14.93 MB / 0.5137 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008155/0.6216, allocations: 3.075 MB / 0.7537 GB, free: 11.85 MB / 0.5137 GB Notification: Performance of collectPreVariables (initialization): time 0.0007641/0.6223, allocations: 61.7 kB / 0.7538 GB, free: 11.79 MB / 0.5137 GB Notification: Performance of collectInitialEqns (initialization): time 0.001346/0.6237, allocations: 1.051 MB / 0.7548 GB, free: 10.73 MB / 0.5137 GB Notification: Performance of collectInitialBindings (initialization): time 0.001418/0.6251, allocations: 0.7285 MB / 0.7555 GB, free: 10.02 MB / 0.5137 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.002088/0.6272, allocations: 477.3 kB / 0.756 GB, free: 9.543 MB / 0.5137 GB Notification: Performance of setup shared object (initialization): time 0.0001279/0.6273, allocations: 301.1 kB / 0.7563 GB, free: 9.246 MB / 0.5137 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.003733/0.631, allocations: 1.492 MB / 0.7577 GB, free: 7.746 MB / 0.5137 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.004055/0.6351, allocations: 1.758 MB / 0.7594 GB, free: 5.926 MB / 0.5137 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.03532/0.6704, allocations: 9.136 MB / 0.7684 GB, free: 12.71 MB / 0.5294 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.182e-05/0.6704, allocations: 3.656 kB / 0.7684 GB, free: 12.71 MB / 0.5294 GB Notification: Performance of matching and sorting (n=332) (initialization): time 0.0121/0.6825, allocations: 4.444 MB / 0.7727 GB, free: 8.238 MB / 0.5294 GB Notification: Performance of prepare postOptimizeDAE: time 2.727e-05/0.6825, allocations: 12 kB / 0.7727 GB, free: 8.227 MB / 0.5294 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.655e-05/0.6826, allocations: 6.078 kB / 0.7727 GB, free: 8.223 MB / 0.5294 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.009329/0.6919, allocations: 2.417 MB / 0.7751 GB, free: 5.797 MB / 0.5294 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004891/0.6968, allocations: 0.8454 MB / 0.7759 GB, free: 4.98 MB / 0.5294 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01338/0.7102, allocations: 9.949 MB / 0.7856 GB, free: 10.37 MB / 0.545 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004957/0.7151, allocations: 0.5448 MB / 0.7862 GB, free: 9.824 MB / 0.545 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007046/0.7158, allocations: 91.97 kB / 0.7863 GB, free: 9.734 MB / 0.545 GB Warning: Assuming fixed start value for the following 6 variables: gENROE.Epd:VARIABLE(start = gENROE.Epd0 unit = "1" fixed = true ) "d-axis voltage behind transient reactance" type: Real pSS2A.rampTrackingFilter.TF2[1].x_scaled[1]:VARIABLE(fixed = true protected = true ) "Scaled vector x" type: Real [1,1] pSS2A.rampTrackingFilter.TF1[5].x_scaled[1]:VARIABLE(fixed = true protected = true ) "Scaled vector x" type: Real [5,1] pSS2A.rampTrackingFilter.TF1[4].x_scaled[1]:VARIABLE(fixed = true protected = true ) "Scaled vector x" type: Real [5,1] pSS2A.rampTrackingFilter.TF1[3].x_scaled[1]:VARIABLE(fixed = true protected = true ) "Scaled vector x" type: Real [5,1] pSS2A.rampTrackingFilter.TF1[2].x_scaled[1]:VARIABLE(fixed = true protected = true ) "Scaled vector x" type: Real [5,1] Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.005217/0.721, allocations: 1.918 MB / 0.7881 GB, free: 7.816 MB / 0.545 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.003952/0.725, allocations: 1.709 MB / 0.7898 GB, free: 6.047 MB / 0.545 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.03541/0.7604, allocations: 8.995 MB / 0.7986 GB, free: 12.96 MB / 0.5606 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 9.337e-06/0.7604, allocations: 4 kB / 0.7986 GB, free: 12.96 MB / 0.5606 GB Notification: Performance of matching and sorting (n=332) (initialization_lambda0): time 0.01174/0.7721, allocations: 4.347 MB / 0.8028 GB, free: 8.586 MB / 0.5606 GB Notification: Performance of prepare postOptimizeDAE: time 2.025e-05/0.7722, allocations: 10.97 kB / 0.8028 GB, free: 8.578 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.205e-05/0.7722, allocations: 8 kB / 0.8028 GB, free: 8.57 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.008995/0.7812, allocations: 2.418 MB / 0.8052 GB, free: 6.145 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003997/0.7852, allocations: 0.6551 MB / 0.8058 GB, free: 5.516 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.009783/0.7949, allocations: 6.835 MB / 0.8125 GB, free: 14.27 MB / 0.5762 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004615/0.7996, allocations: 0.5056 MB / 0.813 GB, free: 13.76 MB / 0.5762 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004901/0.8, allocations: 91.64 kB / 0.8131 GB, free: 13.68 MB / 0.5762 GB Warning: Assuming fixed start value for the following 6 variables: gENROE.Epd:VARIABLE(start = gENROE.Epd0 unit = "1" fixed = true ) "d-axis voltage behind transient reactance" type: Real pSS2A.rampTrackingFilter.TF2[1].x_scaled[1]:VARIABLE(fixed = true protected = true ) "Scaled vector x" type: Real [1,1] pSS2A.rampTrackingFilter.TF1[5].x_scaled[1]:VARIABLE(fixed = true protected = true ) "Scaled vector x" type: Real [5,1] pSS2A.rampTrackingFilter.TF1[4].x_scaled[1]:VARIABLE(fixed = true protected = true ) "Scaled vector x" type: Real [5,1] pSS2A.rampTrackingFilter.TF1[3].x_scaled[1]:VARIABLE(fixed = true protected = true ) "Scaled vector x" type: Real [5,1] pSS2A.rampTrackingFilter.TF1[2].x_scaled[1]:VARIABLE(fixed = true protected = true ) "Scaled vector x" type: Real [5,1] Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 15 * Number of states: 0 () * Number of discrete variables: 2 ($whenCondition2,$whenCondition1) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (279): * Single equations (assignments): 273 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 4 * Torn equation systems: 2 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details (not torn): * Constant Jacobian (size): 0 systems * Linear Jacobian (size,density): 0 systems * Non-linear Jacobian (size): 4 systems {1, 1, 1, 1} * Without analytic Jacobian (size): 0 systems 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 {(27,26)} Notification: Performance of prepare postOptimizeDAE: time 0.00086/0.8009, allocations: 314.4 kB / 0.8134 GB, free: 13.39 MB / 0.5762 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00102/0.8019, allocations: 290.1 kB / 0.8137 GB, free: 13.11 MB / 0.5762 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02037/0.8223, allocations: 6.193 MB / 0.8197 GB, free: 6.934 MB / 0.5762 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.192e-05/0.8224, allocations: 11.95 kB / 0.8197 GB, free: 6.922 MB / 0.5762 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5e-06/0.8224, allocations: 0 / 0.8197 GB, free: 6.922 MB / 0.5762 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001497/0.8239, allocations: 458.7 kB / 0.8202 GB, free: 6.453 MB / 0.5762 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001223/0.8251, allocations: 51.98 kB / 0.8202 GB, free: 6.402 MB / 0.5762 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003883/0.829, allocations: 395.4 kB / 0.8206 GB, free: 6.016 MB / 0.5762 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001887/0.8309, allocations: 0.6072 MB / 0.8212 GB, free: 5.414 MB / 0.5762 GB Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0133/0.8442, allocations: 4.428 MB / 0.8255 GB, free: 1.016 MB / 0.5762 GB Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.0105/0.8547, allocations: 4.997 MB / 0.8304 GB, free: 12 MB / 0.5919 GB Notification: Performance of postOpt setEvaluationStage (simulation): time 0.00293/0.8576, allocations: 1.312 MB / 0.8317 GB, free: 10.69 MB / 0.5919 GB Notification: Performance of sorting global known variables: time 0.005471/0.8631, allocations: 2.209 MB / 0.8338 GB, free: 8.477 MB / 0.5919 GB Notification: Performance of Backend: time 1.8e-07/0.8631, allocations: 0 / 0.8338 GB, free: 8.477 MB / 0.5919 GB Notification: Performance of simCode: created initialization part: time 0.02011/0.8832, allocations: 5.657 MB / 0.8394 GB, free: 2.871 MB / 0.5919 GB Notification: Performance of SimCode: time 0.01097/0.8942, allocations: 6.036 MB / 0.8453 GB, free: 12.8 MB / 0.6075 GB Notification: Performance of Templates: time 0.1099/1.004, allocations: 61.49 MB / 0.9053 GB, free: 15.51 MB / 0.67 GB [Timeout remaining time 659] make -j1 -f OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.PSS.PSS2A.makefile [Timeout 660] (rm -f OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.PSS.PSS2A.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.PSS.PSS2A.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.PSS.PSS2A.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.PSS.PSS2A.sim & ./OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.PSS.PSS2A -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.PSS.PSS2A.pipe 2>&1) [Timeout 480]