Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.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/PowerGrids 2.0.0-develop/package.mo", uses=false)
Using package PowerGrids with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/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(PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=4000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF")
translateModel(PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=4000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001571/0.001571, allocations: 104.9 kB / 16.27 MB, free: 6.402 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.001975/0.001975, allocations: 191.5 kB / 17.2 MB, free: 5.969 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.385/1.385, allocations: 222.9 MB / 240.9 MB, free: 15.3 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo): time 0.07611/0.07611, allocations: 16.79 MB / 307.8 MB, free: 14.44 MB / 254.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001464/0.001464, allocations: 66.48 kB / 372.4 MB, free: 48.81 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.09717/0.09866, allocations: 53.57 MB / 426 MB, free: 12.64 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF): time 0.3565/0.4552, allocations: 101.4 MB / 0.5151 GB, free: 19.68 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.03968/0.495, allocations: 23.36 MB / 0.5379 GB, free: 17.62 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01626/0.5114, allocations: 199.3 kB / 0.5381 GB, free: 17.62 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01263/0.524, allocations: 3.176 MB / 0.5412 GB, free: 16.65 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.02956/0.5536, allocations: 9.172 MB / 0.5501 GB, free: 10.5 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01312/0.5668, allocations: 4.973 MB / 0.555 GB, free: 7.84 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.03404/0.6008, allocations: 21.4 MB / 0.5759 GB, free: 8.035 MB / 398.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.008407/0.6093, allocations: 3.735 MB / 0.5795 GB, free: 4.348 MB / 398.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.01454/0.6238, allocations: 7.131 MB / 0.5865 GB, free: 13.31 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01532/0.6392, allocations: 7.66 MB / 0.594 GB, free: 5.867 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.005226/0.6445, allocations: 1.012 MB / 0.595 GB, free: 4.855 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.01039/0.6549, allocations: 2.061 MB / 0.597 GB, free: 2.801 MB / 414.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.003674/0.6586, allocations: 2.385 MB / 0.5993 GB, free: 0.4961 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.01681/0.6754, allocations: 6.867 MB / 0.606 GB, free: 9.684 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.02758/0.703, allocations: 15.96 MB / 0.6216 GB, free: 10.35 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 8.967e-06/0.7031, allocations: 0 / 0.6216 GB, free: 10.35 MB / 446.1 MB
Notification: Performance of FrontEnd: time 2.614e-06/0.7031, allocations: 0 / 0.6216 GB, free: 10.35 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0005947/0.7037, allocations: 4 kB / 0.6216 GB, free: 10.34 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: 2013
 * Number of variables: 2013
