Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Tests.FACTS.TCSC_Test.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo", uses=false) [Timeout 90]
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo): time 0.1087/0.1087, allocations: 12.77 MB / 30.85 MB, free: 6.418 MB / 26.18 MB
[Timeout remaining time 90]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 90]
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001348/0.001348, allocations: 111.5 kB / 36.84 MB, free: 1.238 MB / 26.18 MB
[Timeout remaining time 90]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 90]
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.558/1.558, allocations: 207.6 MB / 250.3 MB, free: 7.305 MB / 190.1 MB
[Timeout remaining time 88]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 90]
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: 188.5 kB / 305.5 MB, free: 484 kB / 238.1 MB
[Timeout remaining time 90]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo", uses=false) [Timeout 90]
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo): time 0.3277/0.3277, allocations: 79.73 MB / 440.2 MB, free: 12.54 MB / 318.1 MB
[Timeout remaining time 89]
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.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.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.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.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-master/package.mo)
Running command: translateModel(OpenIPSL.Tests.FACTS.TCSC_Test,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.FACTS.TCSC_Test")
translateModel(OpenIPSL.Tests.FACTS.TCSC_Test,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.FACTS.TCSC_Test") [Timeout 660]
Notification: Modelica_Synchronous 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: 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: OpenIPSL 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: Performance of FrontEnd - loaded program: time 0.004041/0.004041, allocations: 146.4 kB / 0.5867 GB, free: 9.625 MB / 398.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1586/0.1626, allocations: 72 MB / 0.657 GB, free: 1.551 MB / 462.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.FACTS.TCSC_Test): time 0.3698/0.5324, allocations: 6.08 MB / 0.6629 GB, free: 7.824 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.004507/0.5369, allocations: 1.819 MB / 0.6647 GB, free: 7.062 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001126/0.5381, allocations: 14.25 kB / 0.6647 GB, free: 7.059 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0007976/0.5389, allocations: 218.5 kB / 0.6649 GB, free: 6.945 MB / 462.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002146/0.541, allocations: 0.7385 MB / 0.6656 GB, free: 6.598 MB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001402/0.5424, allocations: 0.5476 MB / 0.6662 GB, free: 6.367 MB / 462.1 MB
Notification: Performance of NFFlatten.flatten: time 0.002101/0.5445, allocations: 1.425 MB / 0.6676 GB, free: 5.664 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0004115/0.5449, allocations: 191.9 kB / 0.6677 GB, free: 5.551 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0009855/0.5459, allocations: 0.5629 MB / 0.6683 GB, free: 5.273 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0008165/0.5467, allocations: 0.6645 MB / 0.6689 GB, free: 4.949 MB / 462.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001974/0.5469, allocations: 102.1 kB / 0.669 GB, free: 4.945 MB / 462.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.000653/0.5476, allocations: 224.2 kB / 0.6693 GB, free: 4.91 MB / 462.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0002512/0.5478, allocations: 268.8 kB / 0.6695 GB, free: 4.738 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0005189/0.5484, allocations: 0.5669 MB / 0.6701 GB, free: 4.352 MB / 462.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.001918/0.5503, allocations: 1.4 MB / 0.6714 GB, free: 3.723 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.863e-06/0.5503, allocations: 0 / 0.6714 GB, free: 3.723 MB / 462.1 MB
Notification: Performance of FrontEnd: time 1.653e-06/0.5503, allocations: 0 / 0.6714 GB, free: 3.723 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 3.179e-05/0.5503, allocations: 0.75 kB / 0.6714 GB, free: 3.723 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 157
 * Number of variables: 157
Notification: Performance of Generate backend data structure: time 0.007403/0.5577, allocations: 2.277 MB / 0.6737 GB, free: 2.594 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.036e-05/0.5578, allocations: 13.22 kB / 0.6737 GB, free: 2.59 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003509/0.5613, allocations: 0.5636 MB / 0.6742 GB, free: 2.457 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001596/0.5629, allocations: 0.7978 MB / 0.675 GB, free: 1.914 MB / 462.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002408/0.5631, allocations: 207.9 kB / 0.6752 GB, free: 1.73 MB / 462.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001299/0.5632, allocations: 68.19 kB / 0.6753 GB, free: 1.707 MB / 462.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001767/0.565, allocations: 0.8319 MB / 0.6761 GB, free: 1.395 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.016e-05/0.565, allocations: 3.938 kB / 0.6761 GB, free: 1.391 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.38e-05/0.5651, allocations: 25.91 kB / 0.6761 GB, free: 1.371 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.005e-05/0.5651, allocations: 20.19 kB / 0.6761 GB, free: 1.367 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001733/0.5669, allocations: 0.8455 MB / 0.6769 GB, free: 1.094 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 preOpt removeSimpleEquations (simulation): time 0.00624/0.5731, allocations: 3.043 MB / 0.6799 GB, free: 15.38 MB / 478.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.006416/0.5795, allocations: 1.642 MB / 0.6815 GB, free: 14.61 MB / 478.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001414/0.5809, allocations: 0.496 MB / 0.682 GB, free: 14.42 MB / 478.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 9.304e-05/0.581, allocations: 18.81 kB / 0.682 GB, free: 14.41 MB / 478.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001445/0.5825, allocations: 0.6236 MB / 0.6826 GB, free: 14.17 MB / 478.1 MB
Notification: Performance of pre-optimization done (n=74): time 3.296e-06/0.5825, allocations: 0 / 0.6826 GB, free: 14.17 MB / 478.1 MB
Notification: Performance of matching and sorting (n=74): time 0.02013/0.6026, allocations: 4.68 MB / 0.6872 GB, free: 12.25 MB / 478.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.131e-05/0.6027, allocations: 60.16 kB / 0.6873 GB, free: 12.18 MB / 478.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001887/0.6046, allocations: 0.9711 MB / 0.6882 GB, free: 11.63 MB / 478.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.000248/0.6048, allocations: 37.05 kB / 0.6882 GB, free: 11.59 MB / 478.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004553/0.6053, allocations: 479.7 kB / 0.6887 GB, free: 11.17 MB / 478.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0003871/0.6056, allocations: 278.8 kB / 0.689 GB, free: 10.93 MB / 478.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004256/0.6061, allocations: 182.8 kB / 0.6891 GB, free: 10.81 MB / 478.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001742/0.6062, allocations: 343.2 kB / 0.6895 GB, free: 10.48 MB / 478.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001331/0.6076, allocations: 0.5491 MB / 0.69 GB, free: 10.21 MB / 478.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001444/0.609, allocations: 0.6706 MB / 0.6907 GB, free: 9.809 MB / 478.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01797/0.627, allocations: 4.042 MB / 0.6946 GB, free: 7.809 MB / 478.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.707e-05/0.627, allocations: 5.625 kB / 0.6946 GB, free: 7.805 MB / 478.1 MB
Notification: Performance of matching and sorting (n=125) (initialization): time 0.009544/0.6366, allocations: 2.821 MB / 0.6974 GB, free: 6.391 MB / 478.1 MB
Notification: Performance of prepare postOptimizeDAE: time 3.375e-05/0.6366, allocations: 6.281 kB / 0.6974 GB, free: 6.391 MB / 478.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.599e-05/0.6366, allocations: 2.5 kB / 0.6974 GB, free: 6.391 MB / 478.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.004961/0.6416, allocations: 1.267 MB / 0.6986 GB, free: 5.828 MB / 478.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001172/0.6427, allocations: 187 kB / 0.6988 GB, free: 5.785 MB / 478.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01354/0.6563, allocations: 5.691 MB / 0.7043 GB, free: 1.945 MB / 478.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001852/0.6581, allocations: 153.3 kB / 0.7045 GB, free: 1.855 MB / 478.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001191/0.6583, allocations: 33.36 kB / 0.7045 GB, free: 1.848 MB / 478.1 MB
Warning: Assuming fixed start value for the following 1 variables:
         Gen1.delta:VARIABLE(flow=false start = Gen1.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 10
 * Number of states: 0 ()
 * Number of discrete variables: 2 ($whenCondition2,$whenCondition1)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (103):
 * Single equations (assignments): 101
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 1 system
   {(2,100.0%)}
 * Non-linear Jacobian (size): 0 systems
 * Without analytic Jacobian (size): 0 systems
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
   {(14,8)}
Notification: Performance of prepare postOptimizeDAE: time 0.0006044/0.6589, allocations: 240.9 kB / 0.7048 GB, free: 1.648 MB / 478.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002479/0.6591, allocations: 119.5 kB / 0.7049 GB, free: 1.582 MB / 478.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.008914/0.668, allocations: 2.636 MB / 0.7074 GB, free: 15.52 MB / 494.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.761e-05/0.668, allocations: 8 kB / 0.7075 GB, free: 15.51 MB / 494.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.404e-06/0.668, allocations: 1.125 kB / 0.7075 GB, free: 15.51 MB / 494.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.324e-05/0.6681, allocations: 3.984 kB / 0.7075 GB, free: 15.5 MB / 494.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0113/0.6794, allocations: 4 MB / 0.7114 GB, free: 11.39 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.122e-05/0.6794, allocations: 0 / 0.7114 GB, free: 11.39 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0006204/0.68, allocations: 98.7 kB / 0.7115 GB, free: 11.29 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.004962/0.685, allocations: 1.322 MB / 0.7127 GB, free: 9.973 MB / 494.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001832/0.6851, allocations: 19.98 kB / 0.7128 GB, free: 9.953 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01368/0.6988, allocations: 6.011 MB / 0.7186 GB, free: 3.801 MB / 494.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.899e-06/0.6988, allocations: 3.984 kB / 0.7186 GB, free: 3.797 MB / 494.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01042/0.7092, allocations: 3.354 MB / 0.7219 GB, free: 424 kB / 494.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006127/0.7099, allocations: 212.2 kB / 0.7221 GB, free: 192 kB / 494.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001865/0.71, allocations: 15.94 kB / 0.7221 GB, free: 180 kB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009854/0.711, allocations: 79.91 kB / 0.7222 GB, free: 100 kB / 494.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005621/0.7116, allocations: 164.4 kB / 0.7224 GB, free: 15.94 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.619e-05/0.7117, allocations: 23.94 kB / 0.7224 GB, free: 15.91 MB / 0.4981 GB
Notification: Performance of sorting global known variables: time 0.001124/0.7128, allocations: 0.6553 MB / 0.723 GB, free: 15.25 MB / 0.4981 GB
Notification: Performance of sort global known variables: time 1e-07/0.7128, allocations: 4 kB / 0.723 GB, free: 15.25 MB / 0.4981 GB
Notification: Performance of remove unused functions: time 0.001364/0.7141, allocations: 347.8 kB / 0.7234 GB, free: 14.91 MB / 0.4981 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 4 (tCSC.PIcontroller.x_scaled[1],tCSC.X1.state,Gen1.delta,Gen1.w)
 * Number of discrete variables: 2 ($whenCondition1,$whenCondition2)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (48):
 * Single equations (assignments): 46
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 1 system
   {(2,100.0%)}
 * Non-linear Jacobian (size): 0 systems
 * Without analytic Jacobian (size): 0 systems
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
   {(15,13)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0003193/0.7145, allocations: 131 kB / 0.7235 GB, free: 14.77 MB / 0.4981 GB
Notification: Performance of simCode: created initialization part: time 0.005367/0.7198, allocations: 1.792 MB / 0.7252 GB, free: 12.95 MB / 0.4981 GB
Notification: Performance of simCode: created event and clocks part: time 5e-06/0.7198, allocations: 4 kB / 0.7252 GB, free: 12.94 MB / 0.4981 GB
Notification: Performance of simCode: created simulation system equations: time 0.003546/0.7234, allocations: 1.168 MB / 0.7264 GB, free: 11.73 MB / 0.4981 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001042/0.7244, allocations: 92.41 kB / 0.7265 GB, free: 11.64 MB / 0.4981 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003996/0.7284, allocations: 1.861 MB / 0.7283 GB, free: 9.762 MB / 0.4981 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007728/0.7292, allocations: 1.046 MB / 0.7293 GB, free: 8.691 MB / 0.4981 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003282/0.7295, allocations: 75.42 kB / 0.7294 GB, free: 8.617 MB / 0.4981 GB
Notification: Performance of SimCode: time 1.322e-06/0.7295, allocations: 0 / 0.7294 GB, free: 8.617 MB / 0.4981 GB
Notification: Performance of Templates: time 0.03142/0.7609, allocations: 22.01 MB / 0.7509 GB, free: 2.582 MB / 0.5137 GB
[Timeout remaining time 659]
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Tests.FACTS.TCSC_Test.makefile [Timeout 660]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Tests.FACTS.TCSC_Test.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Tests.FACTS.TCSC_Test.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Tests.FACTS.TCSC_Test.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Tests.FACTS.TCSC_Test.sim & ./OpenIPSL_2.0.0_OpenIPSL.Tests.FACTS.TCSC_Test  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Tests.FACTS.TCSC_Test.pipe 2>&1) [Timeout 480]