Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerSystems_PowerSystems.Examples.Wind.WindTurbine_IG.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/Complex 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/PowerSystems 2.0.0/package.mo", uses=false)
Using package PowerSystems with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.0.0/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 Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 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(PowerSystems.Examples.Wind.WindTurbine_IG,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="PowerSystems_PowerSystems.Examples.Wind.WindTurbine_IG")
translateModel(PowerSystems.Examples.Wind.WindTurbine_IG,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="PowerSystems_PowerSystems.Examples.Wind.WindTurbine_IG")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001216/0.001216, allocations: 110.6 kB / 16.62 MB, free: 6.961 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001277/0.001276, allocations: 190 kB / 17.54 MB, free: 6.066 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.342/1.342, allocations: 222.9 MB / 241.2 MB, free: 13.74 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.0.0/package.mo): time 0.177/0.177, allocations: 38.02 MB / 329.4 MB, free: 8.758 MB / 270.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001292/0.001292, allocations: 63.94 kB / 398.3 MB, free: 34.37 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1063/0.1076, allocations: 58.06 MB / 456.3 MB, free: 8.402 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.Wind.WindTurbine_IG): time 0.01149/0.1191, allocations: 9.313 MB / 465.6 MB, free: 15.04 MB / 350.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01069/0.1298, allocations: 5.858 MB / 471.5 MB, free: 9.164 MB / 350.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00145/0.1313, allocations: 51.62 kB / 471.5 MB, free: 9.113 MB / 350.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01025/0.1416, allocations: 5.292 MB / 476.8 MB, free: 3.812 MB / 350.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002259/0.1439, allocations: 0.7823 MB / 477.6 MB, free: 3.027 MB / 350.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001364/0.1453, allocations: 447.3 kB / 478.1 MB, free: 2.59 MB / 350.1 MB
Notification: Performance of NFFlatten.flatten: time 0.00367/0.149, allocations: 2.589 MB / 480.6 MB, free: 15.99 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001217/0.1502, allocations: 0.6494 MB / 481.3 MB, free: 15.33 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001387/0.1516, allocations: 0.7783 MB / 482.1 MB, free: 14.55 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001648/0.1533, allocations: 1.114 MB / 483.2 MB, free: 13.43 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001787/0.1535, allocations: 104 kB / 483.3 MB, free: 13.33 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0008456/0.1543, allocations: 363.5 kB / 483.6 MB, free: 12.98 MB / 366.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0006569/0.155, allocations: 0.6703 MB / 484.3 MB, free: 12.3 MB / 366.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0007213/0.1558, allocations: 0.692 MB / 485 MB, free: 11.61 MB / 366.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.001798/0.1576, allocations: 1.709 MB / 486.7 MB, free: 9.895 MB / 366.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.883e-06/0.1576, allocations: 4 kB / 486.7 MB, free: 9.891 MB / 366.1 MB
Notification: Performance of FrontEnd: time 1.352e-06/0.1576, allocations: 0 / 486.7 MB, free: 9.891 MB / 366.1 MB
Notification: Performance of Transformations before backend: time 2.167e-05/0.1576, allocations: 0 / 486.7 MB, free: 9.891 MB / 366.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 281
 * Number of variables: 281
