Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.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.1022/0.1022, allocations: 12.36 MB / 32.03 MB, free: 6.41 MB / 26.18 MB
"
[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.001586/0.001586, allocations: 120.9 kB / 38.07 MB, free: 1.082 MB / 26.18 MB
"
[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.621/1.621, allocations: 207.7 MB / 251.7 MB, free: 7.23 MB / 190.1 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.002102/0.002102, allocations: 224.3 kB / 307 MB, free: 248 kB / 238.1 MB
"
[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.3351/0.3351, allocations: 79.74 MB / 441.8 MB, free: 12.22 MB / 318.1 MB
"
[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.Examples.KundurSMIB.SMIB_AVR_PSS,tolerance=1e-06,outputFormat="empty",numberOfIntervals=100000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS")
translateModel(OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS,tolerance=1e-06,outputFormat="empty",numberOfIntervals=100000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.6e-05/2.6e-05, allocations: 2.281 kB / 0.5887 GB, free: 7.035 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS): time 0.008168/0.008194, allocations: 5.312 MB / 0.5939 GB, free: 1.684 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.005125/0.01332, allocations: 2.009 MB / 0.5959 GB, free: 15.66 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001141/0.01446, allocations: 15.94 kB / 0.5959 GB, free: 15.65 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001111/0.01557, allocations: 242.1 kB / 0.5961 GB, free: 15.41 MB / 414.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation transformer.m <> 1.0, <> on Real numbers is only allowed inside functions.
Notification: Performance of NFTyping.typeBindings: time 0.002974/0.01854, allocations: 0.7886 MB / 0.5969 GB, free: 14.62 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001862/0.02041, allocations: 0.7244 MB / 0.5976 GB, free: 13.9 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.003211/0.02362, allocations: 2.26 MB / 0.5998 GB, free: 11.64 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0006824/0.0243, allocations: 182.2 kB / 0.6 GB, free: 11.45 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001532/0.02583, allocations: 0.7981 MB / 0.6008 GB, free: 10.65 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001298/0.02713, allocations: 0.6998 MB / 0.6014 GB, free: 9.945 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004638/0.02759, allocations: 152 kB / 0.6016 GB, free: 9.797 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0009588/0.02855, allocations: 295.8 kB / 0.6019 GB, free: 9.508 MB / 414.1 MB
Notification: Performance of combineBinaries: time 0.003144/0.0317, allocations: 2.957 MB / 0.6047 GB, free: 6.523 MB / 414.1 MB
Notification: Performance of replaceArrayConstructors: time 0.001432/0.03313, allocations: 1.697 MB / 0.6064 GB, free: 4.805 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0002272/0.03336, allocations: 76 kB / 0.6065 GB, free: 4.73 MB / 414.1 MB
Notification: Performance of FrontEnd: time 0.0002174/0.03357, allocations: 31.88 kB / 0.6065 GB, free: 4.699 MB / 414.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 159 (153)
 * Number of variables: 159 (159)
Notification: Performance of [SIM] Bindings: time 0.006805/0.04038, allocations: 6.245 MB / 0.6126 GB, free: 14.23 MB / 430.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.001046/0.04143, allocations: 0.734 MB / 0.6133 GB, free: 13.47 MB / 430.1 MB
Notification: Performance of [SIM] Early Inline: time 0.005481/0.04691, allocations: 5.077 MB / 0.6183 GB, free: 8.352 MB / 430.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.001586/0.04849, allocations: 0.9444 MB / 0.6192 GB, free: 7.359 MB / 430.1 MB
Warning: NBAlias.setStartFixed: Alias set with conflicting unfixed start values detected. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.006094/0.05459, allocations: 4.04 MB / 0.6232 GB, free: 2.98 MB / 430.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.001353/0.05594, allocations: 0.8436 MB / 0.624 GB, free: 2.102 MB / 430.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0003716/0.05631, allocations: 339.8 kB / 0.6243 GB, free: 1.742 MB / 430.1 MB
Notification: Performance of [SIM] Detect States: time 0.001096/0.05741, allocations: 0.9136 MB / 0.6252 GB, free: 0.7734 MB / 430.1 MB
Notification: Performance of [SIM] Events: time 0.0005089/0.05792, allocations: 359.2 kB / 0.6255 GB, free: 424 kB / 430.1 MB
Notification: Performance of [SIM] Partitioning: time 0.001711/0.05963, allocations: 1.362 MB / 0.6269 GB, free: 15.03 MB / 446.1 MB
Notification: Performance of [SIM] Causalize: time 0.00353/0.06316, allocations: 2.347 MB / 0.6292 GB, free: 12.64 MB / 446.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.003/0.06616, allocations: 2.869 MB / 0.632 GB, free: 9.77 MB / 446.1 MB
[/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelica/OMCompiler/Compiler/NBackEnd/Classes/NBEquation.mo:623:7-623:72:writable] Warning: In relation transformer.m <> 1.0, <> on Real numbers is only allowed inside functions.
Notification: Performance of [INI] Simplify: time 0.005183/0.07134, allocations: 2.873 MB / 0.6348 GB, free: 6.801 MB / 446.1 MB
Notification: Performance of [INI] Inline: time 0.006536/0.07788, allocations: 6.362 MB / 0.641 GB, free: 404 kB / 446.1 MB
Notification: Performance of [INI] Partitioning: time 0.0001959/0.07807, allocations: 80.17 kB / 0.6411 GB, free: 304 kB / 446.1 MB
Notification: Performance of [INI] Cleanup: time 0.2779/0.356, allocations: 1.294 MB / 0.6423 GB, free: 44.35 MB / 446.1 MB
Notification: Performance of [INI] Causalize: time 0.02493/0.3809, allocations: 10.92 MB / 0.653 GB, free: 43.45 MB / 446.1 MB
Notification: Performance of [INI] Tearing: time 0.002188/0.3831, allocations: 1.89 MB / 0.6548 GB, free: 43.05 MB / 446.1 MB
Notification: Performance of [SIM] Initialization: time 2.214e-06/0.3831, allocations: 5.094 kB / 0.6548 GB, free: 43.05 MB / 446.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.002189/0.3853, allocations: 1.621 MB / 0.6564 GB, free: 42.51 MB / 446.1 MB
Notification: Performance of [SIM] Remove Dummies: time 1.231e-05/0.3853, allocations: 1 kB / 0.6564 GB, free: 42.5 MB / 446.1 MB
Notification: Performance of [SIM] Tearing: time 0.0005918/0.3859, allocations: 0.5189 MB / 0.6569 GB, free: 42.34 MB / 446.1 MB
Notification: Performance of [SIM] Categorize: time 3.369e-05/0.386, allocations: 12.03 kB / 0.6569 GB, free: 42.33 MB / 446.1 MB
Notification: Performance of [SIM] Solve: time 0.01119/0.3972, allocations: 6.844 MB / 0.6636 GB, free: 41.5 MB / 446.1 MB
Notification: Performance of [SIM] Jacobian: time 0.006602/0.4038, allocations: 5.996 MB / 0.6695 GB, free: 38.29 MB / 446.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 2
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 1
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 12 (12) {G1.machine.e1q, G1.machine.e2q, G1.machine.delta, G1.machine.w, G1.avr.vm, G1.avr.vr, G1.avr.vf1, G1.pss.imLeadLag.TF.x_scaled, G1.pss.imLeadLag1.TF.x_scaled, G1.pss.derivativeLag.TF.x_scaled, G1.machine.e1d, G1.machine.e2d}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 18 (18) {$SEV_9, $SEV_8, $SEV_7, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $TEV_7, $TEV_6, $TEV_5, $TEV_4, $TEV_3, $TEV_2, $TEV_1, $TEV_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: ........... 93 (scalar:90, array:3, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, 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: 3, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 230 (scalar:227, array:3, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, 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: 3, nonlinear:0)
Notification: [Initialization (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 230 (scalar:227, array:3, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, 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: 3, nonlinear:0)
Notification: Performance of backend: time 0.0001556/0.4039, allocations: 102.8 kB / 0.6696 GB, free: 38.2 MB / 446.1 MB
Notification: Performance of SimCode: time 0.00634/0.4103, allocations: 6.355 MB / 0.6758 GB, free: 35.33 MB / 446.1 MB
Notification: Performance of Templates: time 0.0251/0.4354, allocations: 20.4 MB / 0.6957 GB, free: 17.15 MB / 446.1 MB
"
[Timeout remaining time 660]
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.makefile [Timeout 660]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.sim & ./OpenIPSL_2.0.0_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 6.5029592189821415]
Failed to read output from testmodel.py, exit status != 0:
0.44375502795446664 0.451614115 0.41588253
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fb20ff76fb0>