Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.0.0_ModelicaTest.Blocks.Continuous_InitialState.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.001951/0.001951, allocations: 103.6 kB / 21.25 MB, free: 0.5664 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.001957/0.001957, allocations: 211 kB / 24.55 MB, free: 3.824 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.488/1.488, allocations: 230.6 MB / 258.3 MB, free: 7.75 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.0.0+maint.om/package.mo): time 0.2156/0.2156, allocations: 44.94 MB / 359.7 MB, free: 5.984 MB / 286.1 MB
"
[Timeout remaining time 180]
Using package ModelicaTest with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.0.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 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(ModelicaTest.Blocks.Continuous_InitialState,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5007,variableFilter="time|criticalDamping.x.1.|criticalDamping.x.2.|derivative.x|firstOrder.y|integrator.y|limIntegrator.y|limPID.D.x|limPID.I.y|lowpassButterworth.x1.1.|lowpassButterworth.x2.1.|pI.x|pID.D.x|pID.I.y|secondOrder.y|secondOrder.yd|stateSpace.x.1.|stateSpace.x.2.|transferFunction.x_scaled.1.",fileNamePrefix="ModelicaTest_4.0.0_ModelicaTest.Blocks.Continuous_InitialState")
translateModel(ModelicaTest.Blocks.Continuous_InitialState,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5007,variableFilter="time|criticalDamping.x.1.|criticalDamping.x.2.|derivative.x|firstOrder.y|integrator.y|limIntegrator.y|limPID.D.x|limPID.I.y|lowpassButterworth.x1.1.|lowpassButterworth.x2.1.|pI.x|pID.D.x|pID.I.y|secondOrder.y|secondOrder.yd|stateSpace.x.1.|stateSpace.x.2.|transferFunction.x_scaled.1.",fileNamePrefix="ModelicaTest_4.0.0_ModelicaTest.Blocks.Continuous_InitialState") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.92e-05/1.92e-05, allocations: 2.281 kB / 0.4943 GB, free: 10 MB / 366.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Blocks.Continuous_InitialState): time 0.004867/0.004886, allocations: 3.084 MB / 0.4973 GB, free: 6.898 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.003492/0.008378, allocations: 1.551 MB / 0.4989 GB, free: 5.34 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0004032/0.008781, allocations: 27.75 kB / 0.4989 GB, free: 5.312 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0005575/0.009338, allocations: 150.7 kB / 0.499 GB, free: 5.164 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0004939/0.009832, allocations: 198.6 kB / 0.4992 GB, free: 4.969 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001324/0.01116, allocations: 0.5608 MB / 0.4998 GB, free: 4.41 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.001439/0.0126, allocations: 1.244 MB / 0.501 GB, free: 3.164 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.00035/0.01295, allocations: 155 kB / 0.5011 GB, free: 2.996 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0007586/0.0137, allocations: 0.5178 MB / 0.5016 GB, free: 2.477 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0004358/0.01414, allocations: 350.1 kB / 0.502 GB, free: 2.133 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001059/0.01425, allocations: 63.94 kB / 0.502 GB, free: 2.07 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0001525/0.0144, allocations: 75.98 kB / 0.5021 GB, free: 1.996 MB / 366.1 MB
Notification: Performance of combineBinaries: time 0.001233/0.01563, allocations: 1.461 MB / 0.5035 GB, free: 0.5195 MB / 366.1 MB
Notification: Performance of replaceArrayConstructors: time 0.000742/0.01637, allocations: 0.9753 MB / 0.5045 GB, free: 15.53 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 7.831e-05/0.01645, allocations: 36 kB / 0.5045 GB, free: 15.49 MB / 382.1 MB
Notification: Performance of FrontEnd: time 0.0001661/0.01662, allocations: 51.66 kB / 0.5046 GB, free: 15.44 MB / 382.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 110 (109)
 * Number of variables: 110 (107)
