Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.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_AVR,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR")
translateModel(OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.0008409/0.0008409, allocations: 174.7 kB / 16.08 MB, free: 5.723 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.262/1.262, allocations: 222.9 MB / 239.7 MB, free: 10.19 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.000753/0.0007529, allocations: 102.5 kB / 290.5 MB, free: 11.04 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.4194/0.4194, allocations: 78.42 MB / 419.6 MB, free: 9.793 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003489/0.0003489, allocations: 12.28 kB / 499.1 MB, free: 10.71 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2475/0.2478, allocations: 68.76 MB / 0.5545 GB, free: 20.25 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR): time 0.005227/0.2531, allocations: 6.457 MB / 0.5608 GB, free: 13.75 MB / 378.7 MB
Notification: Performance of NFInst.instExpressions: time 0.002869/0.256, allocations: 1.919 MB / 0.5627 GB, free: 11.83 MB / 378.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0003657/0.2564, allocations: 15.94 kB / 0.5627 GB, free: 11.82 MB / 378.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0003472/0.2567, allocations: 159 kB / 0.5629 GB, free: 11.66 MB / 378.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.001129/0.2579, allocations: 0.6255 MB / 0.5635 GB, free: 11.03 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 order4_Inputs_Outputs.xq <> order4_Inputs_Outputs.x1q, <> on Real numbers is only allowed inside functions.
Notification: Performance of NFTyping.typeClassSections: time 0.0009498/0.2588, allocations: 0.591 MB / 0.5641 GB, free: 10.45 MB / 378.7 MB
Notification: Performance of NFFlatten.flatten: time 0.0007472/0.2596, allocations: 1.292 MB / 0.5653 GB, free: 9.148 MB / 378.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002767/0.2599, allocations: 255.7 kB / 0.5656 GB, free: 8.887 MB / 378.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0003852/0.2603, allocations: 0.4937 MB / 0.566 GB, free: 8.391 MB / 378.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0004204/0.2607, allocations: 0.6297 MB / 0.5667 GB, free: 7.758 MB / 378.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001046/0.2608, allocations: 52 kB / 0.5667 GB, free: 7.707 MB / 378.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0003094/0.2611, allocations: 187.8 kB / 0.5669 GB, free: 7.523 MB / 378.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0001176/0.2613, allocations: 206.9 kB / 0.5671 GB, free: 7.32 MB / 378.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.000271/0.2615, allocations: 0.5317 MB / 0.5676 GB, free: 6.785 MB / 378.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.0007476/0.2623, allocations: 1.368 MB / 0.5689 GB, free: 5.414 MB / 378.7 MB
Notification: Performance of FrontEnd - DAE generated: time 3.857e-06/0.2623, allocations: 4 kB / 0.5689 GB, free: 5.41 MB / 378.7 MB
Notification: Performance of FrontEnd: time 1.403e-06/0.2623, allocations: 0 / 0.5689 GB, free: 5.41 MB / 378.7 MB
Notification: Performance of Transformations before backend: time 1.085e-05/0.2623, allocations: 0 / 0.5689 GB, free: 5.41 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: 179
 * Number of variables: 179
