Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_TG.conf.json
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/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo", uses=false)
Using package OpenIPSL with version 3.1.0-dev (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/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 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)
Running command: translateModel(OpenIPSL.Tests.Machines.PSAT.Order4test2_TG,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_TG")
translateModel(OpenIPSL.Tests.Machines.PSAT.Order4test2_TG,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_TG")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.0008914/0.0008914, allocations: 175.4 kB / 16.08 MB, free: 5.727 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.263/1.263, allocations: 222.9 MB / 239.7 MB, free: 10.17 MB / 202.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0007069/0.0007069, allocations: 89.44 kB / 290.5 MB, free: 11 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.418/0.418, allocations: 78.42 MB / 419.6 MB, free: 9.715 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004149/0.0004149, allocations: 8.219 kB / 499.1 MB, free: 10.65 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2513/0.2517, allocations: 68.75 MB / 0.5545 GB, free: 20.21 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Machines.PSAT.Order4test2_TG): time 0.004848/0.2566, allocations: 6.711 MB / 0.5611 GB, free: 13.47 MB / 378.7 MB
Notification: Performance of NFInst.instExpressions: time 0.002701/0.2593, allocations: 1.993 MB / 0.563 GB, free: 11.48 MB / 378.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0004959/0.2598, allocations: 15.94 kB / 0.563 GB, free: 11.46 MB / 378.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0004929/0.2603, allocations: 182.8 kB / 0.5632 GB, free: 11.28 MB / 378.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.001157/0.2615, allocations: 0.6455 MB / 0.5638 GB, free: 10.64 MB / 378.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/Electrical/Machines/PSAT/Order4.mo:25:3-30:9:writable] Warning: In relation Generator.xq <> Generator.x1q, <> on Real numbers is only allowed inside functions.
Notification: Performance of NFTyping.typeClassSections: time 0.001014/0.2625, allocations: 0.6148 MB / 0.5644 GB, free: 10.03 MB / 378.7 MB
Notification: Performance of NFFlatten.flatten: time 0.0008181/0.2634, allocations: 1.389 MB / 0.5658 GB, free: 8.629 MB / 378.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002906/0.2637, allocations: 279.4 kB / 0.5661 GB, free: 8.348 MB / 378.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0004174/0.2641, allocations: 0.5209 MB / 0.5666 GB, free: 7.824 MB / 378.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0004458/0.2645, allocations: 0.6569 MB / 0.5672 GB, free: 7.164 MB / 378.7 MB
Notification: Performance of NFPackage.collectConstants: time 9.612e-05/0.2646, allocations: 52 kB / 0.5673 GB, free: 7.113 MB / 378.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0003085/0.265, allocations: 183.8 kB / 0.5674 GB, free: 6.934 MB / 378.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0001369/0.2651, allocations: 230.8 kB / 0.5676 GB, free: 6.707 MB / 378.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0002829/0.2654, allocations: 0.5511 MB / 0.5682 GB, free: 6.152 MB / 378.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.0007896/0.2662, allocations: 1.443 MB / 0.5696 GB, free: 4.703 MB / 378.7 MB
Notification: Performance of FrontEnd - DAE generated: time 3.597e-06/0.2662, allocations: 0 / 0.5696 GB, free: 4.703 MB / 378.7 MB
Notification: Performance of FrontEnd: time 1.132e-06/0.2662, allocations: 0 / 0.5696 GB, free: 4.703 MB / 378.7 MB
Notification: Performance of Transformations before backend: time 1.072e-05/0.2662, allocations: 0 / 0.5696 GB, free: 4.703 MB / 378.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 187
 * Number of variables: 187
Notification: Performance of Generate backend data structure: time 0.006266/0.2725, allocations: 2.311 MB / 0.5719 GB, free: 2.34 MB / 378.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.601e-05/0.2725, allocations: 8.031 kB / 0.5719 GB, free: 2.332 MB / 378.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004377/0.2769, allocations: 0.771 MB / 0.5726 GB, free: 1.559 MB / 378.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001146/0.2781, allocations: 0.8188 MB / 0.5734 GB, free: 0.6836 MB / 378.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001886/0.2783, allocations: 252.1 kB / 0.5737 GB, free: 408 kB / 378.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001175/0.2784, allocations: 76 kB / 0.5737 GB, free: 332 kB / 378.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001998/0.2804, allocations: 1.122 MB / 0.5748 GB, free: 15.19 MB / 394.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.336e-05/0.2805, allocations: 3.938 kB / 0.5748 GB, free: 15.18 MB / 394.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.583e-05/0.2805, allocations: 36 kB / 0.5749 GB, free: 15.15 MB / 394.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.335e-05/0.2806, allocations: 16 kB / 0.5749 GB, free: 15.13 MB / 394.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002112/0.2827, allocations: 1.07 MB / 0.5759 GB, free: 14.06 MB / 394.7 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.005284/0.288, allocations: 3.474 MB / 0.5793 GB, free: 10.44 MB / 394.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002046/0.29, allocations: 1.443 MB / 0.5807 GB, free: 8.883 MB / 394.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001371/0.2914, allocations: 0.7422 MB / 0.5814 GB, free: 8.133 MB / 394.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001053/0.2915, allocations: 16.98 kB / 0.5815 GB, free: 8.117 MB / 394.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.276e-05/0.2916, allocations: 43.56 kB / 0.5815 GB, free: 8.062 MB / 394.7 MB
Notification: Performance of pre-optimization done (n=90): time 2.955e-06/0.2916, allocations: 0 / 0.5815 GB, free: 8.062 MB / 394.7 MB
Notification: Performance of matching and sorting (n=90): time 0.02299/0.3146, allocations: 6.496 MB / 0.5878 GB, free: 1.535 MB / 394.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.497e-05/0.3147, allocations: 86.11 kB / 0.5879 GB, free: 1.434 MB / 394.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001276/0.316, allocations: 0.9416 MB / 0.5888 GB, free: 484 kB / 394.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001289/0.3161, allocations: 41.7 kB / 0.5889 GB, free: 436 kB / 394.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.000331/0.3164, allocations: 480.5 kB / 0.5893 GB, free: 15.95 MB / 410.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0002873/0.3167, allocations: 327.3 kB / 0.5897 GB, free: 15.62 MB / 410.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001427/0.3169, allocations: 105.7 kB / 0.5898 GB, free: 15.52 MB / 410.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001163/0.317, allocations: 349.9 kB / 0.5901 GB, free: 15.17 MB / 410.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001436/0.3184, allocations: 0.7014 MB / 0.5908 GB, free: 14.46 MB / 410.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001484/0.3199, allocations: 0.8384 MB / 0.5916 GB, free: 13.59 MB / 410.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01702/0.337, allocations: 4.717 MB / 0.5962 GB, free: 8.848 MB / 410.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.514e-06/0.337, allocations: 3.938 kB / 0.5962 GB, free: 8.844 MB / 410.7 MB
Notification: Performance of matching and sorting (n=145) (initialization): time 0.01284/0.3499, allocations: 4.222 MB / 0.6003 GB, free: 4.605 MB / 410.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001101/0.35, allocations: 151.3 kB / 0.6005 GB, free: 4.43 MB / 410.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.157e-05/0.35, allocations: 7.938 kB / 0.6005 GB, free: 4.422 MB / 410.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.00575/0.3558, allocations: 1.718 MB / 0.6022 GB, free: 2.699 MB / 410.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009901/0.3568, allocations: 203.7 kB / 0.6024 GB, free: 2.5 MB / 410.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01847/0.3753, allocations: 8.335 MB / 0.6105 GB, free: 10.02 MB / 426.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001403/0.3767, allocations: 178.8 kB / 0.6107 GB, free: 9.848 MB / 426.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.807e-05/0.3768, allocations: 43.98 kB / 0.6107 GB, free: 9.805 MB / 426.7 MB
Notification: Performance of prepare postOptimizeDAE: time 8.676e-06/0.3768, allocations: 4 kB / 0.6107 GB, free: 9.801 MB / 426.7 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.01272/0.3895, allocations: 4.066 MB / 0.6147 GB, free: 5.727 MB / 426.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.894e-05/0.3896, allocations: 0 / 0.6147 GB, free: 5.727 MB / 426.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.00572/0.3953, allocations: 1.722 MB / 0.6164 GB, free: 4 MB / 426.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009826/0.3963, allocations: 203.7 kB / 0.6166 GB, free: 3.801 MB / 426.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01833/0.4147, allocations: 8.33 MB / 0.6247 GB, free: 11.32 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001383/0.4161, allocations: 159.2 kB / 0.6248 GB, free: 11.17 MB / 442.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001524/0.4163, allocations: 39.98 kB / 0.6249 GB, free: 11.13 MB / 442.7 MB
Warning: Assuming fixed start value for the following 2 variables:
         Generator.e1q:VARIABLE(start = Generator.e1q0 unit = "1" fixed = true )  "q-axis transient voltage" type: Real
         TGTypeII1.transferFunction1.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: 9
 * 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 (111):
 * Single equations (assignments): 110
 * 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
   {(24,11)}
Notification: Performance of prepare postOptimizeDAE: time 0.0005606/0.4168, allocations: 320.9 kB / 0.6252 GB, free: 10.8 MB / 442.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002666/0.4171, allocations: 126.4 kB / 0.6253 GB, free: 10.68 MB / 442.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0124/0.4295, allocations: 3.936 MB / 0.6291 GB, free: 6.73 MB / 442.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.036e-05/0.4296, allocations: 3.984 kB / 0.6292 GB, free: 6.727 MB / 442.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.36e-06/0.4296, allocations: 0 / 0.6292 GB, free: 6.727 MB / 442.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003564/0.4299, allocations: 207.4 kB / 0.6294 GB, free: 6.504 MB / 442.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001768/0.4301, allocations: 16 kB / 0.6294 GB, free: 6.488 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001009/0.4311, allocations: 111.8 kB / 0.6295 GB, free: 6.379 MB / 442.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001111/0.4323, allocations: 472.1 kB / 0.6299 GB, free: 5.918 MB / 442.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.005396/0.4377, allocations: 2.017 MB / 0.6319 GB, free: 3.887 MB / 442.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.004322/0.442, allocations: 2.216 MB / 0.6341 GB, free: 1.652 MB / 442.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001264/0.4433, allocations: 0.5778 MB / 0.6346 GB, free: 1.074 MB / 442.7 MB
Notification: Performance of sorting global known variables: time 0.001026/0.4443, allocations: 0.6391 MB / 0.6352 GB, free: 436 kB / 442.7 MB
Notification: Performance of Backend: time 1.092e-06/0.4444, allocations: 0 / 0.6352 GB, free: 436 kB / 442.7 MB
Notification: Performance of simCode: created initialization part: time 0.01024/0.4546, allocations: 3.575 MB / 0.6387 GB, free: 12.82 MB / 458.7 MB
Notification: Performance of SimCode: time 0.003904/0.4585, allocations: 3.024 MB / 0.6417 GB, free: 9.746 MB / 458.7 MB
Notification: Performance of Templates: time 0.043/0.5016, allocations: 29.46 MB / 0.6705 GB, free: 12.29 MB / 490.7 MB
make -j1 -f OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_TG.makefile
(rm -f OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_TG.pipe ; mkfifo OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_TG.pipe ; head -c 1048576 < OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_TG.pipe >> ../files/OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_TG.sim & ./OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_TG -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_TG.pipe 2>&1)