Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Examples.Tutorial.Example_3.Example_3.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.Examples.Tutorial.Example_3.Example_3,tolerance=1e-06,outputFormat="empty",numberOfIntervals=40000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.Tutorial.Example_3.Example_3")
translateModel(OpenIPSL.Examples.Tutorial.Example_3.Example_3,tolerance=1e-06,outputFormat="empty",numberOfIntervals=40000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.Tutorial.Example_3.Example_3")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo): time 0.08313/0.08313, allocations: 12.6 MB / 28.91 MB, free: 0.6289 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.001076/0.001076, allocations: 107.5 kB / 32.51 MB, free: 3.57 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.439/1.439, 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.001268/0.001268, allocations: 200.4 kB / 291.4 MB, free: 13.37 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo): time 0.4442/0.4442, allocations: 78.51 MB / 420 MB, free: 11.25 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.002728/0.002729, allocations: 138.5 kB / 0.5565 GB, free: 28.43 MB / 398.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1225/0.1253, allocations: 68.13 MB / 0.6231 GB, free: 15.42 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.Tutorial.Example_3.Example_3): time 0.01341/0.1387, allocations: 13.42 MB / 0.6362 GB, free: 1.945 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008699/0.1475, allocations: 3.596 MB / 0.6397 GB, free: 14.34 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003095/0.1506, allocations: 27.94 kB / 0.6397 GB, free: 14.31 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002079/0.1528, allocations: 0.5031 MB / 0.6402 GB, free: 13.8 MB / 462.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation twoWindingTransformer.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation twoWindingTransformer1.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation twoWindingTransformer2.m <> 1.0, <> on Real numbers is only allowed inside functions.
Notification: Performance of NFTyping.typeBindings: time 0.005536/0.1583, allocations: 1.569 MB / 0.6417 GB, free: 12.24 MB / 462.1 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 gen1.gen.xq <> gen1.gen.x1q, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Machines/PSAT/Order4.mo:25:3-30:9:writable] Warning: In relation gen2.gen.xq <> gen2.gen.x1q, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Machines/PSAT/Order4.mo:25:3-30:9:writable] Warning: In relation gen3.gen.xq <> gen3.gen.x1q, <> on Real numbers is only allowed inside functions.
Notification: Performance of NFTyping.typeClassSections: time 0.003215/0.1616, allocations: 1.329 MB / 0.643 GB, free: 10.92 MB / 462.1 MB
Notification: Performance of NFFlatten.flatten: time 0.00586/0.1674, allocations: 4.104 MB / 0.647 GB, free: 6.797 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001745/0.1692, allocations: 0.9369 MB / 0.6479 GB, free: 5.805 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002568/0.1718, allocations: 1.599 MB / 0.6495 GB, free: 4.199 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003037/0.1748, allocations: 1.929 MB / 0.6514 GB, free: 2.262 MB / 462.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001152/0.176, allocations: 364 kB / 0.6517 GB, free: 1.906 MB / 462.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001638/0.1777, allocations: 0.5077 MB / 0.6522 GB, free: 1.398 MB / 462.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001214/0.1789, allocations: 0.821 MB / 0.653 GB, free: 0.5742 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002215/0.1811, allocations: 1.702 MB / 0.6547 GB, free: 14.86 MB / 478.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.00517/0.1863, allocations: 4.552 MB / 0.6591 GB, free: 10.29 MB / 478.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.171e-06/0.1863, allocations: 4 kB / 0.6592 GB, free: 10.29 MB / 478.1 MB
Notification: Performance of FrontEnd: time 1.873e-06/0.1863, allocations: 0 / 0.6592 GB, free: 10.29 MB / 478.1 MB
Notification: Performance of Transformations before backend: time 0.0001077/0.1865, allocations: 0 / 0.6592 GB, free: 10.29 MB / 478.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 463
 * Number of variables: 463
