Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.ESAC1A.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo", uses=false)
Using package OpenIPSL with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/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 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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)
Using package Modelica_Synchronous with version 0.93.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo)
Running command: translateModel(OpenIPSL.Tests.Controls.PSSE.ES.ESAC1A,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.ESAC1A")
translateModel(OpenIPSL.Tests.Controls.PSSE.ES.ESAC1A,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.ESAC1A")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo): time 0.08998/0.08998, allocations: 12.6 MB / 28.91 MB, free: 0.6172 MB / 19.63 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001251/0.001251, allocations: 103.5 kB / 32.5 MB, free: 3.562 MB / 26.18 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.392/1.392, allocations: 205.1 MB / 241.1 MB, free: 12.36 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001749/0.001749, allocations: 195.5 kB / 291.4 MB, free: 10.93 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo): time 0.498/0.498, allocations: 78.51 MB / 420 MB, free: 10.77 MB / 302.1 MB
Notification: Modelica_Synchronous requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: OpenIPSL requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.002639/0.002639, allocations: 143.8 kB / 0.5566 GB, free: 17.25 MB / 382.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1505/0.1531, allocations: 68.12 MB / 0.6231 GB, free: 13.11 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSSE.ES.ESAC1A): time 0.01033/0.1635, allocations: 9.146 MB / 0.632 GB, free: 3.914 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.007404/0.171, allocations: 2.943 MB / 0.6349 GB, free: 0.9609 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001736/0.1728, allocations: 16 kB / 0.6349 GB, free: 0.9453 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001247/0.1741, allocations: 349 kB / 0.6352 GB, free: 0.6016 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003911/0.178, allocations: 1.313 MB / 0.6365 GB, free: 15.29 MB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002328/0.1803, allocations: 0.9375 MB / 0.6374 GB, free: 14.36 MB / 462.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0041/0.1844, allocations: 2.685 MB / 0.6401 GB, free: 11.67 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008884/0.1854, allocations: 0.5455 MB / 0.6406 GB, free: 11.09 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001676/0.187, allocations: 1.016 MB / 0.6416 GB, free: 10.07 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001669/0.1887, allocations: 1.26 MB / 0.6428 GB, free: 8.805 MB / 462.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004008/0.1891, allocations: 220 kB / 0.643 GB, free: 8.59 MB / 462.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0009961/0.1901, allocations: 495.6 kB / 0.6435 GB, free: 8.105 MB / 462.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.000474/0.1906, allocations: 0.5215 MB / 0.644 GB, free: 7.582 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001029/0.1917, allocations: 1.041 MB / 0.645 GB, free: 6.535 MB / 462.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.003283/0.195, allocations: 2.747 MB / 0.6477 GB, free: 3.777 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.46e-06/0.195, allocations: 0 / 0.6477 GB, free: 3.777 MB / 462.1 MB
Notification: Performance of FrontEnd: time 1.723e-06/0.195, allocations: 0 / 0.6477 GB, free: 3.777 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 4.565e-05/0.1951, allocations: 0 / 0.6477 GB, free: 3.777 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 283
 * Number of variables: 283
Notification: Performance of Generate backend data structure: time 0.01557/0.2106, allocations: 4.213 MB / 0.6518 GB, free: 15.5 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.865e-05/0.2107, allocations: 11.98 kB / 0.6518 GB, free: 15.48 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005049/0.2157, allocations: 0.787 MB / 0.6526 GB, free: 14.7 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003008/0.2188, allocations: 1.28 MB / 0.6539 GB, free: 13.37 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006023/0.2194, allocations: 0.4925 MB / 0.6543 GB, free: 12.79 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000479/0.2199, allocations: 128 kB / 0.6545 GB, free: 12.67 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004099/0.224, allocations: 1.636 MB / 0.6561 GB, free: 10.98 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.082e-05/0.2241, allocations: 0 / 0.6561 GB, free: 10.98 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001741/0.2243, allocations: 44 kB / 0.6561 GB, free: 10.94 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.801e-05/0.2243, allocations: 28 kB / 0.6561 GB, free: 10.91 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003812/0.2281, allocations: 1.515 MB / 0.6576 GB, free: 9.391 MB / 478.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.01228/0.2404, allocations: 5.589 MB / 0.6631 GB, free: 3.578 MB / 478.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.009331/0.2498, allocations: 3.182 MB / 0.6662 GB, free: 260 kB / 478.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002237/0.2521, allocations: 1.006 MB / 0.6671 GB, free: 15.24 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0006833/0.2528, allocations: 0.7375 MB / 0.6679 GB, free: 14.34 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002537/0.2553, allocations: 1.179 MB / 0.669 GB, free: 13.14 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=139): time 4.558e-06/0.2554, allocations: 3.938 kB / 0.669 GB, free: 13.13 MB / 494.1 MB
Notification: Performance of matching and sorting (n=139): time 0.04107/0.2964, allocations: 9.964 MB / 0.6788 GB, free: 3.133 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.228e-05/0.2966, allocations: 145.9 kB / 0.6789 GB, free: 2.957 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002929/0.2995, allocations: 1.565 MB / 0.6804 GB, free: 1.387 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0003869/0.2999, allocations: 61.69 kB / 0.6805 GB, free: 1.32 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0006388/0.3006, allocations: 0.6801 MB / 0.6811 GB, free: 0.6289 MB / 494.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0007848/0.3014, allocations: 0.5191 MB / 0.6817 GB, free: 108 kB / 494.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.329/0.6304, allocations: 378 kB / 0.682 GB, free: 52.95 MB / 494.1 MB
Notification: Performance of setup shared object (initialization): time 6.533e-05/0.6305, allocations: 305.6 kB / 0.6823 GB, free: 52.65 MB / 494.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002722/0.6332, allocations: 1.091 MB / 0.6834 GB, free: 52.62 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002848/0.6361, allocations: 1.317 MB / 0.6847 GB, free: 52.45 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.005314/0.6414, allocations: 2.297 MB / 0.6869 GB, free: 51.82 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.164e-05/0.6415, allocations: 0.8125 kB / 0.6869 GB, free: 51.82 MB / 494.1 MB
Notification: Performance of matching and sorting (n=265) (initialization): time 0.01159/0.6531, allocations: 4.139 MB / 0.6909 GB, free: 50.55 MB / 494.1 MB
Notification: Performance of prepare postOptimizeDAE: time 4.039e-05/0.6531, allocations: 6.469 kB / 0.6909 GB, free: 50.55 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.991e-05/0.6532, allocations: 4.156 kB / 0.6909 GB, free: 50.55 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01079/0.664, allocations: 2.789 MB / 0.6937 GB, free: 50.17 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001916/0.6659, allocations: 351.2 kB / 0.694 GB, free: 50.14 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006937/0.6729, allocations: 3.368 MB / 0.6973 GB, free: 48.3 MB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002959/0.6758, allocations: 295.3 kB / 0.6976 GB, free: 48.27 MB / 494.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002805/0.6761, allocations: 71.42 kB / 0.6976 GB, free: 48.25 MB / 494.1 MB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.003192/0.6793, allocations: 1.316 MB / 0.6989 GB, free: 47.67 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.003092/0.6824, allocations: 1.307 MB / 0.7002 GB, free: 47.06 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.005253/0.6877, allocations: 2.267 MB / 0.7024 GB, free: 46 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.213e-05/0.6878, allocations: 1.375 kB / 0.7024 GB, free: 46 MB / 494.1 MB
Notification: Performance of matching and sorting (n=265) (initialization_lambda0): time 0.01053/0.6983, allocations: 4.119 MB / 0.7064 GB, free: 44.66 MB / 494.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.186e-05/0.6984, allocations: 7.812 kB / 0.7065 GB, free: 44.66 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.048e-05/0.6984, allocations: 3.938 kB / 0.7065 GB, free: 44.66 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009176/0.7075, allocations: 2.793 MB / 0.7092 GB, free: 44.2 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001695/0.7093, allocations: 352.3 kB / 0.7095 GB, free: 44.16 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.005939/0.7152, allocations: 3.36 MB / 0.7128 GB, free: 42.31 MB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002521/0.7178, allocations: 264.6 kB / 0.7131 GB, free: 42.23 MB / 494.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001847/0.718, allocations: 70.38 kB / 0.7131 GB, free: 42.22 MB / 494.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 17
 * Number of states: 0 ()
 * Number of discrete variables: 3 ($whenCondition2,$whenCondition1,eSAC1A.rotatingExciterWithDemagnetization.sISO.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (200):
 * Single equations (assignments): 198
 * Array equations: 0
 * Algorithm blocks: 0
 * 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): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(30,26), (1,10)}
