Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YD.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.001219/0.001219, 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/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.001316/0.001316, allocations: 212 kB / 23.06 MB, free: 4.984 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.324/1.324, allocations: 230.7 MB / 256.9 MB, free: 7.723 MB / 206.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo): time 2.972/2.972, allocations: 0.5108 GB / 0.8167 GB, free: 17.7 MB / 0.6231 GB
"
[Timeout remaining time 177]
Using package Buildings with version 13.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/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 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(Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YD,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|err_V2.1.|err_V2.2.|err_V2.3.|err_V3.1.|err_V3.2.|err_V3.3.|err_V4.1.|err_V4.2.|err_V4.3.|err_Theta2.1.|err_Theta2.2.|err_Theta2.3.|err_Theta3.1.|err_Theta3.2.|err_Theta3.3.|err_Theta4.1.|err_Theta4.2.|err_Theta4.3.",fileNamePrefix="Buildings_latest_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YD")
translateModel(Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YD,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|err_V2.1.|err_V2.2.|err_V2.3.|err_V3.1.|err_V3.2.|err_V3.3.|err_V4.1.|err_V4.2.|err_V4.3.|err_Theta2.1.|err_Theta2.2.|err_Theta2.3.|err_Theta3.1.|err_Theta3.2.|err_Theta3.3.|err_Theta4.1.|err_Theta4.2.|err_Theta4.3.",fileNamePrefix="Buildings_latest_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YD") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.556e-05/1.556e-05, allocations: 4.109 kB / 1.187 GB, free: 13.38 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YD): time 0.01797/0.01799, allocations: 17.93 MB / 1.205 GB, free: 2.324 MB / 0.9044 GB
Notification: Performance of NFInst.instExpressions: time 0.01072/0.02871, allocations: 6.783 MB / 1.211 GB, free: 13.94 MB / 0.92 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001606/0.03032, allocations: 67.97 kB / 1.211 GB, free: 13.92 MB / 0.92 GB
Notification: Performance of NFTyping.typeComponents: time 0.004053/0.03437, allocations: 1.485 MB / 1.213 GB, free: 13.11 MB / 0.92 GB
Notification: Performance of NFTyping.typeBindings: time 0.003795/0.03816, allocations: 1.466 MB / 1.214 GB, free: 12.29 MB / 0.92 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003266/0.04143, allocations: 1.281 MB / 1.216 GB, free: 11.39 MB / 0.92 GB
Notification: Performance of NFFlatten.flatten: time 0.00599/0.04742, allocations: 4.828 MB / 1.22 GB, free: 8.602 MB / 0.92 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004269/0.05169, allocations: 2.229 MB / 1.222 GB, free: 7.086 MB / 0.92 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003319/0.05501, allocations: 1.886 MB / 1.224 GB, free: 6.004 MB / 0.92 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003273/0.05828, allocations: 1.773 MB / 1.226 GB, free: 4.957 MB / 0.92 GB
Notification: Performance of NFPackage.collectConstants: time 0.0007911/0.05907, allocations: 291.6 kB / 1.226 GB, free: 4.957 MB / 0.92 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0025/0.06157, allocations: 0.8693 MB / 1.227 GB, free: 4.625 MB / 0.92 GB
Notification: Performance of combineBinaries: time 0.005679/0.06725, allocations: 4.499 MB / 1.232 GB, free: 1.484 MB / 0.92 GB
Notification: Performance of replaceArrayConstructors: time 0.002437/0.06969, allocations: 2.509 MB / 1.234 GB, free: 15.74 MB / 0.9356 GB
Notification: Performance of NFVerifyModel.verify: time 0.0005198/0.07021, allocations: 145.3 kB / 1.234 GB, free: 15.73 MB / 0.9356 GB
Notification: Performance of FrontEnd: time 0.0002972/0.0705, allocations: 38.06 kB / 1.234 GB, free: 15.73 MB / 0.9356 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 994 (487)
 * Number of variables: 994 (341)
Notification: Performance of [SIM] Bindings: time 0.01236/0.08287, allocations: 11.93 MB / 1.246 GB, free: 7.094 MB / 0.9356 GB
Notification: Performance of [SIM] FunctionAlias: time 0.001899/0.08477, allocations: 1.725 MB / 1.247 GB, free: 5.324 MB / 0.9356 GB
Notification: Performance of [SIM] Early Inline: time 0.02123/0.106, allocations: 19.12 MB / 1.266 GB, free: 2.5 MB / 0.9512 GB
Notification: Performance of [SIM] Simplify 1: time 0.005649/0.1116, allocations: 3.771 MB / 1.27 GB, free: 14.91 MB / 0.9669 GB
Notification: Performance of [SIM] Alias: time 0.01483/0.1265, allocations: 10.84 MB / 1.28 GB, free: 3.453 MB / 0.9669 GB
Notification: Performance of [SIM] Simplify 2: time 0.005691/0.1322, allocations: 3.568 MB / 1.284 GB, free: 15.96 MB / 0.9825 GB
Notification: Performance of [SIM] Remove Stream: time 0.001537/0.1337, allocations: 1.035 MB / 1.285 GB, free: 14.87 MB / 0.9825 GB
Notification: Performance of [SIM] Detect States: time 0.002866/0.1366, allocations: 2.635 MB / 1.288 GB, free: 12.16 MB / 0.9825 GB
Notification: Performance of [SIM] Events: time 0.001756/0.1383, allocations: 1.356 MB / 1.289 GB, free: 10.82 MB / 0.9825 GB
Notification: Performance of [SIM] Partitioning: time 0.005472/0.1438, allocations: 5.055 MB / 1.294 GB, free: 5.672 MB / 0.9825 GB
Notification: Performance of [SIM] Causalize: time 0.02283/0.1666, allocations: 15.54 MB / 1.309 GB, free: 5.871 MB / 0.9981 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.01398/0.1806, allocations: 12.94 MB / 1.322 GB, free: 8.918 MB / 1.014 GB
Notification: Performance of [INI] Simplify: time 0.009779/0.1904, allocations: 5.769 MB / 1.327 GB, free: 3.023 MB / 1.014 GB
Notification: Performance of [INI] Inline: time 0.02413/0.2145, allocations: 21.68 MB / 1.348 GB, free: 13.28 MB / 1.045 GB
Notification: Performance of [INI] Partitioning: time 0.0006709/0.2152, allocations: 158.7 kB / 1.349 GB, free: 13.09 MB / 1.045 GB
Notification: Performance of [INI] Cleanup: time 0.006279/0.2215, allocations: 3.766 MB / 1.352 GB, free: 9.289 MB / 1.045 GB
Notification: Performance of [INI] Causalize: time 0.045/0.2665, allocations: 29.5 MB / 1.381 GB, free: 11.42 MB / 1.076 GB
Notification: Performance of [INI] Tearing: time 0.002652/0.2691, allocations: 2.1 MB / 1.383 GB, free: 9.359 MB / 1.076 GB
Notification: Performance of [SIM] Initialization: time 1.553e-06/0.2691, allocations: 0 / 1.383 GB, free: 9.359 MB / 1.076 GB
Notification: Performance of [SIM] DAE-Mode: time 0.006798/0.2759, allocations: 5.06 MB / 1.388 GB, free: 4.289 MB / 1.076 GB
Notification: Performance of [SIM] Remove Dummies: time 1.851e-05/0.2759, allocations: 7 kB / 1.388 GB, free: 4.281 MB / 1.076 GB
Notification: Performance of [SIM] Tearing: time 0.001912/0.2778, allocations: 1.741 MB / 1.39 GB, free: 2.57 MB / 1.076 GB
Notification: Performance of [SIM] Categorize: time 5.253e-05/0.2779, allocations: 15.98 kB / 1.39 GB, free: 2.555 MB / 1.076 GB
Notification: Performance of [SIM] Solve: time 0.03445/0.3123, allocations: 25.56 MB / 1.415 GB, free: 9.195 MB / 1.107 GB
Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref transformer.conv2.P_n[2] is neither a seed nor a partial candidate!
"
[Timeout remaining time 300]
[Calling sys.exit(0), Time elapsed: 6.185057044029236]
Failed to read output from testmodel.py, exit status != 0:
0.32601270999293774 0.33978037899999997 0.26631580600000004
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7faef0cb2fb0>