Notification: Performance of Generate backend data structure: time 0.01741/0.2039, allocations: 5.463 MB / 0.6645 GB, free: 4.719 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.618e-05/0.2039, allocations: 11.98 kB / 0.6645 GB, free: 4.707 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01074/0.2147, allocations: 1.633 MB / 0.6661 GB, free: 3.07 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005133/0.2199, allocations: 2.038 MB / 0.6681 GB, free: 0.9688 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000485/0.2204, allocations: 468.6 kB / 0.6685 GB, free: 460 kB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005339/0.2209, allocations: 196 kB / 0.6687 GB, free: 264 kB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004789/0.2257, allocations: 2.427 MB / 0.6711 GB, free: 13.8 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.687e-05/0.2258, allocations: 3.938 kB / 0.6711 GB, free: 13.8 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001886/0.226, allocations: 76 kB / 0.6712 GB, free: 13.72 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.28e-05/0.226, allocations: 44 kB / 0.6712 GB, free: 13.68 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004526/0.2306, allocations: 2.243 MB / 0.6734 GB, free: 11.43 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.01627/0.2469, allocations: 9.156 MB / 0.6823 GB, free: 1.938 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01007/0.257, allocations: 4.421 MB / 0.6867 GB, free: 13.27 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003455/0.2605, allocations: 1.57 MB / 0.6882 GB, free: 11.69 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.000339/0.2608, allocations: 45.81 kB / 0.6882 GB, free: 11.65 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.004211/0.265, allocations: 1.79 MB / 0.69 GB, free: 9.836 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=242): time 7.585e-06/0.2651, allocations: 0 / 0.69 GB, free: 9.836 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=242): time 0.08147/0.3465, allocations: 18.08 MB / 0.7076 GB, free: 7.617 MB / 0.5137 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.000124/0.3467, allocations: 239.5 kB / 0.7079 GB, free: 7.336 MB / 0.5137 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004909/0.3516, allocations: 2.515 MB / 0.7103 GB, free: 4.805 MB / 0.5137 GB
Notification: Performance of collectPreVariables (initialization): time 0.0006076/0.3523, allocations: 69.7 kB / 0.7104 GB, free: 4.73 MB / 0.5137 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001085/0.3534, allocations: 1.135 MB / 0.7115 GB, free: 3.586 MB / 0.5137 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001372/0.3548, allocations: 0.9025 MB / 0.7124 GB, free: 2.676 MB / 0.5137 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002225/0.357, allocations: 0.5816 MB / 0.7129 GB, free: 2.086 MB / 0.5137 GB
Notification: Performance of setup shared object (initialization): time 0.0001182/0.3571, allocations: 301.1 kB / 0.7132 GB, free: 1.789 MB / 0.5137 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003638/0.3608, allocations: 1.607 MB / 0.7148 GB, free: 184 kB / 0.5137 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.2771/0.6379, allocations: 1.936 MB / 0.7167 GB, free: 79.14 MB / 0.5137 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.04814/0.6861, allocations: 11.23 MB / 0.7277 GB, free: 76.51 MB / 0.5137 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.218e-05/0.6861, allocations: 1.344 kB / 0.7277 GB, free: 76.51 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=422) (initialization): time 0.02288/0.709, allocations: 7.518 MB / 0.735 GB, free: 74.19 MB / 0.5137 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001262/0.7092, allocations: 168.4 kB / 0.7352 GB, free: 74 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.677e-05/0.7092, allocations: 7.844 kB / 0.7352 GB, free: 74 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01062/0.7198, allocations: 2.813 MB / 0.7379 GB, free: 73.45 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003455/0.7233, allocations: 0.6468 MB / 0.7386 GB, free: 73.4 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03138/0.7547, allocations: 19.21 MB / 0.7573 GB, free: 59.4 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003883/0.7586, allocations: 414.6 kB / 0.7577 GB, free: 59.11 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003344/0.759, allocations: 116 kB / 0.7578 GB, free: 59 MB / 0.5137 GB
Warning: Assuming fixed start value for the following 9 variables:
         gen3.gen.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         gen2.gen.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         gen1.gen.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         gen1.gen.delta:VARIABLE(flow=false start = gen1.gen.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         gen1.gen.e1q:VARIABLE(start = gen1.gen.e1q0 unit = "1" fixed = true )  "q-axis transient voltage" type: Real
         gen1.gen.e1d:VARIABLE(start = gen1.gen.e1d0 unit = "1" fixed = true )  "d-axis transient voltage" type: Real
         gen1.AVR.firstOrder2.y:VARIABLE(flow=false start = gen1.AVR.firstOrder2.y_start fixed = true )  "Connector of Real output signal" type: Real
         gen2.gen.delta:VARIABLE(flow=false start = gen2.gen.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         gen2.gen.e1q:VARIABLE(start = gen2.gen.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: 22
 * Number of states: 0 ()
 * Number of discrete variables: 6 ($whenCondition2,$whenCondition1,$whenCondition4,$whenCondition3,$whenCondition6,$whenCondition5)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (351):
 * Single equations (assignments): 338
 * Array equations: 0
 * Algorithm blocks: 6
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 2
 * Torn equation systems: 5
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 2 systems
   {(2,100.0%), (2,100.0%)}
 * Non-linear Jacobian (size): 0 systems
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,1,100.0%), (1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(4,2), (29,30), (1,4)}
Notification: Performance of prepare postOptimizeDAE: time 0.001158/0.7602, allocations: 0.6167 MB / 0.7584 GB, free: 58.36 MB / 0.5137 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005/0.7607, allocations: 317.9 kB / 0.7587 GB, free: 58.05 MB / 0.5137 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02068/0.7814, allocations: 7.382 MB / 0.7659 GB, free: 50.62 MB / 0.5137 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.557e-05/0.7815, allocations: 11.95 kB / 0.7659 GB, free: 50.61 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.813e-06/0.7815, allocations: 3.938 kB / 0.7659 GB, free: 50.61 MB / 0.5137 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001055/0.7825, allocations: 463.4 kB / 0.7664 GB, free: 50.17 MB / 0.5137 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0008993/0.7835, allocations: 59.81 kB / 0.7664 GB, free: 50.11 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002444/0.7859, allocations: 215.7 kB / 0.7666 GB, free: 49.9 MB / 0.5137 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001891/0.7878, allocations: 0.743 MB / 0.7674 GB, free: 49.16 MB / 0.5137 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01255/0.8004, allocations: 5.154 MB / 0.7724 GB, free: 43.99 MB / 0.5137 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.008017/0.8084, allocations: 5.044 MB / 0.7773 GB, free: 38.9 MB / 0.5137 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002348/0.8108, allocations: 1.324 MB / 0.7786 GB, free: 37.57 MB / 0.5137 GB
Notification: Performance of sorting global known variables: time 0.002673/0.8135, allocations: 1.677 MB / 0.7803 GB, free: 35.89 MB / 0.5137 GB
Notification: Performance of Backend: time 1.162e-06/0.8135, allocations: 0 / 0.7803 GB, free: 35.89 MB / 0.5137 GB
Notification: Performance of simCode: created initialization part: time 0.01085/0.8244, allocations: 4.786 MB / 0.7849 GB, free: 30.94 MB / 0.5137 GB
Notification: Performance of SimCode: time 0.006863/0.8313, allocations: 6.645 MB / 0.7914 GB, free: 24.23 MB / 0.5137 GB
Notification: Performance of Templates: time 0.0772/0.9085, allocations: 52.08 MB / 0.8423 GB, free: 4.23 MB / 0.545 GB
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Examples.Tutorial.Example_3.Example_3.makefile
(rm -f OpenIPSL_2.0.0_OpenIPSL.Examples.Tutorial.Example_3.Example_3.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Examples.Tutorial.Example_3.Example_3.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Examples.Tutorial.Example_3.Example_3.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Examples.Tutorial.Example_3.Example_3.sim & ./OpenIPSL_2.0.0_OpenIPSL.Examples.Tutorial.Example_3.Example_3  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Examples.Tutorial.Example_3.Example_3.pipe 2>&1)