Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.Graetz.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.001829/0.001829, allocations: 95.66 kB / 21.25 MB, free: 428 kB / 14.72 MB
"
[Timeout remaining time 180]
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.002196/0.002196, allocations: 211.2 kB / 24.55 MB, free: 3.723 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.522/1.522, allocations: 230.6 MB / 258.3 MB, free: 7.785 MB / 206.1 MB
"
[Timeout remaining time 178]
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 Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 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(Modelica.Electrical.Spice3.Examples.Graetz,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|D1.pin|D3.n.v",fileNamePrefix="Modelica_4.1.0_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_4.1.0_Modelica.Electrical.Spice3.Examples.Graetz") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.414e-05/2.414e-05, allocations: 2.281 kB / 379 MB, free: 2.57 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Electrical.Spice3.Examples.Graetz): time 0.2863/0.2864, allocations: 4.57 MB / 383.6 MB, free: 12.41 MB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01353/0.2999, allocations: 5.856 MB / 389.5 MB, free: 9.148 MB / 302.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001869/0.3018, allocations: 68.94 kB / 389.5 MB, free: 9.102 MB / 302.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002517/0.3043, allocations: 477.3 kB / 390 MB, free: 8.871 MB / 302.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006167/0.3104, allocations: 0.8263 MB / 390.8 MB, free: 8.371 MB / 302.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003151/0.3136, allocations: 446.9 kB / 391.3 MB, free: 8.164 MB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.006886/0.3205, allocations: 2.314 MB / 393.6 MB, free: 7.242 MB / 302.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002957/0.3208, allocations: 55.05 kB / 393.6 MB, free: 7.219 MB / 302.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001739/0.3225, allocations: 0.5155 MB / 394.1 MB, free: 7.016 MB / 302.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0009122/0.3234, allocations: 376.3 kB / 394.5 MB, free: 6.836 MB / 302.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001535/0.3236, allocations: 31.53 kB / 394.5 MB, free: 6.836 MB / 302.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.004977/0.3286, allocations: 0.9398 MB / 395.5 MB, free: 6.602 MB / 302.1 MB
Notification: Performance of combineBinaries: time 0.00236/0.3309, allocations: 2.04 MB / 397.5 MB, free: 5.199 MB / 302.1 MB
Notification: Performance of replaceArrayConstructors: time 0.001336/0.3322, allocations: 1.564 MB / 399.1 MB, free: 4.062 MB / 302.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0004896/0.3327, allocations: 78.56 kB / 399.2 MB, free: 4.062 MB / 302.1 MB
Notification: Performance of FrontEnd: time 0.0001042/0.3328, allocations: 8.5 kB / 399.2 MB, free: 4.062 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.007842/0.3407, allocations: 7.235 MB / 406.4 MB, free: 15.1 MB / 318.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0008378/0.3415, allocations: 487.6 kB / 406.9 MB, free: 14.79 MB / 318.1 MB
Notification: Performance of [SIM] Early Inline: time 0.001369/0.3429, allocations: 0.9796 MB / 407.9 MB, free: 14.21 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0005296/0.3434, allocations: 270.3 kB / 408.1 MB, free: 14.05 MB / 318.1 MB
Notification: Performance of [SIM] Alias: time 0.00217/0.3456, allocations: 1.306 MB / 409.4 MB, free: 13.21 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0003834/0.346, allocations: 230.7 kB / 409.7 MB, free: 13.07 MB / 318.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001472/0.3461, allocations: 113.5 kB / 409.8 MB, free: 12.97 MB / 318.1 MB
Notification: Performance of [SIM] Detect States: time 0.0004213/0.3465, allocations: 304.7 kB / 410.1 MB, free: 12.72 MB / 318.1 MB
Notification: Performance of [SIM] Events: time 0.00015/0.3467, allocations: 63.17 kB / 410.1 MB, free: 12.69 MB / 318.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0005552/0.3472, allocations: 381.6 kB / 410.5 MB, free: 12.5 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.7190613279817626]
Failed to read output from testmodel.py, exit status != 0:
['time', 'D1.pin', 'D3.n.v']
0.35813364799832925 0.37204946199999994 0.036016069000000005
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f39a1d52fb0>