Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Tests.Branches.Generic.ULTC.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.08383/0.08383, 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.001315/0.001315, allocations: 116.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.386/1.386, allocations: 207.7 MB / 251.7 MB, free: 7.238 MB / 190.1 MB
"
[Timeout remaining time 179]
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.001623/0.001623, 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.3108/0.3108, allocations: 79.74 MB / 441.8 MB, free: 12.23 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.Tests.Branches.Generic.ULTC,tolerance=0.0001,outputFormat="empty",numberOfIntervals=35000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.Branches.Generic.ULTC")
translateModel(OpenIPSL.Tests.Branches.Generic.ULTC,tolerance=0.0001,outputFormat="empty",numberOfIntervals=35000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.Branches.Generic.ULTC") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.587e-05/1.587e-05, allocations: 2.281 kB / 0.5887 GB, free: 7.066 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Branches.Generic.ULTC): time 0.005166/0.005182, allocations: 3.959 MB / 0.5926 GB, free: 3.078 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.002106/0.007288, allocations: 1.151 MB / 0.5937 GB, free: 1.922 MB / 398.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0001682/0.007456, allocations: 4 kB / 0.5937 GB, free: 1.918 MB / 398.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.000338/0.007794, allocations: 154.7 kB / 0.5938 GB, free: 1.766 MB / 398.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0005209/0.008315, allocations: 266 kB / 0.5941 GB, free: 1.504 MB / 398.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001181/0.009496, allocations: 0.5063 MB / 0.5946 GB, free: 1 MB / 398.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0009212/0.01042, allocations: 0.9617 MB / 0.5955 GB, free: 32 kB / 398.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002696/0.01069, allocations: 118.4 kB / 0.5957 GB, free: 15.89 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.00036/0.01105, allocations: 370.4 kB / 0.596 GB, free: 15.53 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0004695/0.01152, allocations: 381.9 kB / 0.5964 GB, free: 15.16 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 8.921e-05/0.01161, allocations: 56 kB / 0.5964 GB, free: 15.1 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0002973/0.0119, allocations: 135.9 kB / 0.5966 GB, free: 14.97 MB / 414.1 MB
Notification: Performance of combineBinaries: time 0.00131/0.01321, allocations: 1.65 MB / 0.5982 GB, free: 13.3 MB / 414.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0007595/0.01397, allocations: 1.079 MB / 0.5992 GB, free: 12.21 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0001041/0.01408, allocations: 56 kB / 0.5993 GB, free: 12.15 MB / 414.1 MB
Notification: Performance of FrontEnd: time 6.018e-05/0.01414, allocations: 16 kB / 0.5993 GB, free: 12.14 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: 134 (128)
 * Number of variables: 134 (134)
Notification: Performance of [SIM] Bindings: time 0.003676/0.01781, allocations: 3.893 MB / 0.6031 GB, free: 8.051 MB / 414.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0006066/0.01842, allocations: 0.5359 MB / 0.6036 GB, free: 7.492 MB / 414.1 MB
Notification: Performance of [SIM] Early Inline: time 0.004421/0.02284, allocations: 4.483 MB / 0.608 GB, free: 2.973 MB / 414.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.000884/0.02372, allocations: 0.5974 MB / 0.6086 GB, free: 2.309 MB / 414.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.004547/0.02827, allocations: 3.428 MB / 0.6119 GB, free: 14.58 MB / 430.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0007368/0.02901, allocations: 0.5471 MB / 0.6125 GB, free: 13.98 MB / 430.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0002857/0.02929, allocations: 272 kB / 0.6127 GB, free: 13.68 MB / 430.1 MB
Notification: Performance of [SIM] Detect States: time 0.0007233/0.03002, allocations: 0.6759 MB / 0.6134 GB, free: 12.95 MB / 430.1 MB
Notification: Performance of [SIM] Events: time 0.0005223/0.03054, allocations: 366.5 kB / 0.6137 GB, free: 12.59 MB / 430.1 MB
Notification: Performance of [SIM] Partitioning: time 0.001249/0.03179, allocations: 1.096 MB / 0.6148 GB, free: 11.48 MB / 430.1 MB
Notification: Performance of [SIM] Causalize: time 0.003021/0.03481, allocations: 2.149 MB / 0.6169 GB, free: 9.312 MB / 430.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.00219/0.037, allocations: 2.295 MB / 0.6191 GB, free: 7.016 MB / 430.1 MB
Notification: Performance of [INI] Simplify: time 0.001904/0.0389, allocations: 1.196 MB / 0.6203 GB, free: 5.707 MB / 430.1 MB
Notification: Performance of [INI] Inline: time 0.003816/0.04272, allocations: 3.934 MB / 0.6241 GB, free: 1.762 MB / 430.1 MB
Notification: Performance of [INI] Partitioning: time 0.0001231/0.04284, allocations: 60.05 kB / 0.6242 GB, free: 1.688 MB / 430.1 MB
Notification: Performance of [INI] Cleanup: time 0.0005435/0.04339, allocations: 0.5736 MB / 0.6248 GB, free: 1.113 MB / 430.1 MB
Notification: Performance of [INI] Causalize: time 0.007348/0.05073, allocations: 4.913 MB / 0.6296 GB, free: 12.12 MB / 446.1 MB
Notification: Performance of [INI] Tearing: time 0.001145/0.05188, allocations: 0.9413 MB / 0.6305 GB, free: 11.19 MB / 446.1 MB
Notification: Performance of [SIM] Initialization: time 1.503e-06/0.05188, allocations: 0 / 0.6305 GB, free: 11.19 MB / 446.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.001588/0.05347, allocations: 1.2 MB / 0.6316 GB, free: 9.992 MB / 446.1 MB
Notification: Performance of [SIM] Remove Dummies: time 8.205e-06/0.05348, allocations: 8.594 kB / 0.6317 GB, free: 9.984 MB / 446.1 MB
Notification: Performance of [SIM] Tearing: time 0.0009421/0.05442, allocations: 0.7507 MB / 0.6324 GB, free: 9.242 MB / 446.1 MB
Notification: Performance of [SIM] Categorize: time 1.463e-05/0.05443, allocations: 8 kB / 0.6324 GB, free: 9.234 MB / 446.1 MB
Notification: Performance of [SIM] Solve: time 0.004447/0.05888, allocations: 3.329 MB / 0.6356 GB, free: 5.902 MB / 446.1 MB
Notification: Performance of [SIM] Jacobian: time 0.2045/0.2633, allocations: 6.139 MB / 0.6416 GB, free: 45.72 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: ......... 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: ............................. 3 (3) {ultc.x1, load.xp, load.xq}
 * Number of discrete states: .................... 1 (1) {ultc.m}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 20 (20) {ultc.zeroCrossing.y, ultc.zeroCrossing.enable, ultc.zeroCrossing.disable, ultc.zeroCrossing.u_pos, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $TEV_8, $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: ........... 66 (scalar:66, 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: ... 3 (linear: 3, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 106 (scalar:106, 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: ... 3 (linear: 3, nonlinear:0)
Notification: Performance of backend: time 0.0001111/0.2635, allocations: 42.62 kB / 0.6417 GB, free: 45.72 MB / 446.1 MB
Notification: Performance of SimCode: time 0.00507/0.2685, allocations: 3.608 MB / 0.6452 GB, free: 44.73 MB / 446.1 MB
Notification: Performance of Templates: time 0.02185/0.2904, allocations: 12.74 MB / 0.6576 GB, free: 43.05 MB / 446.1 MB
"
[Timeout remaining time 660]
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Tests.Branches.Generic.ULTC.makefile [Timeout 660]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Tests.Branches.Generic.ULTC.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Tests.Branches.Generic.ULTC.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Tests.Branches.Generic.ULTC.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Tests.Branches.Generic.ULTC.sim & ./OpenIPSL_2.0.0_OpenIPSL.Tests.Branches.Generic.ULTC  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Tests.Branches.Generic.ULTC.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 5.315177695010789]
Failed to read output from testmodel.py, exit status != 0:
0.2973865860258229 0.30753879900000003 0.291090905
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f7108d42fb0>