Notification: Performance of prepare postOptimizeDAE: time 0.000479/0.7185, allocations: 269.7 kB / 0.7134 GB, free: 41.97 MB / 494.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002856/0.7188, allocations: 189 kB / 0.7136 GB, free: 41.87 MB / 494.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01419/0.733, allocations: 5.064 MB / 0.7185 GB, free: 37.23 MB / 494.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.516e-05/0.733, allocations: 11.97 kB / 0.7185 GB, free: 37.21 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.328e-06/0.733, allocations: 0 / 0.7185 GB, free: 37.21 MB / 494.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005317/0.7336, allocations: 302.2 kB / 0.7188 GB, free: 36.92 MB / 494.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002837/0.7338, allocations: 32 kB / 0.7188 GB, free: 36.89 MB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001556/0.7354, allocations: 155.8 kB / 0.719 GB, free: 36.73 MB / 494.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001344/0.7368, allocations: 0.5668 MB / 0.7195 GB, free: 36.16 MB / 494.1 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.007389/0.7442, allocations: 3.068 MB / 0.7225 GB, free: 33.13 MB / 494.1 MB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.005567/0.7498, allocations: 3.269 MB / 0.7257 GB, free: 29.84 MB / 494.1 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001648/0.7514, allocations: 0.8652 MB / 0.7266 GB, free: 28.97 MB / 494.1 MB
Notification: Performance of sorting global known variables: time 0.001395/0.7528, allocations: 1.065 MB / 0.7276 GB, free: 27.91 MB / 494.1 MB
Notification: Performance of Backend: time 6.31e-07/0.7528, allocations: 0 / 0.7276 GB, free: 27.91 MB / 494.1 MB
Notification: Performance of simCode: created initialization part: time 0.01396/0.7668, allocations: 4.648 MB / 0.7321 GB, free: 23.3 MB / 494.1 MB
Notification: Performance of SimCode: time 0.004775/0.7716, allocations: 4.037 MB / 0.7361 GB, free: 19.26 MB / 494.1 MB
Notification: Performance of Templates: time 0.06057/0.8322, allocations: 43.44 MB / 0.7785 GB, free: 7.855 MB / 0.5137 GB
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.ESAC1A.makefile
(rm -f OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.ESAC1A.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.ESAC1A.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.ESAC1A.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.ESAC1A.sim & ./OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.ESAC1A  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.ESAC1A.pipe 2>&1)