Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr PowerGrids_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat.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 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.3/package.mo", uses=false)
Using package PowerGrids with version 1.0.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.3/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 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.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat,tolerance=1e-06,outputFormat="empty",numberOfIntervals=3000,variableFilter="",fileNamePrefix="PowerGrids_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat")
translateModel(PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat,tolerance=1e-06,outputFormat="empty",numberOfIntervals=3000,variableFilter="",fileNamePrefix="PowerGrids_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001292/0.001292, allocations: 84.38 kB / 15.06 MB, free: 1.09 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.001559/0.001559, allocations: 168.7 kB / 15.95 MB, free: 204 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.572/1.572, allocations: 205.4 MB / 222.1 MB, free: 5.645 MB / 174.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.3/package.mo): time 0.0626/0.0626, allocations: 14.16 MB / 284.5 MB, free: 7.133 MB / 238.1 MB
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: Performance of FrontEnd - loaded program: time 0.0005443/0.0005443, allocations: 12 kB / 348.2 MB, free: 17.86 MB / 254.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1117/0.1122, allocations: 51.13 MB / 399.3 MB, free: 14.68 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat): time 0.01414/0.1264, allocations: 14.58 MB / 413.9 MB, free: 48 kB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01208/0.1385, allocations: 6.46 MB / 420.4 MB, free: 9.57 MB / 318.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003233/0.1418, allocations: 71.56 kB / 420.4 MB, free: 9.5 MB / 318.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002461/0.1443, allocations: 0.6015 MB / 421 MB, free: 8.895 MB / 318.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006921/0.1512, allocations: 2.025 MB / 423 MB, free: 6.879 MB / 318.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003426/0.1547, allocations: 1.118 MB / 424.2 MB, free: 5.766 MB / 318.1 MB
Notification: Performance of NFFlatten.flatten: time 0.007516/0.1622, allocations: 4.728 MB / 428.9 MB, free: 1.02 MB / 318.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.00119/0.1634, allocations: 0.5855 MB / 429.5 MB, free: 420 kB / 318.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.2061/0.3696, allocations: 1.255 MB / 430.7 MB, free: 14.01 MB / 318.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003336/0.3729, allocations: 1.39 MB / 432.1 MB, free: 14.01 MB / 318.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.000577/0.3735, allocations: 93.25 kB / 432.2 MB, free: 14.01 MB / 318.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002113/0.3757, allocations: 465.9 kB / 432.7 MB, free: 14.01 MB / 318.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0005684/0.3762, allocations: 393.3 kB / 433.1 MB, free: 14.01 MB / 318.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001493/0.3777, allocations: 0.5366 MB / 433.6 MB, free: 13.98 MB / 318.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.005047/0.3828, allocations: 2.918 MB / 436.5 MB, free: 13.8 MB / 318.1 MB
Notification: Performance of FrontEnd - DAE generated: time 7.764e-06/0.3828, allocations: 1.344 kB / 436.5 MB, free: 13.8 MB / 318.1 MB
Notification: Performance of FrontEnd: time 1.472e-06/0.3828, allocations: 0 / 436.5 MB, free: 13.8 MB / 318.1 MB
Notification: Performance of Transformations before backend: time 9.425e-05/0.3829, allocations: 0 / 436.5 MB, free: 13.8 MB / 318.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 507
 * Number of variables: 507
