Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_trunk_Modelica.Electrical.Spice3.Examples.Graetz.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001727/0.001727, allocations: 99.64 kB / 21.25 MB, free: 424 kB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.002005/0.002005, allocations: 215.3 kB / 24.55 MB, free: 3.719 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.546/1.546, allocations: 230.5 MB / 258.2 MB, free: 7.75 MB / 206.1 MB
"
[Timeout remaining time 178]
Using package Modelica with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo)
Using package Complex with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo)
Running command: translateModel(Modelica.Electrical.Spice3.Examples.Graetz,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|D1.pin|D3.n.v",fileNamePrefix="Modelica_trunk_Modelica.Electrical.Spice3.Examples.Graetz")
translateModel(Modelica.Electrical.Spice3.Examples.Graetz,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|D1.pin|D3.n.v",fileNamePrefix="Modelica_trunk_Modelica.Electrical.Spice3.Examples.Graetz") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.397e-05/2.397e-05, allocations: 2.281 kB / 379 MB, free: 2.352 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Electrical.Spice3.Examples.Graetz): time 0.2633/0.2633, allocations: 4.583 MB / 383.6 MB, free: 12.25 MB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01006/0.2733, allocations: 5.858 MB / 389.5 MB, free: 8.973 MB / 302.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001739/0.2751, allocations: 64.34 kB / 389.5 MB, free: 8.93 MB / 302.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001915/0.277, allocations: 476.1 kB / 390 MB, free: 8.699 MB / 302.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004188/0.2812, allocations: 0.8239 MB / 390.8 MB, free: 8.199 MB / 302.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002012/0.2832, allocations: 442.7 kB / 391.2 MB, free: 7.996 MB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004461/0.2877, allocations: 2.323 MB / 393.6 MB, free: 7.066 MB / 302.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002183/0.2879, allocations: 52.03 kB / 393.6 MB, free: 7.047 MB / 302.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001012/0.2889, allocations: 0.5149 MB / 394.1 MB, free: 6.844 MB / 302.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0004547/0.2893, allocations: 379.2 kB / 394.5 MB, free: 6.66 MB / 302.1 MB
Notification: Performance of NFPackage.collectConstants: time 6.944e-05/0.2894, allocations: 33 kB / 394.5 MB, free: 6.66 MB / 302.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003499/0.2929, allocations: 0.9321 MB / 395.5 MB, free: 6.434 MB / 302.1 MB
Notification: Performance of combineBinaries: time 0.00201/0.2949, allocations: 2.035 MB / 397.5 MB, free: 5.035 MB / 302.1 MB
Notification: Performance of replaceArrayConstructors: time 0.001529/0.2964, allocations: 1.573 MB / 399.1 MB, free: 3.891 MB / 302.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0003664/0.2968, allocations: 78.22 kB / 399.2 MB, free: 3.891 MB / 302.1 MB
Notification: Performance of FrontEnd: time 0.0001203/0.2969, allocations: 7.906 kB / 399.2 MB, free: 3.891 MB / 302.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 61 (54)
 * Number of variables: 63 (63)
Notification: Performance of [SIM] Bindings: time 0.007775/0.3047, allocations: 7.234 MB / 406.4 MB, free: 14.93 MB / 318.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0009911/0.3057, allocations: 489.9 kB / 406.9 MB, free: 14.62 MB / 318.1 MB
Notification: Performance of [SIM] Early Inline: time 0.001518/0.3072, allocations: 0.9716 MB / 407.8 MB, free: 14.05 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0004906/0.3077, allocations: 277 kB / 408.1 MB, free: 13.88 MB / 318.1 MB
Notification: Performance of [SIM] Alias: time 0.00246/0.3102, allocations: 1.308 MB / 409.4 MB, free: 13.04 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0004153/0.3106, allocations: 232.5 kB / 409.6 MB, free: 12.9 MB / 318.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001519/0.3107, allocations: 112.3 kB / 409.8 MB, free: 12.8 MB / 318.1 MB
Notification: Performance of [SIM] Detect States: time 0.0005537/0.3113, allocations: 300.8 kB / 410.1 MB, free: 12.55 MB / 318.1 MB
Notification: Performance of [SIM] Events: time 0.0001759/0.3115, allocations: 60.39 kB / 410.1 MB, free: 12.52 MB / 318.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0006331/0.3121, allocations: 394.9 kB / 410.5 MB, free: 12.33 MB / 318.1 MB
Error: Internal error NBResolveSingularities.indexReduction failed because there was not enough state candidates to balance out the constraint equations.
Constraint Equations (38/57)
******************************
(1)       [TUPL] (2) ($FUN_1, D1.m_dCap) = Modelica.Electrical.Spice3.Internal.Diode.diodeNoBypassCode(D1.param, Modelica.Electrical.Spice3.Internal.Diode.DiodeParams(1.0, false, 0.0, 0.0, false), D1.c2, Modelica.Electrical.Spice3.Internal.Model.Model(300.15), false, {D1.pin, D1.n.v}) ($RES_AUX_58)
(2)       [ALGO] (16) ($RES_SIM_0)
(2)       [----] rout.vp := Modelica.Electrical.Spice3.Internal.Rsemiconductor.resistorInitEquations(rout.rp, rout.lp);
(2)       [----] (rout.vp.m_dConduct, rout.vp.m_dCond_dTemp) := Modelica.Electrical.Spice3.Internal.Functions.resDepTemp(rout.vp.m_dResist, rout.rp.m_dTemp, rout.lp.m_dTnom, rout.lp.m_dTC1, rout.lp.m_dTC2);
(2)       [----] rout.i := rout.vp.m_dConduct * rout.v;

(3)       [TUPL] (2) ($FUN_3, D3.m_dCap) = Modelica.Electrical.Spice3.Internal.Diode.diodeNoBypassCode(D3.param, Modelica.Electrical.Spice3.Internal.Diode.DiodeParams(1.0, false, 0.0, 0.0, false), D3.c2, Modelica.Electrical.Spice3.Internal.Model.Model(300.15), false, {D3.pin, D3.n.v}) ($RES_AUX_57)
(4)       [TUPL] (2) ($FUN_5, D4.m_dCap) = Modelica.Electrical.Spice3.Internal.Diode.diodeNoBypassCode(D4.param, Modelica.Electrical.Spice3.Internal.Diode.DiodeParams(1.0, false, 0.0, 0.0, false), D4.c2, Modelica.Electrical.Spice3.Internal.Model.Model(300.15), false, {D4.pin, D3.n.v}) ($RES_AUX_56)
(5)       [SCAL] (1) vsin.v = D1.n.v ($RES_SIM_2)
(6)       [TUPL] (2) ($FUN_7, D2.m_dCap) = Modelica.Electrical.Spice3.Internal.Diode.diodeNoBypassCode(D2.param, Modelica.Electrical.Spice3.Internal.Diode.DiodeParams(1.0, false, 0.0, 0.0, false), D2.c2, Modelica.Electrical.Spice3.Internal.Model.Model(300.15), false, {D2.pin, 0.0}) ($RES_AUX_55)
(7)       [SCAL] (1) vsin.v = vsin.VO + (if $TEV_0 then 0.0 else exp(-vsin.THETA * (time - vsin.TD)) * vsin.VA * sin(6.283185307179586 * (time - vsin.TD) * vsin.FREQ)) ($RES_SIM_5)
(8)       [SCAL] (1) rout.v = D3.n.v - D2.p.v ($RES_SIM_7)
(9)       [RECD] (1) D2.cc = $FUN_7 ($RES_SIM_65)
(10)      [RECD] (1) D4.cc = $FUN_5 ($RES_SIM_63)
(11)      [RECD] (1) D3.cc = $FUN_3 ($RES_SIM_61)
(12)      [SCAL] (1) D3.ir + vsin.i + D1.n.i = 0.0 ($RES_SIM_45)
(13)      [SCAL] (1) D4.n.i + rout.i + D3.n.i = 0.0 ($RES_SIM_44)
(14)      [SCAL] (1) (D2.n.i + ground.p.i + D4.ir) - vsin.i = 0.0 ($RES_SIM_43)
(15)      [SCAL] (1) D3.n.i = -(D3.igmin + D3.cc.m_dCurrent + D3.icap) ($RES_SIM_28)
(16)      [SCAL] (1) (D2.ir + D1.ir) - rout.i = 0.0 ($RES_SIM_42)
(17)      [SCAL] (1) 0.0 = (D3.igmin + D3.cc.m_dCurrent + D3.icap) - D3.ir ($RES_SIM_27)
(18)      [SCAL] (1) D1.icap = D1.m_dCap * ($DER.D1.pin - $DER.D1.n.v) ($RES_SIM_40)
(19)      [SCAL] (1) D4.icap = D4.m_dCap * ($DER.D4.pin - $DER.D3.n.v) ($RES_SIM_24)
(20)      [SCAL] (1) D4.ir * D4.param.m_resist = -D4.pin ($RES_SIM_23)
(21)      [SCAL] (1) D4.igmin = 1e-12 * (D4.pin - D3.n.v) ($RES_SIM_22)
(22)      [SCAL] (1) D4.n.i = -(D4.igmin + D4.cc.m_dCurrent + D4.icap) ($RES_SIM_20)
(23)      [RECD] (1) D1.cc = $FUN_1 ($RES_SIM_59)
(24)      [SCAL] (1) D1.ir * D1.param.m_resist = D2.p.v - D1.pin ($RES_SIM_39)
(25)      [SCAL] (1) D1.igmin = 1e-12 * (D1.pin - D1.n.v) ($RES_SIM_38)
(26)      [SCAL] (1) D1.n.i = -(D1.igmin + D1.cc.m_dCurrent + D1.icap) ($RES_SIM_36)
(27)      [SCAL] (1) 0.0 = (D1.igmin + D1.cc.m_dCurrent + D1.icap) - D1.ir ($RES_SIM_35)
(28)      [SCAL] (1) 0.0 = (D4.igmin + D4.cc.m_dCurrent + D4.icap) - D4.ir ($RES_SIM_19)
(29)      [SCAL] (1) D1.v = D2.p.v - D1.n.v ($RES_SIM_34)
(30)      [SCAL] (1) D3.icap = D3.m_dCap * ($DER.D3.pin - $DER.D3.n.v) ($RES_SIM_32)
(31)      [SCAL] (1) D2.icap = D2.m_dCap * $DER.D2.pin ($RES_SIM_16)
(32)      [SCAL] (1) D3.ir * D3.param.m_resist = D1.n.v - D3.pin ($RES_SIM_31)
(33)      [SCAL] (1) D2.ir * D2.param.m_resist = D2.p.v - D2.pin ($RES_SIM_15)
(34)      [SCAL] (1) D3.igmin = 1e-12 * (D3.pin - D3.n.v) ($RES_SIM_30)
(35)      [SCAL] (1) D2.igmin = 1e-12 * D2.pin ($RES_SIM_14)
(36)      [SCAL] (1) D2.n.i = -(D2.igmin + D2.cc.m_dCurrent + D2.icap) ($RES_SIM_12)
(37)      [SCAL] (1) 0.0 = (D2.igmin + D2.cc.m_dCurrent + D2.icap) - D2.ir ($RES_SIM_11)
(38)      [SCAL] (1) D2.v = D2.p.v ($RES_SIM_10)


State Candidate Variables (53/53)
***********************************
(1)       [ALGB] (1) Real D3.icap
(2)       [DER-] (1) Real $DER.D3.n.v
(3)       [ALGB] (1) Real $FUN_5.m_dCurrent
(4)       [ALGB] (1) Real D2.icap
(5)       [ALGB] (1) Real D1.icap
(6)       [ALGB] (1) Real $FUN_3.m_dCurrent
(7)       [ALGB] (1) Real $FUN_1.m_dCurrent
(8)       [ALGB] (1) Real D2.v
(9)       [ALGB] (1) Real D1.v
(10)      [ALGB] (1) Real rout.i
(11)      [ALGB] (1) Real D4.ir
(12)      [DER-] (1) Real $DER.D2.pin
(13)      [ALGB] (1) Real D3.ir
(14)      [ALGB] (1) Real D2.p.v
(15)      [ALGB] (1) Real D2.ir
(16)      [ALGB] (1) Real D1.ir
(17)      [ALGB] (1) Real D1.cc.m_dCurrent
(18)      [ALGB] (1) Real D2.cc.m_dCurrent
(19)      [ALGB] (1) Real D3.cc.m_dCurrent
(20)      [ALGB] (1) Real D4.cc.m_dCurrent
(21)      [ALGB] (1) Real rout.vp.m_dLength
(22)      [ALGB] (1) Real D4.igmin
(23)      [ALGB] (1) Real D4.m_dCap
(24)      [ALGB] (1) Real D3.igmin
(25)      [ALGB] (1) Real D3.m_dCap
(26)      [ALGB] (1) Real D2.igmin
(27)      [STAT] (1) Real D4.pin
(28)      [ALGB] (1) Real D2.m_dCap
(29)      [ALGB] (1) Real D1.igmin
(30)      [STAT] (1) Real D3.pin
(31)      [ALGB] (1) Real D1.m_dCap
(32)      [STAT] (1) Real D2.pin
(33)      [DER-] (1) Real $DER.D1.pin
(34)      [STAT] (1) Real D1.pin (fixed = true, start = 0.0)
(35)      [ALGB] (1) Real rout.vp.m_dWidth
(36)      [DER-] (1) Real $DER.D4.pin
(37)      [ALGB] (1) Real vsin.v
(38)      [ALGB] (1) Real rout.vp.m_dCond_dTemp
(39)      [ALGB] (1) flow Real D4.n.i
(40)      [ALGB] (1) Real rout.vp.m_dResist
(41)      [ALGB] (1) flow Real D3.n.i
(42)      [ALGB] (1) flow Real D2.n.i
(43)      [ALGB] (1) flow Real ground.p.i
(44)      [ALGB] (1) flow Real D1.n.i
(45)      [DER-] (1) Real $DER.D1.n.v
(46)      [ALGB] (1) Real vsin.i
(47)      [DER-] (1) Real $DER.D3.pin
(48)      [ALGB] (1) Real rout.vp.m_dConduct
(49)      [STAT] (1) Real D3.n.v (fixed = true, start = 0.0)
(50)      [ALGB] (1) Real rout.v
(51)      [STAT] (1) Real D1.n.v
(52)      [ALGB] (1) Real $FUN_7.m_dCurrent
(53)      [ALGB] (1) Real D4.icap
"
[Timeout remaining time 660]
[Calling sys.exit(0), Time elapsed: 2.7158456229954027]
Failed to read output from testmodel.py, exit status != 0:
['time', 'D1.pin', 'D3.n.v']
0.32320089399581775 0.345352268 0.044031299
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f40366aafb0>