Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.Tutorial.Example_3.Example_3.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.001701/0.001701, 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/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.499/1.499, allocations: 230.7 MB / 253.5 MB, free: 14.25 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.001588/0.001588, allocations: 214.8 kB / 310.1 MB, free: 3.098 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo): time 0.6224/0.6224, allocations: 107.2 MB / 473.7 MB, free: 30 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.1.0-dev (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/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.Tutorial.Example_3.Example_3,tolerance=1e-06,outputFormat="empty",numberOfIntervals=20000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.Tutorial.Example_3.Example_3")
translateModel(OpenIPSL.Examples.Tutorial.Example_3.Example_3,tolerance=1e-06,outputFormat="empty",numberOfIntervals=20000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.Tutorial.Example_3.Example_3") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.15e-05/2.15e-05, allocations: 2.281 kB / 0.6343 GB, free: 29.54 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.Tutorial.Example_3.Example_3): time 0.01072/0.01074, allocations: 11.5 MB / 0.6455 GB, free: 21.77 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.006972/0.01771, allocations: 3.586 MB / 0.649 GB, free: 19.19 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001826/0.01954, allocations: 27.31 kB / 0.6491 GB, free: 19.18 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001167/0.0207, allocations: 0.5068 MB / 0.6496 GB, free: 18.89 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003489/0.02419, allocations: 1.565 MB / 0.6511 GB, free: 17.82 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002694/0.02689, allocations: 1.346 MB / 0.6524 GB, free: 17.09 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004525/0.03141, allocations: 4.919 MB / 0.6572 GB, free: 14.03 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008886/0.0323, allocations: 0.5516 MB / 0.6578 GB, free: 13.63 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001957/0.03426, allocations: 1.788 MB / 0.6595 GB, free: 12.5 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001574/0.03583, allocations: 1.735 MB / 0.6612 GB, free: 11.28 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004744/0.03631, allocations: 338.1 kB / 0.6615 GB, free: 11.28 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0008811/0.03719, allocations: 0.5121 MB / 0.662 GB, free: 11.02 MB / 446.1 MB
Notification: Performance of combineBinaries: time 0.003122/0.04031, allocations: 7.552 MB / 0.6694 GB, free: 3.398 MB / 446.1 MB
Notification: Performance of replaceArrayConstructors: time 0.002029/0.04234, allocations: 4.546 MB / 0.6738 GB, free: 14.79 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0004804/0.04282, allocations: 208 kB / 0.674 GB, free: 14.59 MB / 462.1 MB
Notification: Performance of FrontEnd: time 0.0004059/0.04323, allocations: 95.56 kB / 0.6741 GB, free: 14.49 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 463 (445)
 * Number of variables: 463 (463)
Notification: Performance of [SIM] Bindings: time 0.01564/0.05886, allocations: 16.02 MB / 0.6898 GB, free: 14.07 MB / 478.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.002387/0.06125, allocations: 1.918 MB / 0.6916 GB, free: 12.11 MB / 478.1 MB
Notification: Performance of [SIM] Early Inline: time 0.01456/0.07581, allocations: 13.28 MB / 0.7046 GB, free: 14.77 MB / 494.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.003474/0.07929, allocations: 1.864 MB / 0.7064 GB, free: 12.79 MB / 494.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.01548/0.09477, allocations: 11.07 MB / 0.7172 GB, free: 0.6484 MB / 494.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.002513/0.09728, allocations: 1.656 MB / 0.7189 GB, free: 14.89 MB / 0.4981 GB
Notification: Performance of [SIM] Remove Stream: time 0.0009644/0.09825, allocations: 0.803 MB / 0.7196 GB, free: 14.02 MB / 0.4981 GB
Notification: Performance of [SIM] Detect States: time 0.002309/0.1006, allocations: 2.168 MB / 0.7218 GB, free: 11.77 MB / 0.4981 GB
Notification: Performance of [SIM] Events: time 0.001227/0.1018, allocations: 0.8362 MB / 0.7226 GB, free: 10.91 MB / 0.4981 GB
Notification: Performance of [SIM] Partitioning: time 0.0039/0.1057, allocations: 3.413 MB / 0.7259 GB, free: 7.457 MB / 0.4981 GB
Notification: Performance of [SIM] Causalize: time 0.009542/0.1152, allocations: 6.436 MB / 0.7322 GB, free: 0.9062 MB / 0.4981 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.007288/0.1225, allocations: 7.002 MB / 0.739 GB, free: 9.898 MB / 0.5137 GB
Notification: Performance of [INI] Simplify: time 0.01198/0.1345, allocations: 6.565 MB / 0.7454 GB, free: 3.051 MB / 0.5137 GB
Notification: Performance of [INI] Inline: time 0.01746/0.152, allocations: 15.64 MB / 0.7607 GB, free: 3.367 MB / 0.5294 GB
Notification: Performance of [INI] Partitioning: time 0.0006915/0.1526, allocations: 225.1 kB / 0.7609 GB, free: 3.098 MB / 0.5294 GB
Notification: Performance of [INI] Cleanup: time 0.002692/0.1553, allocations: 2.111 MB / 0.763 GB, free: 0.9805 MB / 0.5294 GB
Notification: Performance of [INI] Causalize: time 0.05006/0.2054, allocations: 22.93 MB / 0.7854 GB, free: 9.641 MB / 0.5606 GB
Notification: Performance of [INI] Tearing: time 0.005888/0.2113, allocations: 2.529 MB / 0.7879 GB, free: 7.184 MB / 0.5606 GB
Notification: Performance of [SIM] Initialization: time 2.915e-06/0.2113, allocations: 0 / 0.7879 GB, free: 7.184 MB / 0.5606 GB
Notification: Performance of [SIM] DAE-Mode: time 0.009748/0.221, allocations: 3.603 MB / 0.7914 GB, free: 3.625 MB / 0.5606 GB
Notification: Performance of [SIM] Remove Dummies: time 3.099e-05/0.2211, allocations: 2 kB / 0.7914 GB, free: 3.621 MB / 0.5606 GB
Notification: Performance of [SIM] Tearing: time 0.004657/0.2257, allocations: 1.887 MB / 0.7932 GB, free: 1.785 MB / 0.5606 GB
Notification: Performance of [SIM] Categorize: time 5.204e-05/0.2258, allocations: 12 kB / 0.7932 GB, free: 1.773 MB / 0.5606 GB
Notification: Performance of [SIM] Solve: time 0.03843/0.2642, allocations: 14.25 MB / 0.8071 GB, free: 3.582 MB / 0.5762 GB
Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref gen2.AVR.vref0 is neither a seed nor a partial candidate!
"
[Timeout remaining time 660]
[Calling sys.exit(0), Time elapsed: 3.8540441359509714]
Failed to read output from testmodel.py, exit status != 0:
0.2781464559957385 0.588821401 0.5429983310000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f740038afb0>