Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.Discretization.FourthOrder_AVRII_Discrete.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.001653/0.001653, allocations: 104.3 kB / 19.76 MB, free: 1.906 MB / 14.72 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.521/1.521, allocations: 230.7 MB / 253.5 MB, free: 14.44 MB / 206.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.002126/0.002126, allocations: 202.5 kB / 310.1 MB, free: 3.055 MB / 238.1 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.6427/0.6427, allocations: 107.2 MB / 473.7 MB, free: 29.88 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.1.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.Examples.PSATSystems.Discretization.FourthOrder_AVRII_Discrete,tolerance=0.001,outputFormat="empty",numberOfIntervals=2000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.Discretization.FourthOrder_AVRII_Discrete")
translateModel(OpenIPSL.Examples.PSATSystems.Discretization.FourthOrder_AVRII_Discrete,tolerance=0.001,outputFormat="empty",numberOfIntervals=2000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.Discretization.FourthOrder_AVRII_Discrete") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.512e-05/2.512e-05, allocations: 2.281 kB / 0.6343 GB, free: 29.49 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.Discretization.FourthOrder_AVRII_Discrete): time 0.006089/0.006114, allocations: 5.513 MB / 0.6397 GB, free: 25.76 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.004166/0.01028, allocations: 1.812 MB / 0.6415 GB, free: 24.52 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0008406/0.01112, allocations: 9.469 kB / 0.6415 GB, free: 24.52 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0006282/0.01175, allocations: 214.4 kB / 0.6417 GB, free: 24.4 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002275/0.01402, allocations: 0.8012 MB / 0.6425 GB, free: 23.88 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001531/0.01555, allocations: 0.5695 MB / 0.643 GB, free: 23.59 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.002076/0.01763, allocations: 1.66 MB / 0.6447 GB, free: 22.78 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0004403/0.01807, allocations: 168.4 kB / 0.6448 GB, free: 22.7 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001117/0.01919, allocations: 0.6728 MB / 0.6455 GB, free: 22.36 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0007758/0.01996, allocations: 0.689 MB / 0.6461 GB, free: 22.06 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001609/0.02012, allocations: 92.12 kB / 0.6462 GB, free: 22.06 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0005894/0.02071, allocations: 247 kB / 0.6465 GB, free: 22.02 MB / 446.1 MB
Notification: Performance of combineBinaries: time 0.001832/0.02255, allocations: 2.887 MB / 0.6493 GB, free: 20.1 MB / 446.1 MB
Notification: Performance of replaceArrayConstructors: time 0.001063/0.02361, allocations: 1.773 MB / 0.651 GB, free: 18.84 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0002493/0.02386, allocations: 79.06 kB / 0.6511 GB, free: 18.84 MB / 446.1 MB
Notification: Performance of FrontEnd: time 0.0002079/0.02407, allocations: 34.72 kB / 0.6511 GB, free: 18.84 MB / 446.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 176 (164)
 * Number of variables: 176 (176)
Notification: Performance of [SIM] Bindings: time 0.004958/0.02902, allocations: 6.219 MB / 0.6572 GB, free: 14.66 MB / 446.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0008146/0.02984, allocations: 0.7643 MB / 0.658 GB, free: 14.05 MB / 446.1 MB
Notification: Performance of [SIM] Early Inline: time 0.004445/0.03428, allocations: 6.117 MB / 0.6639 GB, free: 8.82 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0009704/0.03525, allocations: 0.8322 MB / 0.6647 GB, free: 7.914 MB / 446.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.004824/0.04008, allocations: 5.256 MB / 0.6699 GB, free: 2.227 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0007912/0.04087, allocations: 0.7668 MB / 0.6706 GB, free: 1.395 MB / 446.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0002839/0.04115, allocations: 363.9 kB / 0.671 GB, free: 1 MB / 446.1 MB
Notification: Performance of [SIM] Detect States: time 0.0006792/0.04183, allocations: 0.9323 MB / 0.6719 GB, free: 8 kB / 446.1 MB
Notification: Performance of [SIM] Events: time 0.0006551/0.04249, allocations: 410.8 kB / 0.6723 GB, free: 15.61 MB / 462.1 MB
Notification: Performance of [SIM] Partitioning: time 0.001831/0.04432, allocations: 1.525 MB / 0.6738 GB, free: 14.05 MB / 462.1 MB
Notification: Performance of [SIM] Causalize: time 0.004216/0.04853, allocations: 2.844 MB / 0.6765 GB, free: 11.16 MB / 462.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.003103/0.05164, allocations: 3.048 MB / 0.6795 GB, free: 8.113 MB / 462.1 MB
Notification: Performance of [INI] Simplify: time 0.005341/0.05698, allocations: 2.607 MB / 0.6821 GB, free: 5.332 MB / 462.1 MB
Notification: Performance of [INI] Inline: time 0.007479/0.06446, allocations: 6.747 MB / 0.6886 GB, free: 14.55 MB / 478.1 MB
Notification: Performance of [INI] Partitioning: time 0.0002662/0.06472, allocations: 72.06 kB / 0.6887 GB, free: 14.47 MB / 478.1 MB
Notification: Performance of [INI] Cleanup: time 0.00104/0.06576, allocations: 0.8701 MB / 0.6896 GB, free: 13.6 MB / 478.1 MB
Warning: NBResolveSingularities.balanceInitialization reports an overdetermined initialization!
Checking for consistency is not yet supported, following equations had to be removed:
	[SCAL] (1) aVRTypeII.vm = aVRTypeII.vm0 ($RES_SRT_336);
	[SCAL] (1) aVRTypeII.vr1 = aVRTypeII.vr10 ($RES_SRT_335);
	[SCAL] (1) aVRTypeII.vr2 = aVRTypeII.vr20 ($RES_SRT_334);
	[SCAL] (1) aVRTypeII.vr20 = -aVRTypeII.vf0 * aVRTypeII.Kf / aVRTypeII.Tf ($RES_BND_421);
	[SCAL] (1) aVRTypeII.vr10 = aVRTypeII.Ka * (aVRTypeII.vref0 - aVRTypeII.vr20 - aVRTypeII.vm0 - aVRTypeII.Kf * aVRTypeII.vf0 / aVRTypeII.Tf) ($RES_BND_420);
	[SCAL] (1) aVRTypeII.vm0 = aVRTypeII.v0 ($RES_BND_419);
Notification: Performance of [INI] Causalize: time 0.01366/0.07942, allocations: 8.668 MB / 0.698 GB, free: 4.723 MB / 478.1 MB
Notification: Performance of [INI] Tearing: time 0.001704/0.08113, allocations: 1.395 MB / 0.6994 GB, free: 3.355 MB / 478.1 MB
Notification: Performance of [SIM] Initialization: time 1.633e-06/0.08113, allocations: 0 / 0.6994 GB, free: 3.355 MB / 478.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.00226/0.08339, allocations: 1.662 MB / 0.701 GB, free: 1.707 MB / 478.1 MB
Notification: Performance of [SIM] Remove Dummies: time 1.697e-05/0.0834, allocations: 4.656 kB / 0.701 GB, free: 1.703 MB / 478.1 MB
Notification: Performance of [SIM] Tearing: time 0.001295/0.0847, allocations: 0.9632 MB / 0.702 GB, free: 0.7539 MB / 478.1 MB
Notification: Performance of [SIM] Categorize: time 1.831e-05/0.08472, allocations: 8 kB / 0.702 GB, free: 0.7461 MB / 478.1 MB
Notification: Performance of [SIM] Solve: time 0.007241/0.09196, allocations: 5.314 MB / 0.7072 GB, free: 11.43 MB / 494.1 MB
Notification: Performance of [SIM] Jacobian: time 0.01195/0.1039, allocations: 9.049 MB / 0.716 GB, free: 2.484 MB / 494.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: ......... 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: ............................. 6 (6) {order2.delta, order2.w, order4.e1d, order4.delta, order4.w, order4.e1q}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 4 (4) {aVRTypeII.vf, aVRTypeII.vr2, aVRTypeII.vr1, aVRTypeII.vm}
 * Number of discrete variables: ................. 22 (22) {$SEV_2, $SEV_1, $SEV_0, $TEV_18, $TEV_17, $TEV_16, $TEV_15, $TEV_14, $TEV_13, $TEV_12, $TEV_11, $TEV_10, $TEV_9, $TEV_8, $TEV_7, $TEV_6, $TEV_5, $TEV_4, $TEV_3, $TEV_2, $TEV_1, $TEV_0}
 * Number of clocks: ............................. 1 (1) {periodicClock1.y}
 * Number of top-level inputs: ................... 0 (0) {}
Notification: [Simulation] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 64 (scalar:64, array:0, 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: ... 1 (linear: 1, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 183 (scalar:183, array:0, 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: ... 1 (linear: 1, nonlinear:0)
Notification: Performance of backend: time 0.0001314/0.104, allocations: 65.19 kB / 0.7161 GB, free: 2.43 MB / 494.1 MB
Notification: Performance of SimCode: time 0.008119/0.1122, allocations: 5.564 MB / 0.7215 GB, free: 12.73 MB / 0.4981 GB
[/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelica/OMCompiler/Compiler/SimCode/SerializeModelInfo.mo:126:9-126:84:writable] Error: Internal error SerializeModelInfo.serialize failed
"
[Timeout remaining time 660]
[Calling sys.exit(0), Time elapsed: 3.6212263079942204]
Failed to read output from testmodel.py, exit status != 0:
0.15146412595640868 0.166640521 0.13938292600000002
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f5f90512fb0>