Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI.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.001734/0.001734, allocations: 100.4 kB / 19.76 MB, free: 1.906 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.503/1.503, allocations: 230.7 MB / 253.5 MB, free: 14.24 MB / 206.1 MB
"
[Timeout remaining time 178]
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.001545/0.001545, allocations: 200.1 kB / 310.1 MB, free: 3.012 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo): time 0.4993/0.4993, allocations: 79.74 MB / 446.3 MB, free: 40.46 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/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 Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 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(OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=20000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=20000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.892e-05/1.892e-05, allocations: 2.938 kB / 0.5942 GB, free: 38.42 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI): time 0.01068/0.0107, allocations: 11.61 MB / 0.6055 GB, free: 31.51 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.007813/0.01851, allocations: 3.504 MB / 0.609 GB, free: 29.05 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003608/0.02212, allocations: 31.59 kB / 0.609 GB, free: 29.05 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002011/0.02413, allocations: 0.5661 MB / 0.6095 GB, free: 28.74 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.005786/0.02992, allocations: 1.564 MB / 0.6111 GB, free: 27.7 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003791/0.03371, allocations: 1.565 MB / 0.6126 GB, free: 26.91 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.005229/0.03894, allocations: 4.632 MB / 0.6171 GB, free: 24.64 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0009184/0.03985, allocations: 0.4975 MB / 0.6176 GB, free: 24.34 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002075/0.04193, allocations: 1.768 MB / 0.6193 GB, free: 23.47 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001437/0.04337, allocations: 1.816 MB / 0.6211 GB, free: 22.65 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003399/0.04371, allocations: 247.7 kB / 0.6213 GB, free: 22.65 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0008737/0.04458, allocations: 440.5 kB / 0.6218 GB, free: 22.61 MB / 446.1 MB
Notification: Performance of combineBinaries: time 0.003727/0.04831, allocations: 7.543 MB / 0.6291 GB, free: 17.51 MB / 446.1 MB
Notification: Performance of replaceArrayConstructors: time 0.002177/0.05049, allocations: 4.706 MB / 0.6337 GB, free: 14.18 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0005389/0.05102, allocations: 211.1 kB / 0.6339 GB, free: 14.18 MB / 446.1 MB
Notification: Performance of FrontEnd: time 0.0004838/0.05151, allocations: 85.53 kB / 0.634 GB, free: 14.16 MB / 446.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 519 (481)
 * Number of variables: 519 (515)
Notification: Performance of [SIM] Bindings: time 0.01117/0.06267, allocations: 17.01 MB / 0.6506 GB, free: 2.863 MB / 446.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.002122/0.0648, allocations: 2.073 MB / 0.6526 GB, free: 1.625 MB / 446.1 MB
Notification: Performance of [SIM] Early Inline: time 0.02543/0.09022, allocations: 20.21 MB / 0.6724 GB, free: 7.297 MB / 462.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.005797/0.09602, allocations: 2.594 MB / 0.6749 GB, free: 6.125 MB / 462.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.02038/0.1164, allocations: 12.34 MB / 0.687 GB, free: 9.609 MB / 478.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.003831/0.1202, allocations: 2.378 MB / 0.6893 GB, free: 7.16 MB / 478.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.001291/0.1215, allocations: 1.001 MB / 0.6903 GB, free: 6.102 MB / 478.1 MB
Notification: Performance of [SIM] Detect States: time 0.002988/0.1245, allocations: 2.75 MB / 0.6929 GB, free: 3.266 MB / 478.1 MB
Notification: Performance of [SIM] Events: time 0.002098/0.1266, allocations: 1.296 MB / 0.6942 GB, free: 1.957 MB / 478.1 MB
Notification: Performance of [SIM] Partitioning: time 0.005746/0.1323, allocations: 4.491 MB / 0.6986 GB, free: 13.41 MB / 494.1 MB
Notification: Performance of [SIM] Causalize: time 0.01354/0.1459, allocations: 8.034 MB / 0.7064 GB, free: 5.277 MB / 494.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.01117/0.1571, allocations: 9.331 MB / 0.7156 GB, free: 11.94 MB / 0.4981 GB
Notification: Performance of [INI] Simplify: time 0.0135/0.1706, allocations: 6.832 MB / 0.7222 GB, free: 4.852 MB / 0.4981 GB
Notification: Performance of [INI] Inline: time 0.02557/0.1961, allocations: 19.02 MB / 0.7408 GB, free: 1.785 MB / 0.5137 GB
Notification: Performance of [INI] Partitioning: time 0.0009648/0.1971, allocations: 226 kB / 0.741 GB, free: 1.512 MB / 0.5137 GB
Notification: Performance of [INI] Cleanup: time 0.005832/0.2029, allocations: 3.741 MB / 0.7447 GB, free: 13.75 MB / 0.5294 GB
Notification: Performance of [INI] Causalize: time 0.09349/0.2964, allocations: 47.72 MB / 0.7913 GB, free: 13.27 MB / 0.5762 GB
Notification: Performance of [INI] Tearing: time 0.0127/0.3091, allocations: 9.834 MB / 0.8009 GB, free: 3.883 MB / 0.5762 GB
Notification: Performance of [SIM] Initialization: time 2.525e-06/0.3091, allocations: 0 / 0.8009 GB, free: 3.883 MB / 0.5762 GB
Notification: Performance of [SIM] DAE-Mode: time 0.008613/0.3177, allocations: 6.005 MB / 0.8067 GB, free: 13.98 MB / 0.5919 GB
Notification: Performance of [SIM] Remove Dummies: time 3.029e-05/0.3178, allocations: 2 kB / 0.8067 GB, free: 13.98 MB / 0.5919 GB
Notification: Performance of [SIM] Tearing: time 0.00402/0.3218, allocations: 2.85 MB / 0.8095 GB, free: 11.12 MB / 0.5919 GB
Notification: Performance of [SIM] Categorize: time 4.603e-05/0.3218, allocations: 15.94 kB / 0.8095 GB, free: 11.11 MB / 0.5919 GB
Notification: Performance of [SIM] Solve: time 0.2596/0.5814, allocations: 18.09 MB / 0.8272 GB, free: 143 MB / 0.5919 GB
Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref Exc1.vref0 is neither a seed nor a partial candidate!
"
[Timeout remaining time 659]
[Calling sys.exit(0), Time elapsed: 3.7440214510424994]
Failed to read output from testmodel.py, exit status != 0:
0.5928385829902254 0.60311278 0.549284329
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fc523e46fb0>