Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.YY.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.001433/0.001433, allocations: 104.3 kB / 19.77 MB, free: 2.051 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.001522/0.001522, allocations: 214.2 kB / 23.07 MB, free: 4.844 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.293/1.293, allocations: 230.7 MB / 256.9 MB, free: 7.77 MB / 206.1 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo): time 2.796/2.796, allocations: 0.4893 GB / 0.7952 GB, free: 35.71 MB / 0.6231 GB " [Timeout remaining time 177] Using package Buildings with version 12.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/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.BalancedStepUp.YY,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_12_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.YY") translateModel(Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.YY,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_12_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.YY") [Timeout 300] "Notification: Performance of FrontEnd - Absyn->SCode: time 1.104e-05/1.104e-05, allocations: 2.281 kB / 1.155 GB, free: 25.48 MB / 0.9044 GB Notification: Performance of NFInst.instantiate(Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.BalancedStepUp.YY): time 0.01791/0.01792, allocations: 19.27 MB / 1.173 GB, free: 13.64 MB / 0.9044 GB Notification: Performance of NFInst.instExpressions: time 0.00994/0.02786, allocations: 7.141 MB / 1.18 GB, free: 9.059 MB / 0.9044 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.002141/0.03, allocations: 67.34 kB / 1.18 GB, free: 9.043 MB / 0.9044 GB Notification: Performance of NFTyping.typeComponents: time 0.004314/0.03432, allocations: 1.896 MB / 1.182 GB, free: 8.008 MB / 0.9044 GB Notification: Performance of NFTyping.typeBindings: time 0.003334/0.03765, allocations: 1.552 MB / 1.184 GB, free: 7.137 MB / 0.9044 GB Notification: Performance of NFTyping.typeClassSections: time 0.002908/0.04056, allocations: 1.309 MB / 1.185 GB, free: 6.43 MB / 0.9044 GB Notification: Performance of NFFlatten.flatten: time 0.005309/0.04587, allocations: 5.127 MB / 1.19 GB, free: 4.27 MB / 0.9044 GB Notification: Performance of NFFlatten.resolveConnections: time 0.003853/0.04972, allocations: 2.454 MB / 1.192 GB, free: 2.898 MB / 0.9044 GB Notification: Performance of NFEvalConstants.evaluate: time 0.003168/0.05289, allocations: 2.007 MB / 1.194 GB, free: 2.062 MB / 0.9044 GB Notification: Performance of NFSimplifyModel.simplify: time 0.003158/0.05605, allocations: 1.885 MB / 1.196 GB, free: 1.344 MB / 0.9044 GB Notification: Performance of NFPackage.collectConstants: time 0.0008812/0.05693, allocations: 310.7 kB / 1.197 GB, free: 1.344 MB / 0.9044 GB Notification: Performance of NFFlatten.collectFunctions: time 0.002557/0.05949, allocations: 0.8969 MB / 1.197 GB, free: 1.113 MB / 0.9044 GB Notification: Performance of combineBinaries: time 0.005321/0.06481, allocations: 4.745 MB / 1.202 GB, free: 14.23 MB / 0.92 GB Notification: Performance of replaceArrayConstructors: time 0.002378/0.06719, allocations: 2.656 MB / 1.205 GB, free: 12.57 MB / 0.92 GB Notification: Performance of NFVerifyModel.verify: time 0.0004563/0.06764, allocations: 155.1 kB / 1.205 GB, free: 12.56 MB / 0.92 GB Notification: Performance of FrontEnd: time 0.0002201/0.06786, allocations: 35.22 kB / 1.205 GB, free: 12.56 MB / 0.92 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 1083 (512) * Number of variables: 1083 (366) Notification: Performance of [SIM] Bindings: time 0.01327/0.08113, allocations: 12.7 MB / 1.217 GB, free: 4.879 MB / 0.92 GB Notification: Performance of [SIM] FunctionAlias: time 0.002099/0.08323, allocations: 1.877 MB / 1.219 GB, free: 3.746 MB / 0.92 GB Notification: Performance of [SIM] Early Inline: time 0.0216/0.1048, allocations: 19.61 MB / 1.238 GB, free: 8.453 MB / 0.9356 GB Notification: Performance of [SIM] Simplify 1: time 0.005348/0.1102, allocations: 3.793 MB / 1.242 GB, free: 6.316 MB / 0.9356 GB Notification: Performance of [SIM] Alias: time 0.01446/0.1246, allocations: 11.67 MB / 1.253 GB, free: 12.3 MB / 0.9512 GB Notification: Performance of [SIM] Simplify 2: time 0.004727/0.1294, allocations: 3.599 MB / 1.257 GB, free: 8.938 MB / 0.9512 GB Notification: Performance of [SIM] Remove Stream: time 0.001249/0.1306, allocations: 1.051 MB / 1.258 GB, free: 7.832 MB / 0.9512 GB Notification: Performance of [SIM] Detect States: time 0.002692/0.1333, allocations: 2.715 MB / 1.26 GB, free: 5.023 MB / 0.9512 GB Notification: Performance of [SIM] Events: time 0.00161/0.1349, allocations: 1.398 MB / 1.262 GB, free: 3.707 MB / 0.9512 GB Notification: Performance of [SIM] Partitioning: time 0.005415/0.1403, allocations: 5.19 MB / 1.267 GB, free: 14.43 MB / 0.9669 GB Notification: Performance of [SIM] Causalize: time 0.02234/0.1627, allocations: 16.36 MB / 1.283 GB, free: 13.79 MB / 0.9825 GB Notification: Performance of [SIM] After Index Reduction Inline: time 0.01409/0.1768, allocations: 13.35 MB / 1.296 GB, free: 444 kB / 0.9825 GB Notification: Performance of [INI] Simplify: time 0.008578/0.1853, allocations: 5.793 MB / 1.302 GB, free: 10.6 MB / 0.9981 GB Notification: Performance of [INI] Inline: time 0.02128/0.2066, allocations: 22.34 MB / 1.323 GB, free: 4.215 MB / 1.014 GB Notification: Performance of [INI] Partitioning: time 0.0004948/0.2071, allocations: 155.2 kB / 1.324 GB, free: 4.02 MB / 1.014 GB Notification: Performance of [INI] Cleanup: time 0.004379/0.2115, allocations: 3.868 MB / 1.327 GB, free: 128 kB / 1.014 GB Notification: Performance of [INI] Causalize: time 0.0447/0.2562, allocations: 30.65 MB / 1.357 GB, free: 1.055 MB / 1.045 GB Notification: Performance of [INI] Tearing: time 0.002234/0.2584, allocations: 1.91 MB / 1.359 GB, free: 15.2 MB / 1.061 GB Notification: Performance of [SIM] Initialization: time 1.202e-06/0.2584, allocations: 0 / 1.359 GB, free: 15.2 MB / 1.061 GB Notification: Performance of [SIM] DAE-Mode: time 0.007046/0.2655, allocations: 5.091 MB / 1.364 GB, free: 10.09 MB / 1.061 GB Notification: Performance of [SIM] Remove Dummies: time 1.92e-05/0.2655, allocations: 3 kB / 1.364 GB, free: 10.09 MB / 1.061 GB Notification: Performance of [SIM] Tearing: time 0.001908/0.2674, allocations: 1.712 MB / 1.366 GB, free: 8.402 MB / 1.061 GB Notification: Performance of [SIM] Categorize: time 4.814e-05/0.2675, allocations: 23.86 kB / 1.366 GB, free: 8.379 MB / 1.061 GB Notification: Performance of [SIM] Solve: time 0.03485/0.3023, allocations: 25.71 MB / 1.391 GB, free: 14.99 MB / 1.092 GB Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref node4.term.phase[1].i[1] is neither a seed nor a partial candidate! " [Timeout remaining time 300] [Calling sys.exit(0), Time elapsed: 5.844548446009867] Failed to read output from testmodel.py, exit status != 0: 0.31673880998278037 0.330575357 0.259866392 Calling exit ...