Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Tests.FACTS.PSAT.tcsc_alpha_4bus.conf.json
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.00162/0.00162, allocations: 76.2 kB / 9.873 MB, free: 0.8203 MB / 10.45 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.002525/0.004146, allocations: 444.3 kB / 10.31 MB, free: 396 kB / 10.45 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.579/1.579, allocations: 177.1 MB / 188.2 MB, free: 4.477 MB / 186.7 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.2104/1.789, allocations: 53.64 MB / 241.8 MB, free: 2.711 MB / 234.7 MB
"
[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.001219/0.001219, allocations: 157.1 kB / 242.7 MB, free: 1.84 MB / 234.7 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.001654/0.002873, allocations: 474.2 kB / 243.2 MB, free: 1.391 MB / 234.7 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo): time 0.6918/0.6918, allocations: 84.66 MB / 328.6 MB, free: 1.637 MB / 314.7 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.06526/0.757, allocations: 28.73 MB / 357.3 MB, free: 8.809 MB / 346.7 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.2.0-dev (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/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 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+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)
Running command: translateModel(OpenIPSL.Tests.FACTS.PSAT.tcsc_alpha_4bus,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.FACTS.PSAT.tcsc_alpha_4bus")
translateModel(OpenIPSL.Tests.FACTS.PSAT.tcsc_alpha_4bus,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.FACTS.PSAT.tcsc_alpha_4bus") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.646e-05/2.646e-05, allocations: 4.703 kB / 358.6 MB, free: 7.562 MB / 346.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.FACTS.PSAT.tcsc_alpha_4bus): time 0.009861/0.009887, allocations: 6.387 MB / 365 MB, free: 1.172 MB / 346.7 MB
Notification: Performance of NFInst.instExpressions: time 0.007042/0.01693, allocations: 2.49 MB / 367.5 MB, free: 14.68 MB / 362.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001191/0.01812, allocations: 35.81 kB / 367.5 MB, free: 14.64 MB / 362.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.000849/0.01897, allocations: 237.9 kB / 367.8 MB, free: 14.41 MB / 362.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.003212/0.02218, allocations: 0.9708 MB / 368.7 MB, free: 13.43 MB / 362.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001511/0.02369, allocations: 0.5806 MB / 369.3 MB, free: 12.85 MB / 362.7 MB
Notification: Performance of NFFlatten.flatten: time 0.00303/0.02672, allocations: 1.967 MB / 371.3 MB, free: 10.88 MB / 362.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0005211/0.02724, allocations: 201 kB / 371.5 MB, free: 10.66 MB / 362.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.02991/0.05715, allocations: 18.62 MB / 390.1 MB, free: 8 MB / 378.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001328/0.05848, allocations: 0.5958 MB / 390.7 MB, free: 7.402 MB / 378.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003049/0.05879, allocations: 116 kB / 390.8 MB, free: 7.289 MB / 378.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0008422/0.05963, allocations: 291.7 kB / 391.1 MB, free: 7.004 MB / 378.7 MB
Notification: Performance of combineBinaries: time 0.002685/0.06231, allocations: 2.341 MB / 393.4 MB, free: 4.648 MB / 378.7 MB
Notification: Performance of replaceArrayConstructors: time 0.001571/0.06389, allocations: 1.363 MB / 394.8 MB, free: 3.273 MB / 378.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0007399/0.06463, allocations: 80 kB / 394.9 MB, free: 3.195 MB / 378.7 MB
Notification: Performance of FrontEnd: time 0.0003081/0.06493, allocations: 39.88 kB / 394.9 MB, free: 3.156 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 (171)
 * Number of variables: 179 (179)
Notification: Performance of [SIM] Bindings: time 0.009463/0.0744, allocations: 6.799 MB / 401.7 MB, free: 12.11 MB / 394.7 MB
Notification: Performance of [SIM] FunctionAlias: time 0.003698/0.07809, allocations: 1.536 MB / 403.2 MB, free: 10.46 MB / 394.7 MB
Notification: Performance of [SIM] Early Inline: time 0.01132/0.08941, allocations: 8.054 MB / 411.3 MB, free: 2.277 MB / 394.7 MB
Notification: Performance of [SIM] Simplify: time 0.0013/0.09071, allocations: 0.7015 MB / 412 MB, free: 1.508 MB / 394.7 MB
Notification: Performance of [SIM] Alias: time 0.01306/0.1038, allocations: 8.226 MB / 420.2 MB, free: 8.742 MB / 410.7 MB
Notification: Performance of [SIM] Remove Stream: time 0.000583/0.1044, allocations: 421.9 kB / 420.6 MB, free: 8.285 MB / 410.7 MB
Notification: Performance of [SIM] Algorithm Locals: time 0.001268/0.1056, allocations: 0.8553 MB / 421.5 MB, free: 7.402 MB / 410.7 MB
Notification: Performance of [SIM] Detect States: time 0.001912/0.1075, allocations: 1.17 MB / 422.7 MB, free: 6.152 MB / 410.7 MB
Notification: Performance of [SIM] Events: time 0.000989/0.1085, allocations: 0.7523 MB / 423.4 MB, free: 5.398 MB / 410.7 MB
Notification: Performance of [SIM] Partitioning: time 0.003037/0.1116, allocations: 2.01 MB / 425.4 MB, free: 3.281 MB / 410.7 MB
Notification: Performance of [SIM] Causalize: time 0.004581/0.1161, allocations: 2.786 MB / 428.2 MB, free: 412 kB / 410.7 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.005261/0.1214, allocations: 4.037 MB / 432.2 MB, free: 12.36 MB / 426.7 MB
Notification: Performance of [INI] Simplify: time 0.007185/0.1286, allocations: 3.639 MB / 435.9 MB, free: 8.477 MB / 426.7 MB
Notification: Performance of [INI] Inline: time 0.01194/0.1405, allocations: 7.935 MB / 443.8 MB, free: 0.5117 MB / 426.7 MB
Notification: Performance of [INI] Partitioning: time 0.0007148/0.1412, allocations: 117.7 kB / 443.9 MB, free: 364 kB / 426.7 MB
Notification: Performance of [INI] Cleanup: time 0.001545/0.1428, allocations: 1.07 MB / 445 MB, free: 15.28 MB / 442.7 MB
Notification: Performance of [INI] Causalize: time 0.01567/0.1585, allocations: 8.201 MB / 453.2 MB, free: 6.402 MB / 442.7 MB
Notification: Performance of [INI] Tearing: time 0.00361/0.1621, allocations: 2.398 MB / 455.6 MB, free: 4.07 MB / 442.7 MB
Notification: Performance of [SIM] Initialization: time 2.305e-06/0.1621, allocations: 4 kB / 455.6 MB, free: 4.066 MB / 442.7 MB
Notification: Performance of [SIM] Remove Dummies: time 2.613e-05/0.1621, allocations: 5.25 kB / 455.6 MB, free: 4.062 MB / 442.7 MB
Notification: Performance of [SIM] Tearing: time 0.003108/0.1652, allocations: 1.93 MB / 457.5 MB, free: 2.184 MB / 442.7 MB
Notification: Performance of [SIM] Categorize: time 0.002187/0.1674, allocations: 0.9298 MB / 458.5 MB, free: 1.207 MB / 442.7 MB
Notification: Performance of [SIM] Solve: time 0.003628/0.171, allocations: 1.926 MB / 460.4 MB, free: 15.27 MB / 458.7 MB
Notification: Performance of [SIM] Jacobian: time 0.01327/0.1843, allocations: 8.354 MB / 468.8 MB, free: 6.492 MB / 458.7 MB
Notification: Performance of [SIM] Minimize Homotopy System: time 1.794e-06/0.1843, allocations: 0 / 468.8 MB, free: 6.492 MB / 458.7 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 1
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 0
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 0
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 11 (11) {tcsc.PIcontroller.x_scaled, tcsc.lagLimWindupGuard.state, machine.e1q, machine.e2q, machine.delta, machine.w, avr.lagLimWindupGuard.state, avr.vm, avr.vr, machine.e1d, machine.e2d}
 * Number of discrete states: .................... 4 (4) {avr.lagLimWindupGuard.atMin, avr.lagLimWindupGuard.atMax, tcsc.lagLimWindupGuard.atMin, tcsc.lagLimWindupGuard.atMax}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 37 (37) {tcsc.lagLimWindupGuard.stateHigh, tcsc.lagLimWindupGuard.inputAbove, tcsc.lagLimWindupGuard.inputHigh, tcsc.lagLimWindupGuard.stateLow, tcsc.lagLimWindupGuard.inputBelow, tcsc.lagLimWindupGuard.inputLow, avr.lagLimWindupGuard.stateHigh, avr.lagLimWindupGuard.inputAbove, avr.lagLimWindupGuard.inputHigh, avr.lagLimWindupGuard.stateLow, avr.lagLimWindupGuard.inputBelow, avr.lagLimWindupGuard.inputLow, $WC_7, $WC_6, $WC_5, $WC_4, $WC_3, $WC_2, $WC_1, $WC_0, $SEV_16, $SEV_15, $SEV_14, $SEV_13, $SEV_12, $SEV_11, $SEV_10, $SEV_9, $SEV_8, $SEV_7, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0}
 * Number of algorithm local variables: .......... 0 (0) {}
 * Number of clocks: ............................. 0 (0) {}
 * Number of top-level inputs: ................... 0 (0) {}
Notification: [Simulation] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 135 (scalar:128, array:7, record:0)
 * Number of multi strong components: ............ 4 (algorithm:0, when:4, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 3 (linear: 0, nonlinear:3)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 233 (scalar:225, array:8, record:0)
 * Number of multi strong components: ............ 4 (algorithm:4, when:0, if:0, tuple:2)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 3 (linear: 0, nonlinear:3)
Notification: Performance of backend: time 0.0001679/0.1845, allocations: 95.98 kB / 468.8 MB, free: 6.398 MB / 458.7 MB
Notification: Performance of SimCode: time 0.02099/0.2054, allocations: 14.08 MB / 482.9 MB, free: 8.055 MB / 474.7 MB
Notification: Performance of Templates: time 0.2377/0.4432, allocations: 36.13 MB / 0.5069 GB, free: 105.2 MB / 490.7 MB
"
[Timeout remaining time 660]
make -j1 -f OpenIPSL_dev_OpenIPSL.Tests.FACTS.PSAT.tcsc_alpha_4bus.makefile [Timeout 660]
(rm -f OpenIPSL_dev_OpenIPSL.Tests.FACTS.PSAT.tcsc_alpha_4bus.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.FACTS.PSAT.tcsc_alpha_4bus.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.FACTS.PSAT.tcsc_alpha_4bus.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.FACTS.PSAT.tcsc_alpha_4bus.sim & ./OpenIPSL_dev_OpenIPSL.Tests.FACTS.PSAT.tcsc_alpha_4bus -abortSlowSimulation -alarm=240  -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Tests.FACTS.PSAT.tcsc_alpha_4bus.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 6.616059059975669]