Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildingSystems_BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.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.001664/0.001664, 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.001752/0.001752, allocations: 219.5 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.368/1.368, allocations: 230.7 MB / 256.9 MB, free: 7.723 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo): time 0.002377/0.002377, allocations: 287.1 kB / 313.6 MB, free: 2.555 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo): time 1.2/1.2, allocations: 180.7 MB / 0.5378 GB, free: 14.52 MB / 382.1 MB
"
[Timeout remaining time 179]
Using package BuildingSystems with version 2.0.0-beta (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo)
Using package NcDataReader2 with version 2.5.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 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(BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY")
translateModel(BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.279e-05/1.279e-05, allocations: 12.83 kB / 0.7409 GB, free: 27.87 MB / 0.5294 GB
Notification: Performance of NFInst.instantiate(BuildingSystems.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepUp.YY): time 0.01767/0.01768, allocations: 19.32 MB / 0.7597 GB, free: 15.98 MB / 0.5294 GB
Notification: Performance of NFInst.instExpressions: time 0.01051/0.02819, allocations: 7.07 MB / 0.7667 GB, free: 11.41 MB / 0.5294 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001212/0.0294, allocations: 68.19 kB / 0.7667 GB, free: 11.39 MB / 0.5294 GB
Notification: Performance of NFTyping.typeComponents: time 0.004446/0.03385, allocations: 1.911 MB / 0.7686 GB, free: 10.35 MB / 0.5294 GB
Notification: Performance of NFTyping.typeBindings: time 0.003514/0.03736, allocations: 1.542 MB / 0.7701 GB, free: 9.477 MB / 0.5294 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003056/0.04042, allocations: 1.316 MB / 0.7714 GB, free: 8.766 MB / 0.5294 GB
Notification: Performance of NFFlatten.flatten: time 0.008346/0.04877, allocations: 5.151 MB / 0.7764 GB, free: 6.598 MB / 0.5294 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004106/0.05287, allocations: 2.513 MB / 0.7789 GB, free: 5.184 MB / 0.5294 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003642/0.05651, allocations: 2.017 MB / 0.7808 GB, free: 4.344 MB / 0.5294 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003572/0.06009, allocations: 1.902 MB / 0.7827 GB, free: 3.613 MB / 0.5294 GB
Notification: Performance of NFPackage.collectConstants: time 0.00102/0.06111, allocations: 318.3 kB / 0.783 GB, free: 3.609 MB / 0.5294 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002544/0.06365, allocations: 0.8605 MB / 0.7838 GB, free: 3.402 MB / 0.5294 GB
Notification: Performance of combineBinaries: time 0.004172/0.06782, allocations: 4.833 MB / 0.7886 GB, free: 472 kB / 0.5294 GB
Notification: Performance of replaceArrayConstructors: time 0.002797/0.07062, allocations: 2.716 MB / 0.7912 GB, free: 14.76 MB / 0.545 GB
Notification: Performance of NFVerifyModel.verify: time 0.0006903/0.07131, allocations: 153.4 kB / 0.7914 GB, free: 14.75 MB / 0.545 GB
Notification: Performance of FrontEnd: time 0.0003372/0.07165, allocations: 36.88 kB / 0.7914 GB, free: 14.75 MB / 0.545 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1094 (523)
 * Number of variables: 1094 (377)
Notification: Performance of [SIM] Bindings: time 0.01626/0.08791, allocations: 12.9 MB / 0.804 GB, free: 6.094 MB / 0.545 GB
Notification: Performance of [SIM] FunctionAlias: time 0.002225/0.09013, allocations: 1.858 MB / 0.8058 GB, free: 4.184 MB / 0.545 GB
Notification: Performance of [SIM] Early Inline: time 0.02373/0.1139, allocations: 19.7 MB / 0.825 GB, free: 0.8047 MB / 0.5606 GB
Notification: Performance of [SIM] Simplify 1: time 0.006653/0.1205, allocations: 3.802 MB / 0.8287 GB, free: 13.19 MB / 0.5762 GB
Notification: Performance of [SIM] Alias: time 0.01673/0.1372, allocations: 11.88 MB / 0.8403 GB, free: 0.6172 MB / 0.5762 GB
Notification: Performance of [SIM] Simplify 2: time 0.005412/0.1427, allocations: 3.594 MB / 0.8439 GB, free: 13.23 MB / 0.5919 GB
Notification: Performance of [SIM] Remove Stream: time 0.001476/0.1441, allocations: 1.047 MB / 0.8449 GB, free: 12.13 MB / 0.5919 GB
Notification: Performance of [SIM] Detect States: time 0.003087/0.1472, allocations: 2.74 MB / 0.8476 GB, free: 9.301 MB / 0.5919 GB
Notification: Performance of [SIM] Events: time 0.001768/0.149, allocations: 1.368 MB / 0.8489 GB, free: 7.957 MB / 0.5919 GB
Notification: Performance of [SIM] Partitioning: time 0.00584/0.1548, allocations: 5.182 MB / 0.854 GB, free: 2.676 MB / 0.5919 GB
Notification: Performance of [SIM] Causalize: time 0.0247/0.1795, allocations: 16.36 MB / 0.8699 GB, free: 2.047 MB / 0.6075 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.01495/0.1945, allocations: 13.34 MB / 0.883 GB, free: 4.695 MB / 0.6231 GB
Notification: Performance of [INI] Simplify: time 0.01009/0.2046, allocations: 5.886 MB / 0.8887 GB, free: 14.64 MB / 0.6387 GB
Notification: Performance of [INI] Inline: time 0.02365/0.2282, allocations: 22.36 MB / 0.9105 GB, free: 8.238 MB / 0.6544 GB
Notification: Performance of [INI] Partitioning: time 0.0005822/0.2288, allocations: 163.5 kB / 0.9107 GB, free: 8.039 MB / 0.6544 GB
Notification: Performance of [INI] Cleanup: time 0.006/0.2348, allocations: 3.876 MB / 0.9145 GB, free: 4.133 MB / 0.6544 GB
Notification: Performance of [INI] Causalize: time 0.05148/0.2863, allocations: 30.73 MB / 0.9445 GB, free: 5 MB / 0.6856 GB
Notification: Performance of [INI] Tearing: time 0.002371/0.2887, allocations: 1.931 MB / 0.9464 GB, free: 3.121 MB / 0.6856 GB
Notification: Performance of [SIM] Initialization: time 1.643e-06/0.2887, allocations: 3.938 kB / 0.9464 GB, free: 3.117 MB / 0.6856 GB
Notification: Performance of [SIM] DAE-Mode: time 0.007581/0.2962, allocations: 5.065 MB / 0.9513 GB, free: 14.04 MB / 0.7012 GB
Notification: Performance of [SIM] Remove Dummies: time 2.516e-05/0.2963, allocations: 14.94 kB / 0.9513 GB, free: 14.03 MB / 0.7012 GB
Notification: Performance of [SIM] Tearing: time 0.002109/0.2984, allocations: 1.711 MB / 0.953 GB, free: 12.34 MB / 0.7012 GB
Notification: Performance of [SIM] Categorize: time 6.687e-05/0.2984, allocations: 15.94 kB / 0.953 GB, free: 12.33 MB / 0.7012 GB
Notification: Performance of [SIM] Solve: time 0.03833/0.3368, allocations: 25.85 MB / 0.9783 GB, free: 2.691 MB / 0.7169 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 659]
[Calling sys.exit(0), Time elapsed: 4.4923823429853655]
Failed to read output from testmodel.py, exit status != 0:
0.7051496980129741 0.7168342350000001 0.642487527
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7febb121afb0>