Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.CGMES.TG.GovHydroIEEE0_Test.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo): time 0.07509/0.07509, allocations: 9.526 MB / 28.63 MB, free: 1.574 MB / 19.63 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:11:9-20:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:130:9-144:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001429/0.001429, allocations: 87.42 kB / 34.66 MB, free: 2.773 MB / 26.18 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:11:9-20:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:130:9-144:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.377/1.377, allocations: 158.6 MB / 199.2 MB, free: 10.56 MB / 190.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:11:9-20:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:130:9-144:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001658/0.001658, allocations: 164.5 kB / 254.4 MB, free: 6.762 MB / 238.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:11:9-20:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:130:9-144:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo): time 0.4729/0.4729, allocations: 59.71 MB / 369.2 MB, free: 50.02 MB / 334.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:11:9-20:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/WorkInProgress.mo:130:9-144:24:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 179]
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.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.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.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.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/package.mo)
Running command: translateModel(OpenIPSL.Tests.Controls.CGMES.TG.GovHydroIEEE0_Test,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.CGMES.TG.GovHydroIEEE0_Test")
translateModel(OpenIPSL.Tests.Controls.CGMES.TG.GovHydroIEEE0_Test,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.CGMES.TG.GovHydroIEEE0_Test") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.333e-06/1.333e-06, allocations: 0 / 0.518 GB, free: 33.41 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.138e-05/2.271e-05, allocations: 3.547 kB / 0.518 GB, free: 33.4 MB / 430.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:3:3-3:37:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:4:3-4:60:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:5:3-5:58:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:6:3-6:60:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:7:3-7:53:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:8:3-8:50:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:9:3-9:50:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:10:3-16:38:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:17:3-18:38:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:19:3-21:38:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:22:3-27:38:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:28:3-30:38:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:31:3-37:38:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:38:3-44:38:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:45:3-51:38:writable] Warning: Components are deprecated in class.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Controls/CGMES/TG/GovHydroIEEE0.mo:53:3-53:105:writable] Warning: Equation sections are deprecated in class.
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.CGMES.TG.GovHydroIEEE0_Test): time 0.006097/0.00612, allocations: 7.032 MB / 0.5249 GB, free: 28.64 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.00442/0.01054, allocations: 2.504 MB / 0.5273 GB, free: 26.92 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001176/0.01172, allocations: 15.62 kB / 0.5273 GB, free: 26.91 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001013/0.01273, allocations: 343 kB / 0.5277 GB, free: 26.73 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003221/0.01595, allocations: 1.281 MB / 0.5289 GB, free: 25.92 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001704/0.01765, allocations: 0.8256 MB / 0.5297 GB, free: 25.51 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.002458/0.02011, allocations: 2.336 MB / 0.532 GB, free: 24.39 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0005392/0.02065, allocations: 240.8 kB / 0.5322 GB, free: 24.25 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001807/0.02246, allocations: 1.225 MB / 0.5334 GB, free: 23.69 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001326/0.02378, allocations: 0.9367 MB / 0.5343 GB, free: 23.39 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004343/0.02422, allocations: 182.2 kB / 0.5345 GB, free: 23.38 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0009999/0.02522, allocations: 353.1 kB / 0.5348 GB, free: 23.33 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0004571/0.02567, allocations: 479.2 kB / 0.5353 GB, free: 23.03 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0009009/0.02658, allocations: 0.6015 MB / 0.5359 GB, free: 22.71 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.002079/0.02865, allocations: 2.329 MB / 0.5382 GB, free: 21.75 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5e-06/0.02866, allocations: 3.938 kB / 0.5382 GB, free: 21.74 MB / 430.1 MB
Notification: Performance of FrontEnd: time 1.303e-06/0.02866, allocations: 0 / 0.5382 GB, free: 21.74 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 4.564e-05/0.02871, allocations: 0 / 0.5382 GB, free: 21.74 MB / 430.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 228
 * Number of variables: 228
Notification: Performance of Generate backend data structure: time 0.01422/0.04292, allocations: 3.766 MB / 0.5418 GB, free: 20.28 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.402e-05/0.04297, allocations: 7.906 kB / 0.5419 GB, free: 20.28 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004943/0.04791, allocations: 0.7564 MB / 0.5426 GB, free: 20.1 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002726/0.05064, allocations: 1.138 MB / 0.5437 GB, free: 19.41 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004323/0.05107, allocations: 0.5103 MB / 0.5442 GB, free: 18.9 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003786/0.05145, allocations: 108.1 kB / 0.5443 GB, free: 18.86 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003096/0.05454, allocations: 1.42 MB / 0.5457 GB, free: 18.3 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.45e-05/0.05457, allocations: 0 / 0.5457 GB, free: 18.3 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000124/0.05469, allocations: 41.59 kB / 0.5457 GB, free: 18.27 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.646e-05/0.05471, allocations: 22.75 kB / 0.5458 GB, free: 18.27 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002941/0.05765, allocations: 1.357 MB / 0.5471 GB, free: 17.81 MB / 430.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.008425/0.06607, allocations: 3.606 MB / 0.5506 GB, free: 15.91 MB / 430.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.008302/0.07438, allocations: 2.386 MB / 0.5529 GB, free: 15.35 MB / 430.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002225/0.0766, allocations: 0.8386 MB / 0.5538 GB, free: 15.04 MB / 430.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001822/0.07678, allocations: 17.42 kB / 0.5538 GB, free: 15.04 MB / 430.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.949e-05/0.07682, allocations: 53 kB / 0.5538 GB, free: 14.98 MB / 430.1 MB
Notification: Performance of pre-optimization done (n=123): time 3.466e-06/0.07683, allocations: 1.375 kB / 0.5538 GB, free: 14.98 MB / 430.1 MB
Notification: Performance of matching and sorting (n=123): time 0.04601/0.1228, allocations: 9.821 MB / 0.5634 GB, free: 11.96 MB / 430.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.732e-05/0.1229, allocations: 116.8 kB / 0.5635 GB, free: 11.83 MB / 430.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003028/0.1259, allocations: 1.439 MB / 0.5649 GB, free: 11.05 MB / 430.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0003868/0.1263, allocations: 54.69 kB / 0.565 GB, free: 10.99 MB / 430.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004169/0.1267, allocations: 0.6074 MB / 0.5656 GB, free: 10.45 MB / 430.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0005801/0.1273, allocations: 468.4 kB / 0.566 GB, free: 10.05 MB / 430.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0008739/0.1282, allocations: 302.5 kB / 0.5663 GB, free: 9.871 MB / 430.1 MB
Notification: Performance of setup shared object (initialization): time 3.371e-05/0.1282, allocations: 302.5 kB / 0.5666 GB, free: 9.574 MB / 430.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002192/0.1304, allocations: 0.9648 MB / 0.5675 GB, free: 9.082 MB / 430.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002285/0.1327, allocations: 1.183 MB / 0.5687 GB, free: 8.371 MB / 430.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02506/0.1578, allocations: 6.516 MB / 0.5751 GB, free: 2.98 MB / 430.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.107e-06/0.1578, allocations: 0 / 0.5751 GB, free: 2.98 MB / 430.1 MB
Notification: Performance of matching and sorting (n=216) (initialization): time 0.007004/0.1648, allocations: 3.052 MB / 0.578 GB, free: 15.92 MB / 446.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.627e-05/0.1648, allocations: 11.94 kB / 0.578 GB, free: 15.91 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.452e-05/0.1648, allocations: 4 kB / 0.5781 GB, free: 15.9 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01056/0.1754, allocations: 2.642 MB / 0.5806 GB, free: 13.25 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001682/0.1771, allocations: 287.6 kB / 0.5809 GB, free: 12.97 MB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007758/0.1848, allocations: 2.689 MB / 0.5835 GB, free: 10.26 MB / 446.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002901/0.1877, allocations: 254.8 kB / 0.5838 GB, free: 10.01 MB / 446.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003381/0.1881, allocations: 59.98 kB / 0.5838 GB, free: 9.949 MB / 446.1 MB
Warning: Assuming fixed start value for the following 2 variables:
         govHydroIEEE0_1.waterway.x_scaled[1]:VARIABLE(fixed = true protected = true )  \"Scaled vector x\" type: Real [1]
         govHydroIEEE0_1.GovernorB.x_scaled[1]:VARIABLE(fixed = true protected = true )  \"Scaled vector x\" type: Real [1]
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.003291/0.1914, allocations: 1.18 MB / 0.585 GB, free: 8.766 MB / 446.1 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0028/0.1942, allocations: 1.161 MB / 0.5861 GB, free: 7.551 MB / 446.1 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.02916/0.2233, allocations: 6.484 MB / 0.5925 GB, free: 1.02 MB / 446.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.087e-05/0.2233, allocations: 4 kB / 0.5925 GB, free: 1.016 MB / 446.1 MB
Notification: Performance of matching and sorting (n=216) (initialization_lambda0): time 0.008948/0.2323, allocations: 3.021 MB / 0.5954 GB, free: 13.98 MB / 462.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.358e-05/0.2323, allocations: 9.875 kB / 0.5954 GB, free: 13.97 MB / 462.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.11e-05/0.2323, allocations: 4 kB / 0.5954 GB, free: 13.96 MB / 462.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01064/0.243, allocations: 2.646 MB / 0.598 GB, free: 11.32 MB / 462.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00159/0.2445, allocations: 271.6 kB / 0.5983 GB, free: 11.05 MB / 462.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007657/0.2522, allocations: 2.686 MB / 0.6009 GB, free: 8.344 MB / 462.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00274/0.2549, allocations: 239 kB / 0.6011 GB, free: 8.109 MB / 462.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002948/0.2552, allocations: 59.98 kB / 0.6012 GB, free: 8.051 MB / 462.1 MB
Warning: Assuming fixed start value for the following 2 variables:
         govHydroIEEE0_1.waterway.x_scaled[1]:VARIABLE(fixed = true protected = true )  \"Scaled vector x\" type: Real [1]
         govHydroIEEE0_1.GovernorB.x_scaled[1]:VARIABLE(fixed = true protected = true )  \"Scaled vector x\" type: Real [1]
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 15
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (161):
 * Single equations (assignments): 160
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(30,26)}
Notification: Performance of prepare postOptimizeDAE: time 0.0005885/0.2558, allocations: 266.2 kB / 0.6014 GB, free: 7.785 MB / 462.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005662/0.2564, allocations: 166.9 kB / 0.6016 GB, free: 7.621 MB / 462.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01872/0.2751, allocations: 4.78 MB / 0.6063 GB, free: 2.816 MB / 462.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.122e-05/0.2751, allocations: 12 kB / 0.6063 GB, free: 2.805 MB / 462.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.307e-06/0.2751, allocations: 0 / 0.6063 GB, free: 2.805 MB / 462.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.695e-05/0.2752, allocations: 7.969 kB / 0.6063 GB, free: 2.797 MB / 462.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 postOpt removeSimpleEquations (simulation): time 0.02205/0.2972, allocations: 5.991 MB / 0.6121 GB, free: 12.76 MB / 478.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.525e-05/0.2973, allocations: 0 / 0.6121 GB, free: 12.76 MB / 478.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001008/0.2983, allocations: 143.8 kB / 0.6123 GB, free: 12.62 MB / 478.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.008619/0.3069, allocations: 2.14 MB / 0.6143 GB, free: 10.47 MB / 478.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0003219/0.3072, allocations: 27.98 kB / 0.6144 GB, free: 10.45 MB / 478.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02946/0.3367, allocations: 9.683 MB / 0.6238 GB, free: 0.6562 MB / 478.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.895e-06/0.3367, allocations: 0 / 0.6238 GB, free: 0.6562 MB / 478.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02141/0.3581, allocations: 6.032 MB / 0.6297 GB, free: 10.6 MB / 494.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006509/0.3587, allocations: 210.5 kB / 0.6299 GB, free: 10.39 MB / 494.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005526/0.3593, allocations: 23.94 kB / 0.6299 GB, free: 10.37 MB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002069/0.3614, allocations: 155.8 kB / 0.6301 GB, free: 10.21 MB / 494.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0009243/0.3623, allocations: 253.3 kB / 0.6303 GB, free: 9.969 MB / 494.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001521/0.3624, allocations: 35.98 kB / 0.6304 GB, free: 9.934 MB / 494.1 MB
Notification: Performance of sorting global known variables: time 0.002089/0.3645, allocations: 0.9886 MB / 0.6313 GB, free: 8.945 MB / 494.1 MB
Notification: Performance of sort global known variables: time 1.3e-07/0.3645, allocations: 0 / 0.6313 GB, free: 8.945 MB / 494.1 MB
Notification: Performance of remove unused functions: time 0.002653/0.3672, allocations: 0.8312 MB / 0.6321 GB, free: 8.113 MB / 494.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 12 (gENCLS.delta,gENCLS.eq,generator.w,generator.delta,generator.Epd,generator.Epq,generator.PSIkd,generator.PSIkq,govHydroIEEE0_1.GovernorA.TF.x_scaled[1],govHydroIEEE0_1.GovernorB.x_scaled[1],govHydroIEEE0_1.waterway.x_scaled[1],gENCLS.omega)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (80):
 * Single equations (assignments): 79
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(26,23)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0004206/0.3676, allocations: 151 kB / 0.6323 GB, free: 7.957 MB / 494.1 MB
Notification: Performance of simCode: created initialization part: time 0.01713/0.3847, allocations: 4.494 MB / 0.6367 GB, free: 3.469 MB / 494.1 MB
Notification: Performance of simCode: created event and clocks part: time 6.102e-06/0.3847, allocations: 0 / 0.6367 GB, free: 3.469 MB / 494.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.007081/0.3918, allocations: 1.837 MB / 0.6385 GB, free: 1.617 MB / 494.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002088/0.3939, allocations: 154.6 kB / 0.6386 GB, free: 1.469 MB / 494.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.006623/0.4005, allocations: 2.922 MB / 0.6415 GB, free: 14.52 MB / 0.4981 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0008849/0.4014, allocations: 0.6486 MB / 0.6421 GB, free: 13.84 MB / 0.4981 GB
Notification: Performance of simCode: alias equations: time 0.00113/0.4025, allocations: 189.6 kB / 0.6423 GB, free: 13.65 MB / 0.4981 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0005447/0.4031, allocations: 92.59 kB / 0.6424 GB, free: 13.56 MB / 0.4981 GB
Notification: Performance of SimCode: time 1.543e-06/0.4031, allocations: 3.938 kB / 0.6424 GB, free: 13.55 MB / 0.4981 GB
Notification: Performance of Templates: time 0.06094/0.464, allocations: 36.11 MB / 0.6776 GB, free: 9.406 MB / 0.5294 GB
"
[Timeout remaining time 660]
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.CGMES.TG.GovHydroIEEE0_Test.makefile [Timeout 660]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.CGMES.TG.GovHydroIEEE0_Test.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.CGMES.TG.GovHydroIEEE0_Test.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.CGMES.TG.GovHydroIEEE0_Test.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.CGMES.TG.GovHydroIEEE0_Test.sim & ./OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.CGMES.TG.GovHydroIEEE0_Test  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.CGMES.TG.GovHydroIEEE0_Test.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 7.262343280017376]
Failed to read output from testmodel.py, exit status != 0:
0.47294997423887253 0.49010395 0.457955
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f6580efd1e0>