Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.0.0_Modelica.Electrical.Spice3.Examples.Graetz.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001839/0.001839, allocations: 104 kB / 21.25 MB, free: 0.5664 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.002608/0.002608, allocations: 190.7 kB / 24.53 MB, free: 3.852 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.65/1.65, allocations: 225.7 MB / 253.4 MB, free: 12.79 MB / 206.1 MB
"
[Timeout remaining time 178]
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.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.0.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.0.0_Modelica.Electrical.Spice3.Examples.Graetz") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 3.444e-05/3.444e-05, allocations: 2.281 kB / 371.7 MB, free: 9.176 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Electrical.Spice3.Examples.Graetz): time 0.0112/0.01124, allocations: 4.549 MB / 376.3 MB, free: 4.602 MB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.3083/0.3196, allocations: 5.817 MB / 382.1 MB, free: 13.04 MB / 302.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001408/0.321, allocations: 64.5 kB / 382.1 MB, free: 13.04 MB / 302.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001738/0.3227, allocations: 469.1 kB / 382.6 MB, free: 13.03 MB / 302.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003462/0.3262, allocations: 0.8357 MB / 383.4 MB, free: 12.95 MB / 302.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001662/0.3279, allocations: 434.5 kB / 383.9 MB, free: 12.88 MB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004138/0.332, allocations: 2.27 MB / 386.1 MB, free: 12.51 MB / 302.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002093/0.3322, allocations: 63.77 kB / 386.2 MB, free: 12.49 MB / 302.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0009959/0.3332, allocations: 0.5007 MB / 386.7 MB, free: 12.38 MB / 302.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.000468/0.3337, allocations: 380.6 kB / 387.1 MB, free: 12.23 MB / 302.1 MB
Notification: Performance of NFPackage.collectConstants: time 7.633e-05/0.3337, allocations: 35.12 kB / 387.1 MB, free: 12.23 MB / 302.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003193/0.3369, allocations: 0.9265 MB / 388 MB, free: 12.2 MB / 302.1 MB
Notification: Performance of combineBinaries: time 0.001529/0.3385, allocations: 2.041 MB / 390.1 MB, free: 11.01 MB / 302.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0008846/0.3393, allocations: 1.554 MB / 391.6 MB, free: 9.996 MB / 302.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0002041/0.3395, allocations: 78.56 kB / 391.7 MB, free: 9.996 MB / 302.1 MB
Notification: Performance of FrontEnd: time 8.122e-05/0.3396, allocations: 8.188 kB / 391.7 MB, free: 9.996 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.006383/0.346, allocations: 7.238 MB / 398.9 MB, free: 5.25 MB / 302.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0007786/0.3468, allocations: 0.5 MB / 399.4 MB, free: 4.973 MB / 302.1 MB
Notification: Performance of [SIM] Early Inline: time 0.00107/0.3479, allocations: 0.9804 MB / 400.4 MB, free: 4.406 MB / 302.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0004807/0.3483, allocations: 273.9 kB / 400.7 MB, free: 4.246 MB / 302.1 MB
Notification: Performance of [SIM] Alias: time 0.002028/0.3504, allocations: 1.308 MB / 402 MB, free: 3.438 MB / 302.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0003743/0.3507, allocations: 245.8 kB / 402.2 MB, free: 3.293 MB / 302.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001216/0.3509, allocations: 116.1 kB / 402.4 MB, free: 3.195 MB / 302.1 MB
Notification: Performance of [SIM] Detect States: time 0.0003493/0.3512, allocations: 262.5 kB / 402.6 MB, free: 2.984 MB / 302.1 MB
Notification: Performance of [SIM] Events: time 0.0001921/0.3514, allocations: 87.91 kB / 402.7 MB, free: 2.938 MB / 302.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0006925/0.3521, allocations: 391.1 kB / 403.1 MB, free: 2.754 MB / 302.1 MB
Error: Internal error NBResolveSingularities.indexReduction failed because there was not enough state candidates to balance out the constraint equations.
Constraint Equations (40/59)
******************************
(1)       [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_59)
(2)       [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_58)
(3)       [ALGO] (16) ($RES_SIM_0)
(3)       [----] rout.vp := Modelica.Electrical.Spice3.Internal.Rsemiconductor.resistorInitEquations(rout.rp, rout.lp);
(3)       [----] (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);
(3)       [----] rout.i := rout.vp.m_dConduct * rout.v;

