Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order4test2.conf.json
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 4.0.0+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 3.0.1/package.mo", uses=false)
Using package OpenIPSL with version 3.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/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.Machines.PSAT.Order4test2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order4test2")
translateModel(OpenIPSL.Tests.Machines.PSAT.Order4test2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order4test2")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001282/0.001282, allocations: 107.2 kB / 16.41 MB, free: 6.492 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.488/1.488, allocations: 222.9 MB / 240.1 MB, free: 4.383 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001581/0.001581, allocations: 192.5 kB / 290.4 MB, free: 2.113 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo): time 0.4603/0.4603, allocations: 78.52 MB / 419.1 MB, free: 3.18 MB / 286.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001465/0.001465, allocations: 83.88 kB / 0.5543 GB, free: 0.9648 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3167/0.3182, allocations: 68.17 MB / 0.6209 GB, free: 9.648 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Machines.PSAT.Order4test2): time 0.004625/0.3228, allocations: 5.794 MB / 0.6265 GB, free: 4.414 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.002614/0.3255, allocations: 1.735 MB / 0.6282 GB, free: 2.676 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0003849/0.3259, allocations: 12 kB / 0.6282 GB, free: 2.664 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0003671/0.3262, allocations: 206.2 kB / 0.6284 GB, free: 2.461 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.001224/0.3275, allocations: 0.6844 MB / 0.6291 GB, free: 1.777 MB / 430.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/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.0009685/0.3285, allocations: 0.5687 MB / 0.6296 GB, free: 1.215 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0009326/0.3294, allocations: 1.257 MB / 0.6309 GB, free: 15.95 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0004117/0.3298, allocations: 279 kB / 0.6311 GB, free: 15.67 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0005882/0.3304, allocations: 0.5251 MB / 0.6316 GB, free: 15.14 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0006565/0.3311, allocations: 0.6417 MB / 0.6323 GB, free: 14.5 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.000137/0.3313, allocations: 91.98 kB / 0.6324 GB, free: 14.41 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0004161/0.3317, allocations: 223.9 kB / 0.6326 GB, free: 14.19 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0001882/0.3319, allocations: 238.9 kB / 0.6328 GB, free: 13.95 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.000439/0.3323, allocations: 0.5515 MB / 0.6333 GB, free: 13.4 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.001297/0.3336, allocations: 1.352 MB / 0.6347 GB, free: 12.05 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.743e-06/0.3336, allocations: 4 kB / 0.6347 GB, free: 12.04 MB / 446.1 MB
Notification: Performance of FrontEnd: time 1.593e-06/0.3337, allocations: 0 / 0.6347 GB, free: 12.04 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 1.343e-05/0.3337, allocations: 0 / 0.6347 GB, free: 12.04 MB / 446.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 168
 * Number of variables: 168
Notification: Performance of Generate backend data structure: time 0.00752/0.3412, allocations: 2.341 MB / 0.637 GB, free: 9.648 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.143e-05/0.3413, allocations: 16.02 kB / 0.637 GB, free: 9.633 MB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005155/0.3464, allocations: 0.7556 MB / 0.6377 GB, free: 8.875 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001241/0.3477, allocations: 0.7127 MB / 0.6384 GB, free: 8.113 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002784/0.348, allocations: 248.2 kB / 0.6386 GB, free: 7.836 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001499/0.3481, allocations: 75.91 kB / 0.6387 GB, free: 7.762 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002097/0.3502, allocations: 0.9521 MB / 0.6396 GB, free: 6.805 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.983e-05/0.3503, allocations: 7.922 kB / 0.6396 GB, free: 6.797 MB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.895e-05/0.3504, allocations: 28 kB / 0.6397 GB, free: 6.77 MB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.496e-05/0.3504, allocations: 16 kB / 0.6397 GB, free: 6.754 MB / 446.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00211/0.3525, allocations: 0.9837 MB / 0.6406 GB, free: 5.766 MB / 446.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.005292/0.3578, allocations: 3.155 MB / 0.6437 GB, free: 2.461 MB / 446.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002046/0.3599, allocations: 1.306 MB / 0.645 GB, free: 1.031 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001403/0.3613, allocations: 0.707 MB / 0.6457 GB, free: 332 kB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 8.565e-05/0.3614, allocations: 17 kB / 0.6457 GB, free: 316 kB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.434e-05/0.3614, allocations: 39.56 kB / 0.6457 GB, free: 268 kB / 446.1 MB
Notification: Performance of pre-optimization done (n=83): time 2.846e-06/0.3614, allocations: 0 / 0.6457 GB, free: 268 kB / 446.1 MB
Notification: Performance of matching and sorting (n=83): time 0.02673/0.3882, allocations: 6.477 MB / 0.6521 GB, free: 9.746 MB / 462.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.706e-05/0.3883, allocations: 60.41 kB / 0.6521 GB, free: 9.676 MB / 462.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001045/0.3893, allocations: 0.7945 MB / 0.6529 GB, free: 8.875 MB / 462.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.000206/0.3895, allocations: 37.7 kB / 0.6529 GB, free: 8.832 MB / 462.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0003005/0.3898, allocations: 436.4 kB / 0.6534 GB, free: 8.398 MB / 462.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0003262/0.3902, allocations: 307.6 kB / 0.6537 GB, free: 8.094 MB / 462.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003533/0.3905, allocations: 190.8 kB / 0.6538 GB, free: 7.898 MB / 462.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001241/0.3907, allocations: 349.9 kB / 0.6542 GB, free: 7.551 MB / 462.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001458/0.3921, allocations: 0.6584 MB / 0.6548 GB, free: 6.895 MB / 462.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001583/0.3937, allocations: 0.7835 MB / 0.6556 GB, free: 6.078 MB / 462.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01861/0.4123, allocations: 4.52 MB / 0.66 GB, free: 1.523 MB / 462.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.281e-05/0.4124, allocations: 4 kB / 0.66 GB, free: 1.52 MB / 462.1 MB
Notification: Performance of matching and sorting (n=135) (initialization): time 0.01427/0.4267, allocations: 4.074 MB / 0.664 GB, free: 13.43 MB / 478.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.783e-05/0.4267, allocations: 4 kB / 0.664 GB, free: 13.43 MB / 478.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.381e-05/0.4268, allocations: 4 kB / 0.664 GB, free: 13.42 MB / 478.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006515/0.4333, allocations: 1.737 MB / 0.6657 GB, free: 11.68 MB / 478.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001005/0.4343, allocations: 183.7 kB / 0.6659 GB, free: 11.5 MB / 478.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0223/0.4566, allocations: 9.058 MB / 0.6747 GB, free: 2.293 MB / 478.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001417/0.4581, allocations: 151 kB / 0.6748 GB, free: 2.145 MB / 478.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001802/0.4583, allocations: 43.98 kB / 0.6749 GB, free: 2.102 MB / 478.1 MB
Warning: Assuming fixed start value for the following 1 variables:
         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: 10
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (101):
 * Single equations (assignments): 100
 * Array equations: 0
 * Algorithm blocks: 0
 * 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.000545/0.4589, allocations: 225.7 kB / 0.6751 GB, free: 1.871 MB / 478.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00036/0.4592, allocations: 102.7 kB / 0.6752 GB, free: 1.77 MB / 478.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01456/0.4738, allocations: 3.809 MB / 0.6789 GB, free: 13.94 MB / 494.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.746e-05/0.4739, allocations: 11.94 kB / 0.6789 GB, free: 13.93 MB / 494.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.017e-06/0.4739, allocations: 4 kB / 0.6789 GB, free: 13.93 MB / 494.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.243e-05/0.4739, allocations: 3.984 kB / 0.6789 GB, free: 13.92 MB / 494.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01595/0.4899, allocations: 5.376 MB / 0.6842 GB, free: 8.41 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.393e-05/0.4899, allocations: 0 / 0.6842 GB, free: 8.41 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0005976/0.4905, allocations: 91.89 kB / 0.6843 GB, free: 8.32 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.006097/0.4966, allocations: 1.741 MB / 0.686 GB, free: 6.574 MB / 494.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001669/0.4968, allocations: 16 kB / 0.686 GB, free: 6.559 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01943/0.5163, allocations: 8.048 MB / 0.6939 GB, free: 14.36 MB / 0.4981 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.755e-06/0.5163, allocations: 0 / 0.6939 GB, free: 14.36 MB / 0.4981 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01516/0.5315, allocations: 4.74 MB / 0.6985 GB, free: 9.594 MB / 0.4981 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003259/0.5319, allocations: 191.6 kB / 0.6987 GB, free: 9.387 MB / 0.4981 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002397/0.5321, allocations: 12 kB / 0.6987 GB, free: 9.375 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001025/0.5332, allocations: 91.89 kB / 0.6988 GB, free: 9.285 MB / 0.4981 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006161/0.5338, allocations: 200.7 kB / 0.699 GB, free: 9.09 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.464e-05/0.5339, allocations: 27.94 kB / 0.699 GB, free: 9.062 MB / 0.4981 GB
Notification: Performance of sorting global known variables: time 0.0007298/0.5346, allocations: 0.5341 MB / 0.6995 GB, free: 8.531 MB / 0.4981 GB
Notification: Performance of sort global known variables: time 1.6e-07/0.5346, allocations: 3.938 kB / 0.6995 GB, free: 8.527 MB / 0.4981 GB
Notification: Performance of remove unused functions: time 0.001205/0.5358, allocations: 407.5 kB / 0.6999 GB, free: 8.129 MB / 0.4981 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 4 (Generator.delta,Generator.w,Generator.e1q,Generator.e1d)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (50):
 * Single equations (assignments): 49
 * Array equations: 0
 * Algorithm blocks: 0
 * 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
   {(26,10)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0002661/0.5361, allocations: 123.2 kB / 0.7 GB, free: 8 MB / 0.4981 GB
Notification: Performance of simCode: created initialization part: time 0.006571/0.5427, allocations: 2.316 MB / 0.7023 GB, free: 5.672 MB / 0.4981 GB
Notification: Performance of simCode: created event and clocks part: time 3.877e-06/0.5427, allocations: 0 / 0.7023 GB, free: 5.672 MB / 0.4981 GB
Notification: Performance of simCode: created simulation system equations: time 0.004856/0.5476, allocations: 1.504 MB / 0.7037 GB, free: 4.148 MB / 0.4981 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0008224/0.5484, allocations: 79.28 kB / 0.7038 GB, free: 4.07 MB / 0.4981 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003578/0.552, allocations: 1.812 MB / 0.7056 GB, free: 2.23 MB / 0.4981 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0006518/0.5527, allocations: 1.025 MB / 0.7066 GB, free: 1.18 MB / 0.4981 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002797/0.553, allocations: 67.42 kB / 0.7067 GB, free: 1.113 MB / 0.4981 GB
Notification: Performance of SimCode: time 8.01e-07/0.553, allocations: 0 / 0.7067 GB, free: 1.113 MB / 0.4981 GB
Notification: Performance of Templates: time 0.02529/0.5783, allocations: 17.9 MB / 0.7241 GB, free: 15.19 MB / 0.5294 GB
make -j1 -f OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order4test2.makefile
(rm -f OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order4test2.pipe ; mkfifo OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order4test2.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order4test2.pipe >> ../files/OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order4test2.sim & ./OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order4test2  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order4test2.pipe 2>&1)