Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSAT.OEL.AVRTypeII_OEL_Test.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+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/ModelicaServices 3.2.3+maint.om/package.mo", uses=false)
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/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 Modelica_Synchronous with version 0.93.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo)
Using package ModelicaServices with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+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 Complex with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Running command: translateModel(OpenIPSL.Tests.Controls.PSAT.OEL.AVRTypeII_OEL_Test,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSAT.OEL.AVRTypeII_OEL_Test")
translateModel(OpenIPSL.Tests.Controls.PSAT.OEL.AVRTypeII_OEL_Test,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSAT.OEL.AVRTypeII_OEL_Test")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.001499/0.001499, allocations: 182.2 kB / 15.98 MB, free: 5.77 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.35/1.35, allocations: 205.1 MB / 221.8 MB, free: 8.477 MB / 186.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.00106/0.00106, allocations: 94.28 kB / 269.2 MB, free: 8.434 MB / 218.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo): time 0.07012/0.07012, allocations: 12.6 MB / 329.1 MB, free: 12.39 MB / 282.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo): time 0.2941/0.2941, allocations: 78.51 MB / 457.8 MB, free: 13.71 MB / 314.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.001776/0.001776, allocations: 111.1 kB / 0.5255 GB, free: 19.68 MB / 330.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1131/0.1149, allocations: 68.13 MB / 0.592 GB, free: 15.63 MB / 394.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSAT.OEL.AVRTypeII_OEL_Test): time 0.008292/0.1232, allocations: 7.383 MB / 0.5992 GB, free: 8.211 MB / 394.7 MB
Notification: Performance of NFInst.instExpressions: time 0.00541/0.1287, allocations: 2.149 MB / 0.6013 GB, free: 6.055 MB / 394.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001797/0.1306, allocations: 19.94 kB / 0.6013 GB, free: 6.035 MB / 394.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0008414/0.1314, allocations: 198.7 kB / 0.6015 GB, free: 5.84 MB / 394.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.001892/0.1334, allocations: 0.6685 MB / 0.6022 GB, free: 5.168 MB / 394.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Machines/PSAT/Order4.mo:25:3-30:9:writable] Warning: In relation generator.xq <> generator.x1q, <> on Real numbers is only allowed inside functions.
Notification: Performance of NFTyping.typeClassSections: time 0.001532/0.1349, allocations: 0.7045 MB / 0.6028 GB, free: 4.473 MB / 394.7 MB
Notification: Performance of NFFlatten.flatten: time 0.001596/0.1365, allocations: 1.655 MB / 0.6045 GB, free: 2.812 MB / 394.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.000467/0.137, allocations: 359 kB / 0.6048 GB, free: 2.438 MB / 394.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0006964/0.1377, allocations: 0.6338 MB / 0.6054 GB, free: 1.801 MB / 394.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0007847/0.1385, allocations: 0.797 MB / 0.6062 GB, free: 1 MB / 394.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001413/0.1386, allocations: 80 kB / 0.6063 GB, free: 0.9219 MB / 394.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0004138/0.1391, allocations: 211.8 kB / 0.6065 GB, free: 0.7148 MB / 394.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0002473/0.1393, allocations: 286.6 kB / 0.6067 GB, free: 444 kB / 394.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0005343/0.1399, allocations: 0.6677 MB / 0.6074 GB, free: 15.76 MB / 410.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.001383/0.1412, allocations: 1.74 MB / 0.6091 GB, free: 14.02 MB / 410.7 MB
Notification: Performance of FrontEnd - DAE generated: time 3.176e-06/0.1413, allocations: 0 / 0.6091 GB, free: 14.02 MB / 410.7 MB
Notification: Performance of FrontEnd: time 1.953e-06/0.1413, allocations: 4 kB / 0.6091 GB, free: 14.01 MB / 410.7 MB
Notification: Performance of Transformations before backend: time 1.286e-05/0.1413, allocations: 0 / 0.6091 GB, free: 14.01 MB / 410.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 219
 * Number of variables: 219
Notification: Performance of Generate backend data structure: time 0.008636/0.1499, allocations: 2.848 MB / 0.6119 GB, free: 11.1 MB / 410.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.947e-05/0.15, allocations: 11.98 kB / 0.6119 GB, free: 11.09 MB / 410.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004642/0.1546, allocations: 0.7982 MB / 0.6127 GB, free: 10.29 MB / 410.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00146/0.1561, allocations: 0.9139 MB / 0.6136 GB, free: 9.316 MB / 410.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003396/0.1565, allocations: 327.6 kB / 0.6139 GB, free: 8.945 MB / 410.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001675/0.1566, allocations: 95.84 kB / 0.614 GB, free: 8.852 MB / 410.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002505/0.1591, allocations: 1.299 MB / 0.6152 GB, free: 7.535 MB / 410.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.992e-05/0.1592, allocations: 4 kB / 0.6152 GB, free: 7.531 MB / 410.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001019/0.1593, allocations: 36 kB / 0.6153 GB, free: 7.496 MB / 410.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.433e-05/0.1593, allocations: 20 kB / 0.6153 GB, free: 7.477 MB / 410.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002629/0.162, allocations: 1.202 MB / 0.6165 GB, free: 6.273 MB / 410.7 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.006568/0.1685, allocations: 4.176 MB / 0.6205 GB, free: 1.938 MB / 410.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.003268/0.1718, allocations: 1.772 MB / 0.6223 GB, free: 40 kB / 410.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001687/0.1735, allocations: 0.8562 MB / 0.6231 GB, free: 15.18 MB / 426.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001405/0.1737, allocations: 20.97 kB / 0.6231 GB, free: 15.16 MB / 426.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001944/0.1756, allocations: 0.9659 MB / 0.6241 GB, free: 14.17 MB / 426.7 MB
Notification: Performance of pre-optimization done (n=108): time 3.336e-06/0.1756, allocations: 0 / 0.6241 GB, free: 14.17 MB / 426.7 MB
Notification: Performance of matching and sorting (n=108): time 0.03345/0.2091, allocations: 7.886 MB / 0.6318 GB, free: 6.219 MB / 426.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.256e-05/0.2092, allocations: 115.8 kB / 0.6319 GB, free: 6.082 MB / 426.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001522/0.2107, allocations: 1.049 MB / 0.6329 GB, free: 5.02 MB / 426.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002258/0.211, allocations: 41.7 kB / 0.633 GB, free: 4.973 MB / 426.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0003637/0.2113, allocations: 0.5246 MB / 0.6335 GB, free: 4.441 MB / 426.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0003733/0.2117, allocations: 390.5 kB / 0.6338 GB, free: 4.055 MB / 426.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004475/0.2122, allocations: 247.4 kB / 0.6341 GB, free: 3.809 MB / 426.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001233/0.2123, allocations: 353.9 kB / 0.6344 GB, free: 3.457 MB / 426.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001699/0.214, allocations: 0.7847 MB / 0.6352 GB, free: 2.672 MB / 426.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001777/0.2158, allocations: 0.9483 MB / 0.6361 GB, free: 1.688 MB / 426.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.2354/0.4513, allocations: 5.483 MB / 0.6415 GB, free: 49.88 MB / 426.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.227e-05/0.4513, allocations: 9.031 kB / 0.6415 GB, free: 49.88 MB / 426.7 MB
Notification: Performance of matching and sorting (n=175) (initialization): time 0.01609/0.4674, allocations: 4.453 MB / 0.6458 GB, free: 48.84 MB / 426.7 MB
Notification: Performance of prepare postOptimizeDAE: time 5.953e-05/0.4675, allocations: 60.05 kB / 0.6459 GB, free: 48.78 MB / 426.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.836e-05/0.4676, allocations: 3.484 kB / 0.6459 GB, free: 48.78 MB / 426.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.00669/0.4743, allocations: 1.742 MB / 0.6476 GB, free: 48.47 MB / 426.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001593/0.4759, allocations: 280.5 kB / 0.6478 GB, free: 48.45 MB / 426.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0202/0.4961, allocations: 8.322 MB / 0.656 GB, free: 44.66 MB / 426.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001875/0.498, allocations: 204.4 kB / 0.6562 GB, free: 44.63 MB / 426.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002593/0.4983, allocations: 50.06 kB / 0.6562 GB, free: 44.62 MB / 426.7 MB
Warning: Assuming fixed start value for the following 3 variables:
         generator.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         generator.delta:VARIABLE(flow=false start = generator.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         generator.e1q:VARIABLE(start = generator.e1q0 unit = "1" fixed = true )  "q-axis transient voltage" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 12
 * Number of states: 0 ()
 * Number of discrete variables: 3 ($whenCondition2,$whenCondition1,oXL.limIntegrator.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (141):
 * Single equations (assignments): 138
 * Array equations: 0
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * 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): 1 system
   {(24,11)}
Notification: Performance of prepare postOptimizeDAE: time 0.0007909/0.4991, allocations: 314 kB / 0.6565 GB, free: 44.37 MB / 426.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003848/0.4995, allocations: 129.7 kB / 0.6566 GB, free: 44.3 MB / 426.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01501/0.5145, allocations: 4.399 MB / 0.6609 GB, free: 42.47 MB / 426.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.943e-05/0.5146, allocations: 11.84 kB / 0.6609 GB, free: 42.47 MB / 426.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.769e-06/0.5146, allocations: 0 / 0.6609 GB, free: 42.47 MB / 426.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.841e-05/0.5146, allocations: 11.84 kB / 0.661 GB, free: 42.46 MB / 426.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01753/0.5322, allocations: 6.263 MB / 0.6671 GB, free: 38.66 MB / 426.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.714e-05/0.5322, allocations: 0 / 0.6671 GB, free: 38.66 MB / 426.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0007944/0.533, allocations: 118.3 kB / 0.6672 GB, free: 38.64 MB / 426.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.006408/0.5395, allocations: 1.919 MB / 0.6691 GB, free: 37.08 MB / 426.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.000318/0.5398, allocations: 24 kB / 0.6691 GB, free: 37.05 MB / 426.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02091/0.5608, allocations: 9.75 MB / 0.6786 GB, free: 27.05 MB / 426.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.521e-06/0.5608, allocations: 7.938 kB / 0.6786 GB, free: 27.05 MB / 426.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.112/0.6728, allocations: 38.86 MB / 0.7166 GB, free: 3.742 MB / 442.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004149/0.6733, allocations: 219.3 kB / 0.7168 GB, free: 3.508 MB / 442.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003952/0.6737, allocations: 20 kB / 0.7168 GB, free: 3.488 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00159/0.6753, allocations: 135.9 kB / 0.7169 GB, free: 3.355 MB / 442.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008898/0.6762, allocations: 239.2 kB / 0.7171 GB, free: 3.121 MB / 442.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001785/0.6764, allocations: 31.98 kB / 0.7172 GB, free: 3.09 MB / 442.7 MB
Notification: Performance of sorting global known variables: time 0.001269/0.6777, allocations: 0.7006 MB / 0.7179 GB, free: 2.383 MB / 442.7 MB
Notification: Performance of sort global known variables: time 1.8e-07/0.6777, allocations: 4 kB / 0.7179 GB, free: 2.379 MB / 442.7 MB
Notification: Performance of remove unused functions: time 0.00268/0.6804, allocations: 0.6829 MB / 0.7185 GB, free: 1.695 MB / 442.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 9 (generator.delta,generator.w,generator.e1q,generator.e1d,exciter_Type_II.ExcitationSystem.x_scaled[1],exciter_Type_II.derivativeBlock.x,exciter_Type_II.firstOrder2.y,exciter_Type_II.simpleLagLim.state,oXL.limIntegrator.y)
 * Number of discrete variables: 2 ($whenCondition1,$whenCondition2)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (67):
 * Single equations (assignments): 64
 * 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): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(26,13), (1,6)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0003535/0.6807, allocations: 141.3 kB / 0.7187 GB, free: 1.551 MB / 442.7 MB
Notification: Performance of simCode: created initialization part: time 0.007483/0.6882, allocations: 2.686 MB / 0.7213 GB, free: 14.82 MB / 458.7 MB
Notification: Performance of simCode: created event and clocks part: time 4.418e-06/0.6883, allocations: 0 / 0.7213 GB, free: 14.82 MB / 458.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.005442/0.6937, allocations: 1.829 MB / 0.7231 GB, free: 12.96 MB / 458.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001229/0.695, allocations: 121.4 kB / 0.7232 GB, free: 12.84 MB / 458.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.0103/0.7053, allocations: 4.6 MB / 0.7277 GB, free: 8.168 MB / 458.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0009328/0.7063, allocations: 1.212 MB / 0.7289 GB, free: 6.918 MB / 458.7 MB
Notification: Performance of simCode: alias equations: time 0.0006565/0.7069, allocations: 128.3 kB / 0.729 GB, free: 6.789 MB / 458.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003952/0.7073, allocations: 79.41 kB / 0.7291 GB, free: 6.711 MB / 458.7 MB
Notification: Performance of SimCode: time 8.52e-07/0.7074, allocations: 0 / 0.7291 GB, free: 6.711 MB / 458.7 MB
Notification: Performance of Templates: time 0.05769/0.7651, allocations: 37.17 MB / 0.7654 GB, free: 1.59 MB / 490.7 MB
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSAT.OEL.AVRTypeII_OEL_Test.makefile
(rm -f OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSAT.OEL.AVRTypeII_OEL_Test.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSAT.OEL.AVRTypeII_OEL_Test.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSAT.OEL.AVRTypeII_OEL_Test.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSAT.OEL.AVRTypeII_OEL_Test.sim & ./OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSAT.OEL.AVRTypeII_OEL_Test  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSAT.OEL.AVRTypeII_OEL_Test.pipe 2>&1)