Notification: Performance of Generate backend data structure: time 0.003513/0.1611, allocations: 1.585 MB / 488.3 MB, free: 8.25 MB / 366.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.5e-05/0.1612, allocations: 15.88 kB / 488.3 MB, free: 8.234 MB / 366.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001303/0.1625, allocations: 215.1 kB / 488.5 MB, free: 8.023 MB / 366.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001683/0.1642, allocations: 0.9256 MB / 489.5 MB, free: 7.055 MB / 366.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.533e-05/0.1643, allocations: 73.97 kB / 489.5 MB, free: 6.984 MB / 366.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004525/0.1647, allocations: 179.8 kB / 489.7 MB, free: 6.809 MB / 366.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002393/0.1671, allocations: 1.56 MB / 491.3 MB, free: 5.168 MB / 366.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.644e-05/0.1672, allocations: 4 kB / 491.3 MB, free: 5.164 MB / 366.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001052/0.1673, allocations: 47.92 kB / 491.3 MB, free: 5.117 MB / 366.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0006012/0.1679, allocations: 153.9 kB / 491.5 MB, free: 4.965 MB / 366.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001949/0.1699, allocations: 1.245 MB / 492.7 MB, free: 3.711 MB / 366.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.008225/0.1781, allocations: 6.493 MB / 499.2 MB, free: 12.84 MB / 382.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.00149/0.1796, allocations: 0.9011 MB / 0.4884 GB, free: 11.91 MB / 382.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0007333/0.1804, allocations: 0.5121 MB / 0.4889 GB, free: 11.4 MB / 382.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 8.734e-05/0.1805, allocations: 24.97 kB / 0.4889 GB, free: 11.38 MB / 382.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.804e-05/0.1805, allocations: 43.5 kB / 0.4889 GB, free: 11.32 MB / 382.1 MB
Notification: Performance of pre-optimization done (n=91): time 4.588e-06/0.1805, allocations: 4 kB / 0.489 GB, free: 11.32 MB / 382.1 MB
Notification: Performance of matching and sorting (n=96): time 0.006739/0.1873, allocations: 3.659 MB / 0.4925 GB, free: 7.516 MB / 382.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.757e-05/0.1874, allocations: 93.98 kB / 0.4926 GB, free: 7.406 MB / 382.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.000862/0.1882, allocations: 0.7312 MB / 0.4933 GB, free: 6.664 MB / 382.1 MB
Notification: Performance of collectPreVariables (initialization): time 6.991e-05/0.1883, allocations: 41.7 kB / 0.4934 GB, free: 6.617 MB / 382.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0003618/0.1887, allocations: 0.5535 MB / 0.4939 GB, free: 6.055 MB / 382.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0002661/0.1889, allocations: 362.8 kB / 0.4943 GB, free: 5.695 MB / 382.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003461/0.1893, allocations: 246.7 kB / 0.4945 GB, free: 5.449 MB / 382.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001839/0.1895, allocations: 410.7 kB / 0.4949 GB, free: 5.039 MB / 382.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0009049/0.1904, allocations: 0.5608 MB / 0.4954 GB, free: 4.477 MB / 382.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001052/0.1915, allocations: 0.8854 MB / 0.4963 GB, free: 3.461 MB / 382.1 MB
Notification: It was not possible to check the given initialization system for consistency symbolically, because the relevant equations are part of an algebraic loop. This is not supported yet.
Notification: Performance of analyzeInitialSystem (initialization): time 0.008551/0.2, allocations: 3.247 MB / 0.4995 GB, free: 92 kB / 382.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.138e-05/0.2001, allocations: 4 kB / 0.4995 GB, free: 88 kB / 382.1 MB
Notification: Performance of matching and sorting (n=164) (initialization): time 0.2189/0.419, allocations: 2.734 MB / 0.5021 GB, free: 56.1 MB / 382.1 MB
Notification: Performance of prepare postOptimizeDAE: time 3.604e-05/0.4191, allocations: 11.16 kB / 0.5022 GB, free: 56.1 MB / 382.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.8e-05/0.4191, allocations: 9.562 kB / 0.5022 GB, free: 56.1 MB / 382.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003132/0.4222, allocations: 1.161 MB / 0.5033 GB, free: 56.04 MB / 382.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00088/0.4231, allocations: 198.1 kB / 0.5035 GB, free: 56.02 MB / 382.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01812/0.4413, allocations: 12.3 MB / 0.5155 GB, free: 49.98 MB / 382.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0009387/0.4422, allocations: 53.52 kB / 0.5155 GB, free: 49.97 MB / 382.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001113/0.4424, allocations: 57.81 kB / 0.5156 GB, free: 49.96 MB / 382.1 MB
Warning: The initial conditions are over specified. The following 3 initial equations are redundant, so they are removed from the initialization system:
         $DER.inertia.w = 0.0
         infBus.V = 15000.0 * infBus.v0
         line.omega[1] = 0.0.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 43
 * Number of states: 0 ()
 * Number of discrete variables: 3 (system.initime,trafo.tap_2_internal,trafo.tap_1_internal)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (96):
 * Single equations (assignments): 94
 * 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): 1 system
   {(1,17,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(24,28)}
Notification: Performance of prepare postOptimizeDAE: time 0.0006817/0.4431, allocations: 404.3 kB / 0.516 GB, free: 49.72 MB / 382.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001658/0.4432, allocations: 96.17 kB / 0.5161 GB, free: 49.72 MB / 382.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002155/0.4454, allocations: 1.405 MB / 0.5175 GB, free: 49.04 MB / 382.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.188e-05/0.4454, allocations: 9.969 kB / 0.5175 GB, free: 49.04 MB / 382.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.317e-06/0.4454, allocations: 5.031 kB / 0.5175 GB, free: 49.03 MB / 382.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.25e-05/0.4454, allocations: 12.89 kB / 0.5175 GB, free: 49.03 MB / 382.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.003768/0.4492, allocations: 2.655 MB / 0.5201 GB, free: 47.82 MB / 382.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.131e-06/0.4492, allocations: 1.562 kB / 0.5201 GB, free: 47.82 MB / 382.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001457/0.4507, allocations: 280.6 kB / 0.5203 GB, free: 47.8 MB / 382.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.000154/0.4509, allocations: 51.42 kB / 0.5204 GB, free: 47.78 MB / 382.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 8.227e-05/0.451, allocations: 39.08 kB / 0.5204 GB, free: 47.77 MB / 382.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001421/0.4524, allocations: 2.852 MB / 0.5232 GB, free: 44.94 MB / 382.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.607e-06/0.4524, allocations: 2.75 kB / 0.5232 GB, free: 44.94 MB / 382.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002381/0.4548, allocations: 1.891 MB / 0.5251 GB, free: 43.84 MB / 382.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004152/0.4552, allocations: 257.9 kB / 0.5253 GB, free: 43.64 MB / 382.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001318/0.4554, allocations: 42.2 kB / 0.5253 GB, free: 43.62 MB / 382.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0005362/0.4559, allocations: 43.42 kB / 0.5254 GB, free: 43.59 MB / 382.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001432/0.4561, allocations: 63.81 kB / 0.5254 GB, free: 43.56 MB / 382.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 5.723e-05/0.4561, allocations: 38.77 kB / 0.5255 GB, free: 43.55 MB / 382.1 MB
Notification: Performance of sorting global known variables: time 0.000491/0.4566, allocations: 0.489 MB / 0.526 GB, free: 43.27 MB / 382.1 MB
Notification: Performance of sort global known variables: time 1.3e-07/0.4566, allocations: 0 / 0.526 GB, free: 43.27 MB / 382.1 MB
Notification: Performance of remove unused functions: time 0.001278/0.4579, allocations: 372.6 kB / 0.5263 GB, free: 43.23 MB / 382.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 9
 * Number of states: 25 (generator.i[3],generator.psi_s[1],generator.psi_s[2],generator.psi_rd[1],generator.psi_rq[1],line.v[1,1],line.v[1,2],line.v[1,3],line.v[2,1],line.v[2,2],line.v[2,3],line.v[3,1],line.v[3,2],line.v[3,3],line.i[1,1],line.i[1,2],line.i[1,3],line.i[2,1],line.i[2,2],line.i[2,3],line.i[3,1],line.i[3,2],line.i[3,3],inertia.phi,inertia.w)
 * Number of discrete variables: 1 (system.initime)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (95):
 * Single equations (assignments): 92
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 1
 * 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): 2 systems
   {(1,1,100.0%), (1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.0001585/0.4581, allocations: 140.9 kB / 0.5265 GB, free: 43.12 MB / 382.1 MB
Notification: Performance of simCode: created initialization part: time 0.003676/0.4617, allocations: 2.571 MB / 0.529 GB, free: 41.36 MB / 382.1 MB
Notification: Performance of simCode: created event and clocks part: time 2.965e-06/0.4618, allocations: 0 / 0.529 GB, free: 41.36 MB / 382.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.0008865/0.4627, allocations: 0.7489 MB / 0.5297 GB, free: 40.59 MB / 382.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0008627/0.4635, allocations: 226.3 kB / 0.5299 GB, free: 40.38 MB / 382.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004708/0.4682, allocations: 4.536 MB / 0.5343 GB, free: 35.88 MB / 382.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0005725/0.4688, allocations: 1.31 MB / 0.5356 GB, free: 34.54 MB / 382.1 MB
Notification: Performance of simCode: alias equations: time 0.0007781/0.4696, allocations: 316 kB / 0.5359 GB, free: 34.23 MB / 382.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002278/0.4699, allocations: 79.42 kB / 0.536 GB, free: 34.16 MB / 382.1 MB
Notification: Performance of SimCode: time 4.81e-07/0.4699, allocations: 0 / 0.536 GB, free: 34.16 MB / 382.1 MB
Notification: Performance of Templates: time 0.02475/0.4946, allocations: 19.06 MB / 0.5546 GB, free: 15.24 MB / 382.1 MB
make -j1 -f PowerSystems_PowerSystems.Examples.Wind.WindTurbine_IG.makefile
(rm -f PowerSystems_PowerSystems.Examples.Wind.WindTurbine_IG.pipe ; mkfifo PowerSystems_PowerSystems.Examples.Wind.WindTurbine_IG.pipe ; head -c 1048576 < PowerSystems_PowerSystems.Examples.Wind.WindTurbine_IG.pipe >> ../files/PowerSystems_PowerSystems.Examples.Wind.WindTurbine_IG.sim & ./PowerSystems_PowerSystems.Examples.Wind.WindTurbine_IG  -abortSlowSimulation -alarm=480 -lv LOG_STATS > PowerSystems_PowerSystems.Examples.Wind.WindTurbine_IG.pipe 2>&1)