(4)       [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_57)
(5)       [SCAL] (1) $FUN_9 = exp(-(time - vsin.TD) * vsin.THETA) ($RES_AUX_56)
(6)       [SCAL] (1) vsin.v = D1.n.v ($RES_SIM_2)
(7)       [SCAL] (1) $FUN_10 = sin(6.283185307179586 * vsin.FREQ * (time - vsin.TD)) ($RES_AUX_55)
(8)       [SCAL] (1) vsin.v = vsin.VO + (if $TEV_0 then 0.0 else $FUN_9 * vsin.VA * $FUN_10) ($RES_SIM_5)
(9)       [RECD] (1) D2.cc = $FUN_7 ($RES_SIM_67)
(10)      [SCAL] (1) rout.v = D3.n.v - D2.p.v ($RES_SIM_7)
(11)      [RECD] (1) D4.cc = $FUN_5 ($RES_SIM_65)
(12)      [RECD] (1) D3.cc = $FUN_3 ($RES_SIM_63)
(13)      [RECD] (1) D1.cc = $FUN_1 ($RES_SIM_61)
(14)      [SCAL] (1) D3.ir + vsin.i + D1.n.i = 0.0 ($RES_SIM_45)
(15)      [SCAL] (1) D4.n.i + rout.i + D3.n.i = 0.0 ($RES_SIM_44)
(16)      [SCAL] (1) (D2.n.i + ground.p.i + D4.ir) - vsin.i = 0.0 ($RES_SIM_43)
(17)      [SCAL] (1) D3.n.i = -(D3.igmin + D3.cc.m_dCurrent + D3.icap) ($RES_SIM_28)
(18)      [SCAL] (1) (D2.ir + D1.ir) - rout.i = 0.0 ($RES_SIM_42)
(19)      [SCAL] (1) 0.0 = (D3.igmin + D3.cc.m_dCurrent + D3.icap) - D3.ir ($RES_SIM_27)
(20)      [SCAL] (1) D1.icap = D1.m_dCap * ($DER.D1.pin - $DER.D1.n.v) ($RES_SIM_40)
(21)      [SCAL] (1) D4.icap = D4.m_dCap * ($DER.D4.pin - $DER.D3.n.v) ($RES_SIM_24)
(22)      [SCAL] (1) D4.ir * D4.param.m_resist = -D4.pin ($RES_SIM_23)
(23)      [SCAL] (1) D4.igmin = 1e-12 * (D4.pin - D3.n.v) ($RES_SIM_22)
(24)      [SCAL] (1) D4.n.i = -(D4.igmin + D4.cc.m_dCurrent + D4.icap) ($RES_SIM_20)
(25)      [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_60)
(26)      [SCAL] (1) D1.ir * D1.param.m_resist = D2.p.v - D1.pin ($RES_SIM_39)
(27)      [SCAL] (1) D1.igmin = 1e-12 * (D1.pin - D1.n.v) ($RES_SIM_38)
(28)      [SCAL] (1) D1.n.i = -(D1.igmin + D1.cc.m_dCurrent + D1.icap) ($RES_SIM_36)
(29)      [SCAL] (1) 0.0 = (D1.igmin + D1.cc.m_dCurrent + D1.icap) - D1.ir ($RES_SIM_35)
(30)      [SCAL] (1) 0.0 = (D4.igmin + D4.cc.m_dCurrent + D4.icap) - D4.ir ($RES_SIM_19)
(31)      [SCAL] (1) D1.v = D2.p.v - D1.n.v ($RES_SIM_34)
(32)      [SCAL] (1) D3.icap = D3.m_dCap * ($DER.D3.pin - $DER.D3.n.v) ($RES_SIM_32)
(33)      [SCAL] (1) D2.icap = D2.m_dCap * $DER.D2.pin ($RES_SIM_16)
(34)      [SCAL] (1) D3.ir * D3.param.m_resist = D1.n.v - D3.pin ($RES_SIM_31)
(35)      [SCAL] (1) D2.ir * D2.param.m_resist = D2.p.v - D2.pin ($RES_SIM_15)
(36)      [SCAL] (1) D3.igmin = 1e-12 * (D3.pin - D3.n.v) ($RES_SIM_30)
(37)      [SCAL] (1) D2.igmin = 1e-12 * D2.pin ($RES_SIM_14)
(38)      [SCAL] (1) D2.n.i = -(D2.igmin + D2.cc.m_dCurrent + D2.icap) ($RES_SIM_12)
(39)      [SCAL] (1) 0.0 = (D2.igmin + D2.cc.m_dCurrent + D2.icap) - D2.ir ($RES_SIM_11)
(40)      [SCAL] (1) D2.v = D2.p.v ($RES_SIM_10)