Notification: Performance of Generate backend data structure: time 0.01173/0.3947, allocations: 3.631 MB / 440.1 MB, free: 12.45 MB / 318.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.624e-05/0.3947, allocations: 13.61 kB / 440.2 MB, free: 12.44 MB / 318.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002121/0.3969, allocations: 408.5 kB / 440.6 MB, free: 12.28 MB / 318.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005131/0.402, allocations: 1.727 MB / 442.3 MB, free: 11.77 MB / 318.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001699/0.4022, allocations: 163.9 kB / 442.4 MB, free: 11.77 MB / 318.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003986/0.4026, allocations: 227.2 kB / 442.7 MB, free: 11.77 MB / 318.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004179/0.4068, allocations: 2.097 MB / 444.8 MB, free: 11.5 MB / 318.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.878e-05/0.4068, allocations: 0 / 444.8 MB, free: 11.5 MB / 318.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001743/0.407, allocations: 80.16 kB / 444.8 MB, free: 11.5 MB / 318.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.049e-05/0.4071, allocations: 48.84 kB / 444.9 MB, free: 11.5 MB / 318.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003997/0.4111, allocations: 1.967 MB / 446.9 MB, free: 11.48 MB / 318.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.01451/0.4256, allocations: 11.67 MB / 458.5 MB, free: 3.34 MB / 318.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.003463/0.4291, allocations: 2.69 MB / 461.2 MB, free: 2.125 MB / 318.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001168/0.4303, allocations: 0.8359 MB / 462 MB, free: 2.023 MB / 318.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001031/0.4304, allocations: 25.53 kB / 462.1 MB, free: 2.023 MB / 318.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.598e-05/0.4305, allocations: 58.7 kB / 462.1 MB, free: 1.977 MB / 318.1 MB
Notification: Performance of pre-optimization done (n=194): time 4.869e-06/0.4305, allocations: 1.594 kB / 462.1 MB, free: 1.977 MB / 318.1 MB
Notification: Performance of matching and sorting (n=194): time 0.008723/0.4392, allocations: 3.581 MB / 465.7 MB, free: 0.9258 MB / 318.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.622e-05/0.4393, allocations: 173.8 kB / 465.9 MB, free: 0.7383 MB / 318.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002279/0.4416, allocations: 1.767 MB / 467.6 MB, free: 216 kB / 318.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002145/0.4418, allocations: 66.2 kB / 467.7 MB, free: 184 kB / 318.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0007919/0.4426, allocations: 1.096 MB / 468.8 MB, free: 15.55 MB / 334.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0007177/0.4433, allocations: 0.6866 MB / 469.5 MB, free: 14.96 MB / 334.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001196/0.4434, allocations: 52 kB / 469.5 MB, free: 14.91 MB / 334.1 MB
Notification: Performance of setup shared object (initialization): time 0.000195/0.4436, allocations: 411.4 kB / 469.9 MB, free: 14.5 MB / 334.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001648/0.4453, allocations: 0.8486 MB / 470.8 MB, free: 13.65 MB / 334.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002142/0.4475, allocations: 1.49 MB / 472.3 MB, free: 11.92 MB / 334.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001924/0.4494, allocations: 1.044 MB / 473.3 MB, free: 10.87 MB / 334.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.119e-05/0.4494, allocations: 4 kB / 473.3 MB, free: 10.87 MB / 334.1 MB
Notification: Performance of matching and sorting (n=343) (initialization): time 0.009229/0.4586, allocations: 4.022 MB / 477.4 MB, free: 6.887 MB / 334.1 MB
Notification: Performance of prepare postOptimizeDAE: time 0.00041/0.4591, allocations: 0.551 MB / 477.9 MB, free: 6.117 MB / 334.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.487e-05/0.4591, allocations: 16 kB / 477.9 MB, free: 6.102 MB / 334.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0078/0.4669, allocations: 1.697 MB / 479.6 MB, free: 4.375 MB / 334.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002276/0.4692, allocations: 0.5379 MB / 480.2 MB, free: 3.867 MB / 334.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02393/0.4932, allocations: 20.33 MB / 0.4888 GB, free: 14.17 MB / 366.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003562/0.4968, allocations: 305.5 kB / 0.4891 GB, free: 13.87 MB / 366.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0006903/0.4975, allocations: 99.98 kB / 0.4891 GB, free: 13.77 MB / 366.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.368e-05/0.4975, allocations: 6.344 kB / 0.4892 GB, free: 13.77 MB / 366.1 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.005966/0.5035, allocations: 2.703 MB / 0.4918 GB, free: 11.12 MB / 366.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.726e-05/0.5036, allocations: 16.16 kB / 0.4918 GB, free: 11.1 MB / 366.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006056/0.5096, allocations: 1.291 MB / 0.4931 GB, free: 9.789 MB / 366.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002671/0.5123, allocations: 0.6113 MB / 0.4937 GB, free: 9.211 MB / 366.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01485/0.5272, allocations: 15.26 MB / 0.5086 GB, free: 8.699 MB / 382.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003167/0.5304, allocations: 210.2 kB / 0.5088 GB, free: 8.492 MB / 382.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003179/0.5307, allocations: 108 kB / 0.5089 GB, free: 8.387 MB / 382.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 71
 * Number of states: 0 ()
 * Number of discrete variables: 5 (PSS.firstWashoutVsi2.integrator.local_reset,PSS.secondWashoutVsi1.integrator.local_reset,PSS.firstWashoutVsi1.integrator.local_reset,AVR.firstOrderLim.I.local_reset,TGOV.firstOrderLim.I.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (286):
 * Single equations (assignments): 275
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 11
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 5 {(2,100.0%) 0,(2,100.0%) 0,(2,100.0%) 0,(3,66.7%) 0,(3,66.7%) 0}
 * Non-linear torn systems: 6 {38 0,2 0,2 0,2 0,2 0,10 0}
Notification: Performance of prepare postOptimizeDAE: time 0.002066/0.5328, allocations: 0.553 MB / 0.5094 GB, free: 7.855 MB / 382.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0007502/0.5336, allocations: 245 kB / 0.5096 GB, free: 7.613 MB / 382.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004312/0.5379, allocations: 2.173 MB / 0.5118 GB, free: 5.445 MB / 382.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.06e-05/0.5379, allocations: 11.95 kB / 0.5118 GB, free: 5.434 MB / 382.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.02e-06/0.5379, allocations: 0 / 0.5118 GB, free: 5.434 MB / 382.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.001286/0.5392, allocations: 473.6 kB / 0.5122 GB, free: 4.949 MB / 382.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003349/0.5396, allocations: 44 kB / 0.5123 GB, free: 4.906 MB / 382.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001184/0.5408, allocations: 107.6 kB / 0.5124 GB, free: 4.801 MB / 382.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002756/0.5411, allocations: 155.6 kB / 0.5125 GB, free: 4.648 MB / 382.1 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.004821/0.5459, allocations: 2.161 MB / 0.5146 GB, free: 2.52 MB / 382.1 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.00432/0.5502, allocations: 2.706 MB / 0.5173 GB, free: 15.8 MB / 398.1 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001331/0.5516, allocations: 0.7223 MB / 0.518 GB, free: 15.08 MB / 398.1 MB
Notification: Performance of sorting global known variables: time 0.003282/0.5549, allocations: 1.128 MB / 0.5191 GB, free: 13.94 MB / 398.1 MB
Notification: Performance of Backend: time 8.71e-07/0.5549, allocations: 0 / 0.5191 GB, free: 13.94 MB / 398.1 MB
Notification: Performance of simCode: created initialization part: time 0.02222/0.5771, allocations: 7.058 MB / 0.526 GB, free: 6.766 MB / 398.1 MB
Warning: The hideResult annotation could not be evaluated, probably due to missing annotation(Evaluate=true). It is set to 'isProtected' (=false) by default.
Notification: Performance of SimCode: time 0.01679/0.5939, allocations: 5.985 MB / 0.5318 GB, free: 0.7695 MB / 398.1 MB
Notification: Performance of Templates: time 0.09537/0.6894, allocations: 42.39 MB / 0.5732 GB, free: 6.496 MB / 446.1 MB
make -j1 -f PowerGrids_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat.makefile
(rm -f PowerGrids_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat.pipe ; mkfifo PowerGrids_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat.pipe ; head -c 1048576 < PowerGrids_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat.pipe >> ../files/PowerGrids_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat.sim & ./PowerGrids_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat  -nls=kinsol -lv=LOG_INIT_HOMOTOPY -abortSlowSimulation -alarm=480 -lv LOG_STATS > PowerGrids_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat.pipe 2>&1)