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.001194/0.001193, allocations: 115.5 kB / 16.37 MB, free: 6.004 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.001308/0.001308, allocations: 195.6 kB / 17.31 MB, free: 5.613 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.402/1.402, allocations: 205.1 MB / 223.2 MB, free: 12.24 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.3/package.mo): time 0.05952/0.05952, allocations: 14.18 MB / 284.7 MB, free: 13.88 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.001412/0.001413, allocations: 64.47 kB / 348.7 MB, free: 13.8 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.23/0.2314, allocations: 50.12 MB / 398.8 MB, free: 48.79 MB / 318.1 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat): time 0.01336/0.2448, allocations: 15.97 MB / 414.8 MB, free: 35.31 MB / 318.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01093/0.2558, allocations: 6.835 MB / 421.6 MB, free: 28.45 MB / 318.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004101/0.2599, allocations: 67.62 kB / 421.7 MB, free: 28.39 MB / 318.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003104/0.263, allocations: 0.8338 MB / 422.5 MB, free: 27.55 MB / 318.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006759/0.2699, allocations: 2.536 MB / 425 MB, free: 25.02 MB / 318.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003063/0.2729, allocations: 1.536 MB / 426.6 MB, free: 23.49 MB / 318.1 MB
[/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelica/OMCompiler/Compiler/NFFrontEnd/NFFunction.mo:2652:11-2652:130:writable] Error: Internal error NFFunction.Function.getBody2 got function with external section (not algorithm section)
Notification: Performance of NFFlatten.flatten: time 0.005504/0.2784, allocations: 5.083 MB / 431.6 MB, free: 18.39 MB / 318.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001277/0.2797, allocations: 0.7567 MB / 432.4 MB, free: 17.61 MB / 318.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0022/0.2819, allocations: 1.748 MB / 434.1 MB, free: 15.86 MB / 318.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.00229/0.2842, allocations: 1.844 MB / 436 MB, free: 14 MB / 318.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0007703/0.285, allocations: 288 kB / 436.3 MB, free: 13.72 MB / 318.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001813/0.2869, allocations: 0.7028 MB / 437 MB, free: 13.02 MB / 318.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0007002/0.2876, allocations: 0.6264 MB / 437.6 MB, free: 12.39 MB / 318.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002097/0.2897, allocations: 1.648 MB / 439.3 MB, free: 10.73 MB / 318.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.003463/0.2931, allocations: 4.111 MB / 443.4 MB, free: 6.617 MB / 318.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.049e-06/0.2932, allocations: 0 / 443.4 MB, free: 6.617 MB / 318.1 MB
Notification: Performance of FrontEnd: time 2.845e-06/0.2932, allocations: 0 / 443.4 MB, free: 6.617 MB / 318.1 MB
Notification: Performance of Transformations before backend: time 4.024e-05/0.2932, allocations: 3.938 kB / 443.4 MB, free: 6.613 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.01011/0.3033, allocations: 4.207 MB / 447.6 MB, free: 2.344 MB / 318.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.667e-05/0.3034, allocations: 8.031 kB / 447.6 MB, free: 2.336 MB / 318.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001187/0.3046, allocations: 413 kB / 448 MB, free: 1.93 MB / 318.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003387/0.308, allocations: 1.938 MB / 449.9 MB, free: 15.92 MB / 334.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001364/0.3081, allocations: 164.4 kB / 450.1 MB, free: 15.76 MB / 334.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003162/0.3085, allocations: 227.8 kB / 450.3 MB, free: 15.54 MB / 334.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003202/0.3117, allocations: 2.072 MB / 452.4 MB, free: 13.39 MB / 334.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.455e-05/0.3117, allocations: 7.938 kB / 452.4 MB, free: 13.38 MB / 334.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001468/0.3119, allocations: 84 kB / 452.5 MB, free: 13.3 MB / 334.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.586e-05/0.3119, allocations: 48 kB / 452.5 MB, free: 13.25 MB / 334.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003213/0.3151, allocations: 1.916 MB / 454.4 MB, free: 11.34 MB / 334.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.01732/0.3325, allocations: 12.21 MB / 466.6 MB, free: 14.08 MB / 350.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.003768/0.3363, allocations: 2.645 MB / 469.3 MB, free: 11.27 MB / 350.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001272/0.3375, allocations: 0.8144 MB / 470.1 MB, free: 10.45 MB / 350.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001088/0.3377, allocations: 26 kB / 470.1 MB, free: 10.43 MB / 350.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.826e-05/0.3377, allocations: 52.56 kB / 470.2 MB, free: 10.37 MB / 350.1 MB
Notification: Performance of pre-optimization done (n=194): time 4.058e-06/0.3377, allocations: 0 / 470.2 MB, free: 10.37 MB / 350.1 MB
Notification: Performance of matching and sorting (n=194): time 0.008991/0.3467, allocations: 3.41 MB / 473.6 MB, free: 6.93 MB / 350.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.218e-05/0.3468, allocations: 180.2 kB / 473.8 MB, free: 6.715 MB / 350.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002672/0.3495, allocations: 1.872 MB / 475.6 MB, free: 4.812 MB / 350.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002179/0.3497, allocations: 73.69 kB / 475.7 MB, free: 4.734 MB / 350.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0007897/0.3505, allocations: 1.092 MB / 476.8 MB, free: 3.629 MB / 350.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0006593/0.3512, allocations: 0.6854 MB / 477.5 MB, free: 2.934 MB / 350.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0006369/0.3518, allocations: 481.5 kB / 478 MB, free: 2.457 MB / 350.1 MB
Notification: Performance of setup shared object (initialization): time 9.774e-05/0.352, allocations: 305.1 kB / 478.3 MB, free: 2.156 MB / 350.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00153/0.3535, allocations: 0.8407 MB / 479.1 MB, free: 1.309 MB / 350.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001934/0.3554, allocations: 1.467 MB / 480.6 MB, free: 15.58 MB / 366.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003321/0.3588, allocations: 2.212 MB / 482.8 MB, free: 13.12 MB / 366.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.312e-05/0.3588, allocations: 8 kB / 482.8 MB, free: 13.12 MB / 366.1 MB
Notification: Performance of matching and sorting (n=343) (initialization): time 0.008837/0.3676, allocations: 3.779 MB / 486.6 MB, free: 9.254 MB / 366.1 MB
Notification: Performance of prepare postOptimizeDAE: time 3.111e-05/0.3677, allocations: 12 kB / 486.6 MB, free: 9.242 MB / 366.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.205e-05/0.3677, allocations: 16 kB / 486.6 MB, free: 9.227 MB / 366.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.00867/0.3764, allocations: 1.812 MB / 488.4 MB, free: 7.379 MB / 366.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002172/0.3786, allocations: 0.5343 MB / 488.9 MB, free: 6.844 MB / 366.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02382/0.4024, allocations: 21.37 MB / 0.4983 GB, free: 15.91 MB / 398.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002753/0.4052, allocations: 269.7 kB / 0.4986 GB, free: 15.64 MB / 398.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002378/0.4055, allocations: 104 kB / 0.4987 GB, free: 15.54 MB / 398.1 MB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001962/0.4075, allocations: 1.072 MB / 0.4997 GB, free: 14.46 MB / 398.1 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.001563/0.409, allocations: 1.354 MB / 0.5011 GB, free: 12.86 MB / 398.1 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.002633/0.4117, allocations: 2.06 MB / 0.5031 GB, free: 10.55 MB / 398.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 9.688e-06/0.4117, allocations: 4 kB / 0.5031 GB, free: 10.55 MB / 398.1 MB
Notification: Performance of matching and sorting (n=343) (initialization_lambda0): time 0.00597/0.4177, allocations: 2.966 MB / 0.506 GB, free: 7.5 MB / 398.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.355e-05/0.4177, allocations: 15.98 kB / 0.506 GB, free: 7.484 MB / 398.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.197e-05/0.4177, allocations: 16 kB / 0.506 GB, free: 7.469 MB / 398.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006487/0.4242, allocations: 1.303 MB / 0.5073 GB, free: 6.137 MB / 398.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002519/0.4268, allocations: 0.6046 MB / 0.5079 GB, free: 5.531 MB / 398.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01598/0.4428, allocations: 16.54 MB / 0.524 GB, free: 3.566 MB / 414.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002582/0.4454, allocations: 194.4 kB / 0.5242 GB, free: 3.375 MB / 414.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001775/0.4456, allocations: 107.9 kB / 0.5243 GB, free: 3.27 MB / 414.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 (not torn): 0
 * Torn equation systems: 11
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 5 systems
   {(2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (3,0,66.7%), (3,0,66.7%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 6 systems
   {(38,0), (2,0), (2,0), (2,0), (2,0), (10,0)}
Notification: Performance of prepare postOptimizeDAE: time 0.0005634/0.4462, allocations: 333.6 kB / 0.5246 GB, free: 2.938 MB / 414.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004407/0.4466, allocations: 249 kB / 0.5249 GB, free: 2.691 MB / 414.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003956/0.4506, allocations: 2.116 MB / 0.5269 GB, free: 0.5586 MB / 414.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.637e-05/0.4506, allocations: 7.969 kB / 0.5269 GB, free: 0.5508 MB / 414.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.438e-06/0.4506, allocations: 4 kB / 0.5269 GB, free: 0.5469 MB / 414.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0008865/0.4515, allocations: 0.5015 MB / 0.5274 GB, free: 24 kB / 414.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.1589/0.6104, allocations: 31.48 kB / 0.5275 GB, free: 122.9 MB / 414.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001862/0.6122, allocations: 73.34 kB / 0.5275 GB, free: 122.9 MB / 414.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004438/0.6127, allocations: 150.9 kB / 0.5277 GB, free: 122.9 MB / 414.1 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.005035/0.6178, allocations: 2.097 MB / 0.5297 GB, free: 122.7 MB / 414.1 MB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.004086/0.6219, allocations: 2.64 MB / 0.5323 GB, free: 122.4 MB / 414.1 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001211/0.6231, allocations: 0.7032 MB / 0.533 GB, free: 122.4 MB / 414.1 MB
Notification: Performance of sorting global known variables: time 0.002536/0.6256, allocations: 1.212 MB / 0.5342 GB, free: 121.9 MB / 414.1 MB
Notification: Performance of Backend: time 9.62e-07/0.6257, allocations: 0 / 0.5342 GB, free: 121.9 MB / 414.1 MB
Warning: The hideResult annotation could not be evaluated, probably due to missing annotation(Evaluate=true). It is removed.
Notification: Performance of simCode: created initialization part: time 0.01306/0.6387, allocations: 7.021 MB / 0.541 GB, free: 118.2 MB / 414.1 MB
Warning: The hideResult annotation could not be evaluated, probably due to missing annotation(Evaluate=true). It is removed.
Notification: Performance of SimCode: time 0.007548/0.6463, allocations: 5.285 MB / 0.5462 GB, free: 115.5 MB / 414.1 MB
Notification: Performance of Templates: time 0.06479/0.7111, allocations: 49.4 MB / 0.5944 GB, free: 68.51 MB / 414.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 -s cvode -lv LOG_STATS > PowerGrids_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithReferenceCalculationSat.pipe 2>&1)