Notification: Performance of [SIM] Bindings: time 0.00377/0.02039, allocations: 3.382 MB / 0.5079 GB, free: 11.88 MB / 382.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0004365/0.02082, allocations: 363.5 kB / 0.5082 GB, free: 11.51 MB / 382.1 MB
Notification: Performance of [SIM] Early Inline: time 0.001734/0.02256, allocations: 1.62 MB / 0.5098 GB, free: 9.863 MB / 382.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.000578/0.02314, allocations: 347 kB / 0.5101 GB, free: 9.469 MB / 382.1 MB
Notification: Performance of [SIM] Alias: time 0.003199/0.02633, allocations: 2.26 MB / 0.5123 GB, free: 6.91 MB / 382.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0004243/0.02676, allocations: 284.5 kB / 0.5126 GB, free: 6.582 MB / 382.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001888/0.02695, allocations: 154.8 kB / 0.5128 GB, free: 6.402 MB / 382.1 MB
Notification: Performance of [SIM] Detect States: time 0.0005937/0.02754, allocations: 461.8 kB / 0.5132 GB, free: 5.887 MB / 382.1 MB
Notification: Performance of [SIM] Events: time 0.0002609/0.0278, allocations: 131.6 kB / 0.5133 GB, free: 5.758 MB / 382.1 MB
Notification: Performance of [SIM] Partitioning: time 0.000675/0.02848, allocations: 0.5491 MB / 0.5139 GB, free: 5.164 MB / 382.1 MB
Notification: Performance of [SIM] Causalize: time 0.00177/0.03025, allocations: 1.245 MB / 0.5151 GB, free: 3.828 MB / 382.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.001086/0.03133, allocations: 1.086 MB / 0.5161 GB, free: 2.742 MB / 382.1 MB
Notification: Performance of [INI] Simplify: time 0.001437/0.03277, allocations: 0.8295 MB / 0.5169 GB, free: 1.824 MB / 382.1 MB
Notification: Performance of [INI] Inline: time 0.239/0.2718, allocations: 1.924 MB / 0.5188 GB, free: 28.5 MB / 382.1 MB
Notification: Performance of [INI] Partitioning: time 0.000213/0.272, allocations: 38.41 kB / 0.5189 GB, free: 28.49 MB / 382.1 MB
Notification: Performance of [INI] Cleanup: time 0.0006808/0.2727, allocations: 422 kB / 0.5193 GB, free: 28.48 MB / 382.1 MB
Notification: Performance of [INI] Causalize: time 0.00521/0.2779, allocations: 3.313 MB / 0.5225 GB, free: 28.27 MB / 382.1 MB
Notification: Performance of [INI] Tearing: time 9.059e-05/0.278, allocations: 110.8 kB / 0.5226 GB, free: 28.22 MB / 382.1 MB
Notification: Performance of [SIM] Initialization: time 4.007e-06/0.278, allocations: 2 kB / 0.5226 GB, free: 28.22 MB / 382.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.0008032/0.2788, allocations: 473.1 kB / 0.5231 GB, free: 28.11 MB / 382.1 MB
Notification: Performance of [SIM] Remove Dummies: time 8.747e-06/0.2788, allocations: 1.375 kB / 0.5231 GB, free: 28.11 MB / 382.1 MB
Notification: Performance of [SIM] Tearing: time 3.518e-05/0.2788, allocations: 59.31 kB / 0.5231 GB, free: 28.08 MB / 382.1 MB
Notification: Performance of [SIM] Categorize: time 2.165e-05/0.2788, allocations: 5.516 kB / 0.5231 GB, free: 28.08 MB / 382.1 MB
Notification: Performance of [SIM] Solve: time 0.002505/0.2813, allocations: 1.753 MB / 0.5248 GB, free: 27.98 MB / 382.1 MB
Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref lowpassButterworth.z[1] is neither a seed nor a partial candidate!
"
[Timeout remaining time 660]
[Calling sys.exit(0), Time elapsed: 2.9748269639676437]
Failed to read output from testmodel.py, exit status != 0:
['time', 'criticalDamping.x[1]', 'criticalDamping.x[2]', 'derivative.x', 'firstOrder.y', 'integrator.y', 'limIntegrator.y', 'limPID.D.x', 'limPID.I.y', 'lowpassButterworth.x1[1]', 'lowpassButterworth.x2[1]', 'pI.x', 'pID.D.x', 'pID.I.y', 'secondOrder.y', 'secondOrder.yd', 'stateSpace.x[1]', 'stateSpace.x[2]', 'transferFunction.x_scaled[1]']
0.28991542098810896 0.30267830500000004 0.283398761
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f0dcf2aefb0>