Notification: Performance of Generate backend data structure: time 0.006164/0.2685, allocations: 2.212 MB / 0.5711 GB, free: 3.145 MB / 378.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.379e-05/0.2685, allocations: 12.03 kB / 0.5711 GB, free: 3.133 MB / 378.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004586/0.2731, allocations: 0.7711 MB / 0.5719 GB, free: 2.359 MB / 378.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0007989/0.2739, allocations: 0.7244 MB / 0.5726 GB, free: 1.594 MB / 378.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000192/0.2741, allocations: 256.2 kB / 0.5728 GB, free: 1.305 MB / 378.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001247/0.2743, allocations: 75.91 kB / 0.5729 GB, free: 1.23 MB / 378.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.00172/0.276, allocations: 1.11 MB / 0.574 GB, free: 112 kB / 378.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.381e-05/0.276, allocations: 0 / 0.574 GB, free: 112 kB / 378.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 7.742e-05/0.2761, allocations: 24 kB / 0.574 GB, free: 88 kB / 378.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.129e-05/0.2761, allocations: 20 kB / 0.574 GB, free: 68 kB / 378.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002053/0.2782, allocations: 1.023 MB / 0.575 GB, free: 15.04 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.005665/0.2839, allocations: 3.465 MB / 0.5784 GB, free: 11.42 MB / 394.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002239/0.2861, allocations: 1.442 MB / 0.5798 GB, free: 9.867 MB / 394.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001433/0.2876, allocations: 0.7417 MB / 0.5805 GB, free: 9.121 MB / 394.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001095/0.2877, allocations: 13 kB / 0.5805 GB, free: 9.105 MB / 394.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.557e-05/0.2877, allocations: 43.56 kB / 0.5806 GB, free: 9.055 MB / 394.7 MB
Notification: Performance of pre-optimization done (n=90): time 2.334e-06/0.2877, allocations: 0 / 0.5806 GB, free: 9.055 MB / 394.7 MB
Notification: Performance of matching and sorting (n=90): time 0.02598/0.3137, allocations: 6.906 MB / 0.5873 GB, free: 2.117 MB / 394.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.486e-05/0.3138, allocations: 86.11 kB / 0.5874 GB, free: 2.012 MB / 394.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0009856/0.3148, allocations: 0.8241 MB / 0.5882 GB, free: 1.164 MB / 394.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001003/0.3149, allocations: 41.7 kB / 0.5883 GB, free: 1.117 MB / 394.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0003156/0.3152, allocations: 452.6 kB / 0.5887 GB, free: 0.668 MB / 394.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0002829/0.3155, allocations: 327.4 kB / 0.589 GB, free: 352 kB / 394.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001491/0.3157, allocations: 105.7 kB / 0.5891 GB, free: 240 kB / 394.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001274/0.3158, allocations: 345.9 kB / 0.5894 GB, free: 15.89 MB / 410.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001484/0.3173, allocations: 0.7014 MB / 0.5901 GB, free: 15.19 MB / 410.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00155/0.3189, allocations: 0.8431 MB / 0.5909 GB, free: 14.31 MB / 410.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002959/0.3218, allocations: 1.521 MB / 0.5924 GB, free: 12.76 MB / 410.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.611e-06/0.3218, allocations: 4 kB / 0.5924 GB, free: 12.76 MB / 410.7 MB
Notification: Performance of matching and sorting (n=146) (initialization): time 0.01318/0.335, allocations: 4.234 MB / 0.5966 GB, free: 8.508 MB / 410.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001199/0.3352, allocations: 164.2 kB / 0.5967 GB, free: 8.316 MB / 410.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.325e-05/0.3352, allocations: 4 kB / 0.5967 GB, free: 8.312 MB / 410.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006005/0.3412, allocations: 1.776 MB / 0.5985 GB, free: 6.531 MB / 410.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008771/0.3421, allocations: 195.7 kB / 0.5986 GB, free: 6.34 MB / 410.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01913/0.3612, allocations: 8.565 MB / 0.607 GB, free: 13.63 MB / 426.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00127/0.3625, allocations: 170.9 kB / 0.6072 GB, free: 13.46 MB / 426.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.963e-05/0.3626, allocations: 39.98 kB / 0.6072 GB, free: 13.43 MB / 426.7 MB
Notification: Performance of prepare postOptimizeDAE: time 9.167e-06/0.3626, allocations: 4 kB / 0.6072 GB, free: 13.42 MB / 426.7 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.01267/0.3753, allocations: 4.097 MB / 0.6112 GB, free: 9.316 MB / 426.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.259e-05/0.3754, allocations: 4 kB / 0.6112 GB, free: 9.312 MB / 426.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005808/0.3812, allocations: 1.792 MB / 0.613 GB, free: 7.516 MB / 426.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.000817/0.382, allocations: 183.7 kB / 0.6131 GB, free: 7.336 MB / 426.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01871/0.4007, allocations: 8.542 MB / 0.6215 GB, free: 14.66 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001175/0.4019, allocations: 151.2 kB / 0.6216 GB, free: 14.51 MB / 442.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.663e-05/0.402, allocations: 39.98 kB / 0.6217 GB, free: 14.47 MB / 442.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 10
 * 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 (110):
 * Single equations (assignments): 109
 * 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
   {(25,12)}
Notification: Performance of prepare postOptimizeDAE: time 0.0004362/0.4025, allocations: 308.8 kB / 0.622 GB, free: 14.16 MB / 442.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001878/0.4026, allocations: 106.7 kB / 0.6221 GB, free: 14.05 MB / 442.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01196/0.4146, allocations: 3.911 MB / 0.6259 GB, free: 10.12 MB / 442.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.873e-05/0.4147, allocations: 7.922 kB / 0.6259 GB, free: 10.12 MB / 442.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.256e-06/0.4147, allocations: 4 kB / 0.6259 GB, free: 10.11 MB / 442.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002507/0.4149, allocations: 187.6 kB / 0.6261 GB, free: 9.91 MB / 442.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001564/0.4151, allocations: 16 kB / 0.6261 GB, free: 9.895 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0008986/0.416, allocations: 95.89 kB / 0.6262 GB, free: 9.801 MB / 442.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001083/0.4171, allocations: 480 kB / 0.6266 GB, free: 9.332 MB / 442.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.005406/0.4225, allocations: 2.016 MB / 0.6286 GB, free: 7.301 MB / 442.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.004073/0.4266, allocations: 2.175 MB / 0.6307 GB, free: 5.113 MB / 442.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001216/0.4278, allocations: 0.5738 MB / 0.6313 GB, free: 4.539 MB / 442.7 MB
Notification: Performance of sorting global known variables: time 0.0006532/0.4285, allocations: 0.5511 MB / 0.6318 GB, free: 3.977 MB / 442.7 MB
Notification: Performance of Backend: time 4.1e-07/0.4285, allocations: 0 / 0.6318 GB, free: 3.977 MB / 442.7 MB
Notification: Performance of simCode: created initialization part: time 0.01009/0.4386, allocations: 3.644 MB / 0.6354 GB, free: 308 kB / 442.7 MB
Notification: Performance of SimCode: time 0.003238/0.4419, allocations: 2.914 MB / 0.6382 GB, free: 13.33 MB / 458.7 MB
Notification: Performance of Templates: time 0.03956/0.4814, allocations: 29.51 MB / 0.6671 GB, free: 15.84 MB / 490.7 MB
make -j1 -f OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.makefile
(rm -f OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.pipe ; mkfifo OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.pipe ; head -c 1048576 < OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.pipe >> ../files/OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.sim & ./OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_release_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.pipe 2>&1)