Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001542/0.001542, allocations: 99.64 kB / 21.25 MB, free: 424 kB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001589/0.001589, allocations: 215.3 kB / 24.55 MB, free: 3.719 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.541/1.541, allocations: 230.5 MB / 258.2 MB, free: 7.75 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo): time 0.2432/0.2432, allocations: 46.95 MB / 361.7 MB, free: 3.902 MB / 286.1 MB
"
[Timeout remaining time 179]
Using package ModelicaTest with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo)
Using package Modelica with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo)
Using package Complex with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo)
Running command: translateModel(ModelicaTest.MultiBody.InitializationConversion.Bodies,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|body.Q.1.|body.Q.2.|body.Q.3.|body.Q.4.|body.frame_a.r_0.1.|body.frame_a.r_0.2.|body.frame_a.r_0.3.|body.v_0.1.|body.v_0.2.|body.v_0.3.|body.w_a.1.|body.w_a.2.|body.w_a.3.|body1.body.Q.1.|body1.body.Q.2.|body1.body.Q.3.|body1.body.Q.4.|body1.body.frame_a.r_0.1.|body1.body.frame_a.r_0.2.|body1.body.frame_a.r_0.3.|body1.body.w_a.1.|body1.body.w_a.2.|body1.body.w_a.3.|body1.v_0.1.|body1.v_0.2.|body1.v_0.3.|body2.body.Q.1.|body2.body.Q.2.|body2.body.Q.3.|body2.body.Q.4.|body2.body.frame_a.r_0.1.|body2.body.frame_a.r_0.2.|body2.body.frame_a.r_0.3.|body2.body.w_a.1.|body2.body.w_a.2.|body2.body.w_a.3.|body2.v_0.1.|body2.v_0.2.|body2.v_0.3.|body3.body.Q.1.|body3.body.Q.2.|body3.body.Q.3.|body3.body.Q.4.|body3.body.frame_a.r_0.1.|body3.body.frame_a.r_0.2.|body3.body.frame_a.r_0.3.|body3.body.w_a.1.|body3.body.w_a.2.|body3.body.w_a.3.|body3.v_0.1.|body3.v_0.2.|body3.v_0.3.|pointMass.r_0.1.|pointMass.r_0.2.|pointMass.r_0.3.|pointMass.v_0.1.|pointMass.v_0.2.|pointMass.v_0.3.",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies")
translateModel(ModelicaTest.MultiBody.InitializationConversion.Bodies,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|body.Q.1.|body.Q.2.|body.Q.3.|body.Q.4.|body.frame_a.r_0.1.|body.frame_a.r_0.2.|body.frame_a.r_0.3.|body.v_0.1.|body.v_0.2.|body.v_0.3.|body.w_a.1.|body.w_a.2.|body.w_a.3.|body1.body.Q.1.|body1.body.Q.2.|body1.body.Q.3.|body1.body.Q.4.|body1.body.frame_a.r_0.1.|body1.body.frame_a.r_0.2.|body1.body.frame_a.r_0.3.|body1.body.w_a.1.|body1.body.w_a.2.|body1.body.w_a.3.|body1.v_0.1.|body1.v_0.2.|body1.v_0.3.|body2.body.Q.1.|body2.body.Q.2.|body2.body.Q.3.|body2.body.Q.4.|body2.body.frame_a.r_0.1.|body2.body.frame_a.r_0.2.|body2.body.frame_a.r_0.3.|body2.body.w_a.1.|body2.body.w_a.2.|body2.body.w_a.3.|body2.v_0.1.|body2.v_0.2.|body2.v_0.3.|body3.body.Q.1.|body3.body.Q.2.|body3.body.Q.3.|body3.body.Q.4.|body3.body.frame_a.r_0.1.|body3.body.frame_a.r_0.2.|body3.body.frame_a.r_0.3.|body3.body.w_a.1.|body3.body.w_a.2.|body3.body.w_a.3.|body3.v_0.1.|body3.v_0.2.|body3.v_0.3.|pointMass.r_0.1.|pointMass.r_0.2.|pointMass.r_0.3.|pointMass.v_0.1.|pointMass.v_0.2.|pointMass.v_0.3.",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.251e-05/2.251e-05, allocations: 2.281 kB / 0.4974 GB, free: 7.094 MB / 366.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.InitializationConversion.Bodies): time 0.01374/0.01376, allocations: 10.7 MB / 0.5079 GB, free: 12.34 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01002/0.02378, allocations: 4.085 MB / 0.5118 GB, free: 8.238 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002358/0.02614, allocations: 103.4 kB / 0.5119 GB, free: 8.137 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.005354/0.03149, allocations: 2.554 MB / 0.5144 GB, free: 5.574 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.007016/0.03851, allocations: 2.292 MB / 0.5167 GB, free: 3.273 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002488/0.041, allocations: 0.8775 MB / 0.5175 GB, free: 2.395 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.2933/0.3343, allocations: 7.491 MB / 0.5249 GB, free: 15.96 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.003572/0.3379, allocations: 2.091 MB / 0.5269 GB, free: 15.83 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003773/0.3417, allocations: 1.82 MB / 0.5287 GB, free: 15.62 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.005771/0.3474, allocations: 3.125 MB / 0.5317 GB, free: 15.36 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0007927/0.3482, allocations: 229.2 kB / 0.5319 GB, free: 15.36 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003846/0.3521, allocations: 1.171 MB / 0.5331 GB, free: 15.36 MB / 382.1 MB
Notification: Performance of combineBinaries: time 0.00606/0.3581, allocations: 5.937 MB / 0.5389 GB, free: 12.54 MB / 382.1 MB
Notification: Performance of replaceArrayConstructors: time 0.003311/0.3614, allocations: 3.519 MB / 0.5423 GB, free: 10.47 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0009324/0.3624, allocations: 151.2 kB / 0.5425 GB, free: 10.47 MB / 382.1 MB
Notification: Performance of FrontEnd: time 0.0004707/0.3629, allocations: 92.53 kB / 0.5426 GB, free: 10.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: 1498 (489)
 * Number of variables: 1498 (431)
Notification: Performance of [SIM] Bindings: time 0.01364/0.3765, allocations: 14.88 MB / 0.5571 GB, free: 2.418 MB / 382.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.001363/0.3779, allocations: 1.619 MB / 0.5587 GB, free: 1.25 MB / 382.1 MB
Notification: Performance of [SIM] Early Inline: time 5.137/5.515, allocations: 1.907 GB / 2.465 GB, free: 15.67 MB / 0.7325 GB
Notification: Performance of [SIM] Simplify 1: time 3.738/9.252, allocations: 1.112 GB / 3.577 GB, free: 52.59 MB / 0.9669 GB
Notification: Performance of [SIM] Alias: time 1.709/10.96, allocations: 463.1 MB / 4.03 GB, free: 194.5 MB / 0.9669 GB
Notification: Performance of [SIM] Simplify 2: time 3.174/14.14, allocations: 1.112 GB / 5.141 GB, free: 36.03 MB / 0.9669 GB
Notification: Performance of [SIM] Remove Stream: time 0.1912/14.33, allocations: 69.14 MB / 5.209 GB, free: 30.8 MB / 0.9669 GB
Notification: Performance of [SIM] Detect States: time 1.019/15.35, allocations: 199.3 MB / 5.404 GB, free: 297.9 MB / 0.9669 GB
Notification: Performance of [SIM] Events: time 1.114/16.46, allocations: 0.5482 GB / 5.952 GB, free: 145.8 MB / 0.9669 GB
Notification: Performance of [SIM] Partitioning: time 0.08018/16.54, allocations: 38.4 MB / 5.989 GB, free: 129.9 MB / 0.9669 GB
Error: Internal error NBResolveSingularities.indexReduction failed because slicing during index reduction is not yet supported.
  needed sliced dummy:		[STAT] (4) protected Real[4] body1.body.Q (start = body1.body.Q_start, StateSelect = avoid)
	slice: {3}
"
[Timeout remaining time 643]
[Calling sys.exit(0), Time elapsed: 19.477378188981675]
Failed to read output from testmodel.py, exit status != 0:
['time', 'body.Q[1]', 'body.Q[2]', 'body.Q[3]', 'body.Q[4]', 'body.frame_a.r_0[1]', 'body.frame_a.r_0[2]', 'body.frame_a.r_0[3]', 'body.v_0[1]', 'body.v_0[2]', 'body.v_0[3]', 'body.w_a[1]', 'body.w_a[2]', 'body.w_a[3]', 'body1.body.Q[1]', 'body1.body.Q[2]', 'body1.body.Q[3]', 'body1.body.Q[4]', 'body1.body.frame_a.r_0[1]', 'body1.body.frame_a.r_0[2]', 'body1.body.frame_a.r_0[3]', 'body1.body.w_a[1]', 'body1.body.w_a[2]', 'body1.body.w_a[3]', 'body1.v_0[1]', 'body1.v_0[2]', 'body1.v_0[3]', 'body2.body.Q[1]', 'body2.body.Q[2]', 'body2.body.Q[3]', 'body2.body.Q[4]', 'body2.body.frame_a.r_0[1]', 'body2.body.frame_a.r_0[2]', 'body2.body.frame_a.r_0[3]', 'body2.body.w_a[1]', 'body2.body.w_a[2]', 'body2.body.w_a[3]', 'body2.v_0[1]', 'body2.v_0[2]', 'body2.v_0[3]', 'body3.body.Q[1]', 'body3.body.Q[2]', 'body3.body.Q[3]', 'body3.body.Q[4]', 'body3.body.frame_a.r_0[1]', 'body3.body.frame_a.r_0[2]', 'body3.body.frame_a.r_0[3]', 'body3.body.w_a[1]', 'body3.body.w_a[2]', 'body3.body.w_a[3]', 'body3.v_0[1]', 'body3.v_0[2]', 'body3.v_0[3]', 'pointMass.r_0[1]', 'pointMass.r_0[2]', 'pointMass.r_0[3]', 'pointMass.v_0[1]', 'pointMass.v_0[2]', 'pointMass.v_0[3]']
16.550179534009658 16.559103729 16.193866983
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f886d6eafb0>