State Candidate Variables (55/55)
***********************************
(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 $FUN_10
(22)      [ALGB] (1) Real rout.vp.m_dLength
(23)      [ALGB] (1) Real D4.igmin
(24)      [ALGB] (1) Real D4.m_dCap
(25)      [ALGB] (1) Real D3.igmin
(26)      [ALGB] (1) Real D3.m_dCap
(27)      [ALGB] (1) Real D2.igmin
(28)      [STAT] (1) Real D4.pin
(29)      [ALGB] (1) Real D2.m_dCap
(30)      [ALGB] (1) Real D1.igmin
(31)      [STAT] (1) Real D3.pin
(32)      [ALGB] (1) Real D1.m_dCap
(33)      [STAT] (1) Real D2.pin
(34)      [DER-] (1) Real $DER.D1.pin
(35)      [ALGB] (1) Real rout.vp.m_dWidth
(36)      [DER-] (1) Real $DER.D4.pin
(37)      [STAT] (1) Real D1.pin (fixed = true, start = 0.0)
(38)      [ALGB] (1) Real vsin.v
(39)      [ALGB] (1) Real rout.vp.m_dCond_dTemp
(40)      [ALGB] (1) flow Real D4.n.i
(41)      [ALGB] (1) Real rout.vp.m_dResist
(42)      [ALGB] (1) flow Real D3.n.i
(43)      [ALGB] (1) flow Real D2.n.i
(44)      [ALGB] (1) flow Real ground.p.i
(45)      [ALGB] (1) flow Real D1.n.i
(46)      [DER-] (1) Real $DER.D1.n.v
(47)      [ALGB] (1) Real vsin.i
(48)      [DER-] (1) Real $DER.D3.pin
(49)      [ALGB] (1) Real rout.vp.m_dConduct
(50)      [STAT] (1) Real D3.n.v (fixed = true, start = 0.0)
(51)      [ALGB] (1) Real rout.v
(52)      [STAT] (1) Real D1.n.v
(53)      [ALGB] (1) Real $FUN_9
(54)      [ALGB] (1) Real $FUN_7.m_dCurrent
(55)      [ALGB] (1) Real D4.icap
"
[Timeout remaining time 660]
[Calling sys.exit(0), Time elapsed: 2.8596615440328605]
Failed to read output from testmodel.py, exit status != 0:
['time', 'D1.pin', 'D3.n.v']
0.3659356930293143 0.38768691499999997 0.043452454
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f4aa9d1afb0>