Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerSystems_latest_PowerSystems.Examples.AC1ph_DC.Transformation.TapChanger.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.001414/0.001414, 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.001546/0.001546, allocations: 212.2 kB / 24.55 MB, free: 3.746 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.476/1.476, allocations: 230.7 MB / 258.4 MB, free: 7.73 MB / 206.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems master/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems master/package.mo): time 0.1833/0.1833, allocations: 38.55 MB / 353.3 MB, free: 12.27 MB / 286.1 MB " [Timeout remaining time 179] Using package PowerSystems with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 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(PowerSystems.Examples.AC1ph_DC.Transformation.TapChanger,tolerance=1e-06,outputFormat="mat",numberOfIntervals=4000,variableFilter="Time|meter1.i|meter1.p|meter1.v|meter12.i|meter12.p|meter12.v|meter22.i|meter22.p|meter22.v",fileNamePrefix="PowerSystems_latest_PowerSystems.Examples.AC1ph_DC.Transformation.TapChanger") translateModel(PowerSystems.Examples.AC1ph_DC.Transformation.TapChanger,tolerance=1e-06,outputFormat="mat",numberOfIntervals=4000,variableFilter="Time|meter1.i|meter1.p|meter1.v|meter12.i|meter12.p|meter12.v|meter22.i|meter22.p|meter22.v",fileNamePrefix="PowerSystems_latest_PowerSystems.Examples.AC1ph_DC.Transformation.TapChanger") [Timeout 660] "Notification: Performance of FrontEnd - Absyn->SCode: time 3.388e-05/3.388e-05, allocations: 2.281 kB / 493.8 MB, free: 4.188 MB / 350.1 MB Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC1ph_DC.Transformation.TapChanger): time 0.01094/0.01098, allocations: 6.938 MB / 0.489 GB, free: 13.21 MB / 366.1 MB Notification: Performance of NFInst.instExpressions: time 0.003643/0.01462, allocations: 1.667 MB / 0.4906 GB, free: 11.54 MB / 366.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.0003709/0.01499, allocations: 39.62 kB / 0.4907 GB, free: 11.5 MB / 366.1 MB Notification: Performance of NFTyping.typeComponents: time 0.0007272/0.01572, allocations: 222.5 kB / 0.4909 GB, free: 11.28 MB / 366.1 MB Notification: Performance of NFTyping.typeBindings: time 0.001303/0.01702, allocations: 0.5833 MB / 0.4915 GB, free: 10.7 MB / 366.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.0004492/0.01747, allocations: 219.6 kB / 0.4917 GB, free: 10.48 MB / 366.1 MB Notification: Performance of NFFlatten.flatten: time 0.001578/0.01905, allocations: 1.488 MB / 0.4931 GB, free: 8.988 MB / 366.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.0003938/0.01944, allocations: 184.2 kB / 0.4933 GB, free: 8.805 MB / 366.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.0009796/0.02042, allocations: 0.6342 MB / 0.4939 GB, free: 8.168 MB / 366.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.000774/0.02119, allocations: 0.5292 MB / 0.4944 GB, free: 7.637 MB / 366.1 MB Notification: Performance of NFPackage.collectConstants: time 9.607e-05/0.02129, allocations: 56 kB / 0.4945 GB, free: 7.582 MB / 366.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.0003335/0.02162, allocations: 171.4 kB / 0.4947 GB, free: 7.414 MB / 366.1 MB Notification: Performance of combineBinaries: time 0.001414/0.02304, allocations: 1.513 MB / 0.4961 GB, free: 5.887 MB / 366.1 MB Notification: Performance of replaceArrayConstructors: time 0.000747/0.02379, allocations: 0.9692 MB / 0.4971 GB, free: 4.906 MB / 366.1 MB Notification: Performance of NFVerifyModel.verify: time 0.0001278/0.02391, allocations: 68 kB / 0.4971 GB, free: 4.84 MB / 366.1 MB Notification: Performance of FrontEnd: time 0.0001037/0.02402, allocations: 23.88 kB / 0.4972 GB, free: 4.816 MB / 366.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 166 (134) * Number of variables: 166 (121) Notification: Performance of [SIM] Bindings: time 0.004023/0.02804, allocations: 3.823 MB / 0.5009 GB, free: 0.8203 MB / 366.1 MB Notification: Performance of [SIM] FunctionAlias: time 0.0005048/0.02854, allocations: 387.5 kB / 0.5013 GB, free: 428 kB / 366.1 MB Notification: Performance of [SIM] Early Inline: time 0.001699/0.03024, allocations: 1.647 MB / 0.5029 GB, free: 14.73 MB / 382.1 MB Notification: Performance of [SIM] Simplify 1: time 0.0006139/0.03086, allocations: 404.4 kB / 0.5033 GB, free: 14.27 MB / 382.1 MB Warning: NBAlias.setStartFixed: Alias set with conflicting unfixed start values detected. Use -d=dumprepl for more information. Notification: Performance of [SIM] Alias: time 0.003178/0.03404, allocations: 2.143 MB / 0.5054 GB, free: 11.86 MB / 382.1 MB Notification: Performance of [SIM] Simplify 2: time 0.0004994/0.03454, allocations: 356.4 kB / 0.5057 GB, free: 11.46 MB / 382.1 MB Notification: Performance of [SIM] Remove Stream: time 0.0002494/0.03478, allocations: 196.1 kB / 0.5059 GB, free: 11.24 MB / 382.1 MB Notification: Performance of [SIM] Detect States: time 0.0006175/0.0354, allocations: 443.2 kB / 0.5063 GB, free: 10.75 MB / 382.1 MB Notification: Performance of [SIM] Events: time 0.0001608/0.03556, allocations: 87.8 kB / 0.5064 GB, free: 10.66 MB / 382.1 MB Notification: Performance of [SIM] Partitioning: time 0.0008621/0.03643, allocations: 0.7024 MB / 0.5071 GB, free: 9.949 MB / 382.1 MB Notification: Performance of [SIM] Causalize: time 0.002572/0.039, allocations: 1.793 MB / 0.5088 GB, free: 8.129 MB / 382.1 MB Notification: Performance of [SIM] After Index Reduction Inline: time 0.001398/0.04039, allocations: 1.35 MB / 0.5101 GB, free: 6.777 MB / 382.1 MB Notification: Performance of [INI] Simplify: time 0.001378/0.04177, allocations: 0.7219 MB / 0.5108 GB, free: 5.98 MB / 382.1 MB Notification: Performance of [INI] Inline: time 0.002173/0.04395, allocations: 2.041 MB / 0.5128 GB, free: 3.938 MB / 382.1 MB Notification: Performance of [INI] Partitioning: time 8.656e-05/0.04403, allocations: 43.98 kB / 0.5129 GB, free: 3.883 MB / 382.1 MB Notification: Performance of [INI] Cleanup: time 0.0003507/0.04438, allocations: 335.7 kB / 0.5132 GB, free: 3.555 MB / 382.1 MB Notification: Performance of [INI] Causalize: time 0.004843/0.04923, allocations: 3.493 MB / 0.5166 GB, free: 16 kB / 382.1 MB Notification: Performance of [INI] Tearing: time 0.274/0.3233, allocations: 220 kB / 0.5168 GB, free: 32.3 MB / 382.1 MB Notification: Performance of [SIM] Initialization: time 3.146e-06/0.3233, allocations: 4.25 kB / 0.5168 GB, free: 32.3 MB / 382.1 MB Notification: Performance of [SIM] DAE-Mode: time 0.001019/0.3243, allocations: 481.5 kB / 0.5173 GB, free: 32.27 MB / 382.1 MB Notification: Performance of [SIM] Remove Dummies: time 8.676e-06/0.3243, allocations: 3.625 kB / 0.5173 GB, free: 32.27 MB / 382.1 MB Notification: Performance of [SIM] Tearing: time 0.000128/0.3244, allocations: 169.8 kB / 0.5175 GB, free: 32.25 MB / 382.1 MB Notification: Performance of [SIM] Categorize: time 1.989e-05/0.3244, allocations: 4.125 kB / 0.5175 GB, free: 32.25 MB / 382.1 MB Notification: Performance of [SIM] Solve: time 0.003201/0.3276, allocations: 1.823 MB / 0.5192 GB, free: 32.02 MB / 382.1 MB Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref voltage.term.i[1] is neither a seed nor a partial candidate! " [Timeout remaining time 660] [Calling sys.exit(0), Time elapsed: 3.0640461500152014] Failed to read output from testmodel.py, exit status != 0: 0.337150224018842 0.349890348 0.322873105 Calling exit ...