Notification: Performance of Generate backend data structure: time 0.06371/0.7674, allocations: 16.48 MB / 0.6377 GB, free: 9.949 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.628e-05/0.7675, allocations: 8.031 kB / 0.6377 GB, free: 9.941 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01757/0.7851, allocations: 2.703 MB / 0.6403 GB, free: 7.242 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02108/0.8062, allocations: 6.877 MB / 0.6471 GB, free: 448 kB / 462.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007839/0.807, allocations: 0.6413 MB / 0.6477 GB, free: 15.79 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003313/0.8103, allocations: 0.8594 MB / 0.6485 GB, free: 14.93 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01902/0.8294, allocations: 9.386 MB / 0.6577 GB, free: 4.48 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001512/0.8296, allocations: 51.5 kB / 0.6577 GB, free: 4.43 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001002/0.8306, allocations: 364 kB / 0.6581 GB, free: 4.074 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001787/0.8308, allocations: 220 kB / 0.6583 GB, free: 3.859 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01433/0.8451, allocations: 7.539 MB / 0.6657 GB, free: 12.27 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.5898/1.435, allocations: 63.05 MB / 0.7272 GB, free: 137.1 MB / 0.4982 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0127/1.448, allocations: 8.512 MB / 0.7355 GB, free: 132.4 MB / 0.4982 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004457/1.452, allocations: 2.693 MB / 0.7382 GB, free: 131.3 MB / 0.4982 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003939/1.453, allocations: 102.4 kB / 0.7383 GB, free: 131.2 MB / 0.4982 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0001151/1.453, allocations: 134.2 kB / 0.7384 GB, free: 131.2 MB / 0.4982 GB
Notification: Performance of pre-optimization done (n=708): time 9.397e-06/1.453, allocations: 0 / 0.7384 GB, free: 131.2 MB / 0.4982 GB
Notification: Performance of matching and sorting (n=708): time 0.03856/1.491, allocations: 12.85 MB / 0.751 GB, free: 126.3 MB / 0.4982 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001032/1.491, allocations: 260.2 kB / 0.7512 GB, free: 126.1 MB / 0.4982 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01508/1.506, allocations: 8.715 MB / 0.7597 GB, free: 121.7 MB / 0.4982 GB
Notification: Performance of collectPreVariables (initialization): time 0.002374/1.509, allocations: 194.6 kB / 0.7599 GB, free: 121.6 MB / 0.4982 GB
Notification: Performance of collectInitialEqns (initialization): time 0.00419/1.513, allocations: 4.391 MB / 0.7642 GB, free: 118.3 MB / 0.4982 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003884/1.517, allocations: 2.831 MB / 0.767 GB, free: 115.9 MB / 0.4982 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.01145/1.528, allocations: 4.287 MB / 0.7711 GB, free: 113.4 MB / 0.4982 GB
Notification: Performance of setup shared object (initialization): time 5.456e-05/1.529, allocations: 307.6 kB / 0.7714 GB, free: 113.1 MB / 0.4982 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01141/1.54, allocations: 6.203 MB / 0.7775 GB, free: 107 MB / 0.4982 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01396/1.554, allocations: 8.402 MB / 0.7857 GB, free: 98.25 MB / 0.4982 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01711/1.571, allocations: 11.94 MB / 0.7974 GB, free: 85.93 MB / 0.4982 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.658e-05/1.571, allocations: 4 kB / 0.7974 GB, free: 85.92 MB / 0.4982 GB
Notification: Performance of matching and sorting (n=3041) (initialization): time 0.04404/1.615, allocations: 20.44 MB / 0.8173 GB, free: 65.36 MB / 0.4982 GB
Notification: Performance of prepare postOptimizeDAE: time 6.534e-05/1.615, allocations: 16 kB / 0.8173 GB, free: 65.34 MB / 0.4982 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.032e-05/1.615, allocations: 28.66 kB / 0.8174 GB, free: 65.32 MB / 0.4982 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02432/1.64, allocations: 4.096 MB / 0.8214 GB, free: 61.13 MB / 0.4982 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02007/1.66, allocations: 4.782 MB / 0.826 GB, free: 56.34 MB / 0.4982 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.3102/1.97, allocations: 53.25 MB / 0.878 GB, free: 148.9 MB / 0.4982 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02087/1.991, allocations: 1.561 MB / 0.8796 GB, free: 148.9 MB / 0.4982 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003888/1.995, allocations: 0.7301 MB / 0.8803 GB, free: 148.9 MB / 0.4982 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 119
 * Number of states: 0 ()
 * Number of discrete variables: 12 (loadPQ3.componentPF.isSlackBus,machine3.componentPF.isSlackBus,bus3.componentPF.isSlackBus,refBus3.componentPF.isSlackBus,bus2.componentPF.isSlackBus,refBus2.componentPF.isSlackBus,LoadPQ2.componentPF.isSlackBus,machine2.componentPF.isSlackBus,bus1.componentPF.isSlackBus,refBus1.componentPF.isSlackBus,LoadPQ1.componentPF.isSlackBus,machine1.componentPF.isSlackBus)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (2937):
 * Single equations (assignments): 2905
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 32
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 14 systems
   {(2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 18 systems
   {(6,0), (15,0), (2,0), (2,0), (2,0), (2,0), (6,0), (17,0), (2,0), (2,0), (2,0), (2,0), (21,0), (19,0), (2,0), (2,0), (2,0), (2,0)}
Notification: Performance of prepare postOptimizeDAE: time 0.008472/2.003, allocations: 1.979 MB / 0.8822 GB, free: 148.8 MB / 0.4982 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.004393/2.008, allocations: 0.9793 MB / 0.8832 GB, free: 148.8 MB / 0.4982 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01521/2.023, allocations: 7.424 MB / 0.8904 GB, free: 148.4 MB / 0.4982 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001613/2.023, allocations: 35.55 kB / 0.8905 GB, free: 148.4 MB / 0.4982 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.561e-05/2.023, allocations: 1.391 kB / 0.8905 GB, free: 148.4 MB / 0.4982 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.00795/2.031, allocations: 1.975 MB / 0.8924 GB, free: 148.3 MB / 0.4982 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.004055/2.035, allocations: 117.7 kB / 0.8925 GB, free: 148.3 MB / 0.4982 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01189/2.047, allocations: 316.2 kB / 0.8928 GB, free: 148.3 MB / 0.4982 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001911/2.049, allocations: 0.5186 MB / 0.8933 GB, free: 148.3 MB / 0.4982 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01853/2.068, allocations: 7.635 MB / 0.9008 GB, free: 146 MB / 0.4982 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.0146/2.082, allocations: 8.797 MB / 0.9093 GB, free: 142.3 MB / 0.4982 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003382/2.086, allocations: 2.204 MB / 0.9115 GB, free: 141.4 MB / 0.4982 GB
Notification: Performance of sorting global known variables: time 0.01046/2.096, allocations: 5.203 MB / 0.9166 GB, free: 139.5 MB / 0.4982 GB
Notification: Performance of Backend: time 9.92e-07/2.096, allocations: 0 / 0.9166 GB, free: 139.5 MB / 0.4982 GB
Notification: Performance of simCode: created initialization part: time 0.04049/2.137, allocations: 17.12 MB / 0.9333 GB, free: 131.1 MB / 0.4982 GB
Warning: The hideResult annotation could not be evaluated, probably due to missing annotation(Evaluate=true). It is removed.
Notification: Performance of SimCode: time 0.04457/2.181, allocations: 20.17 MB / 0.953 GB, free: 115.7 MB / 0.4982 GB
Notification: Performance of Templates: time 1.527/3.708, allocations: 0.7916 GB / 1.745 GB, free: 87.34 MB / 0.5294 GB
make -j1 -f PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.makefile
(rm -f PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.pipe ; mkfifo PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.pipe ; head -c 1048576 < PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.pipe >> ../files/PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.sim & ./PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF  -abortSlowSimulation -alarm=480 -lv LOG_STATS > PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.pipe 2>&1)