Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.FixedTranslation.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.001623/0.001623, allocations: 116.4 kB / 18.5 MB, free: 4.535 MB / 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.002069/0.002069, allocations: 212.9 kB / 21.46 MB, free: 1.586 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.569/1.569, allocations: 230.2 MB / 254.5 MB, free: 15.11 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo): time 0.2462/0.2462, allocations: 46.89 MB / 357.4 MB, free: 10.5 MB / 286.1 MB
"
[Timeout remaining time 180]
Using package ModelicaTest with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/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: "" <> buildModelFMU(ModelicaTest.MultiBody.Parts.FixedTranslation,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Parts_FixedTranslation",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.Parts.FixedTranslation,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Parts_FixedTranslation",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Warning: Requested package Modelica of version 4.1.0, but this package was already loaded with version trunk. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Notification: Performance of FrontEnd - loaded program: time 0.002338/0.002338, allocations: 79.81 kB / 0.4912 GB, free: 8.688 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1222/0.1245, allocations: 65.97 MB / 0.5556 GB, free: 6.586 MB / 414.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Parts.FixedTranslation): time 0.0132/0.1377, allocations: 11.44 MB / 0.5668 GB, free: 11.08 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.00631/0.144, allocations: 2.843 MB / 0.5696 GB, free: 8.223 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00153/0.1456, allocations: 59.88 kB / 0.5696 GB, free: 8.164 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002932/0.1485, allocations: 1.354 MB / 0.571 GB, free: 6.805 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002962/0.1515, allocations: 1.28 MB / 0.5722 GB, free: 5.52 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0003573/0.1518, allocations: 75.84 kB / 0.5723 GB, free: 5.445 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.003631/0.1555, allocations: 2.763 MB / 0.575 GB, free: 2.68 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002311/0.1578, allocations: 1.558 MB / 0.5765 GB, free: 1.09 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001963/0.1597, allocations: 1.055 MB / 0.5775 GB, free: 32 kB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002556/0.1623, allocations: 1.643 MB / 0.5791 GB, free: 14.38 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0007877/0.1631, allocations: 204 kB / 0.5793 GB, free: 14.18 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001233/0.1643, allocations: 0.4993 MB / 0.5798 GB, free: 13.68 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001526/0.1658, allocations: 1.468 MB / 0.5813 GB, free: 12.21 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.00303/0.1689, allocations: 1.715 MB / 0.5829 GB, free: 10.48 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.007613/0.1765, allocations: 5.515 MB / 0.5883 GB, free: 4.945 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.422e-06/0.1765, allocations: 4 kB / 0.5883 GB, free: 4.941 MB / 446.1 MB
Notification: Performance of FrontEnd: time 1.633e-06/0.1765, allocations: 0 / 0.5883 GB, free: 4.941 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0001608/0.1766, allocations: 0 / 0.5883 GB, free: 4.941 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: 1477
 * Number of variables: 1477
Notification: Performance of Generate backend data structure: time 0.007685/0.1843, allocations: 3.741 MB / 0.592 GB, free: 1.129 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.486e-05/0.1844, allocations: 12.02 kB / 0.592 GB, free: 1.117 MB / 446.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0009686/0.1854, allocations: 0.6797 MB / 0.5927 GB, free: 436 kB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.2653/0.4506, allocations: 2.994 MB / 0.5956 GB, free: 33.54 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004259/0.4549, allocations: 1.56 MB / 0.5971 GB, free: 33.2 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000166/0.4551, allocations: 174.5 kB / 0.5973 GB, free: 33.19 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0009712/0.456, allocations: 221.1 kB / 0.5975 GB, free: 33.19 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01678/0.4728, allocations: 9.712 MB / 0.607 GB, free: 29.65 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 6.35e-05/0.4729, allocations: 24.19 kB / 0.607 GB, free: 29.63 MB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003914/0.4733, allocations: 110.3 kB / 0.6071 GB, free: 29.6 MB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004102/0.4774, allocations: 2.434 MB / 0.6095 GB, free: 29.17 MB / 446.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01648/0.4938, allocations: 8.808 MB / 0.6181 GB, free: 25.62 MB / 446.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.03932/0.5332, allocations: 30.61 MB / 0.648 GB, free: 10.45 MB / 462.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.005587/0.5388, allocations: 3.175 MB / 0.6511 GB, free: 7.043 MB / 462.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001283/0.54, allocations: 0.8574 MB / 0.6519 GB, free: 6.188 MB / 462.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 6.213e-05/0.5401, allocations: 20.98 kB / 0.6519 GB, free: 6.164 MB / 462.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.65e-05/0.5401, allocations: 43.5 kB / 0.652 GB, free: 6.113 MB / 462.1 MB
Notification: Performance of pre-optimization done (n=148): time 3.016e-06/0.5401, allocations: 0 / 0.652 GB, free: 6.113 MB / 462.1 MB
Notification: Performance of matching and sorting (n=148): time 0.007815/0.548, allocations: 3.383 MB / 0.6553 GB, free: 2.723 MB / 462.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.414e-05/0.548, allocations: 70.28 kB / 0.6553 GB, free: 2.641 MB / 462.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005073/0.5531, allocations: 3.127 MB / 0.6584 GB, free: 15.54 MB / 478.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002308/0.5533, allocations: 41.7 kB / 0.6584 GB, free: 15.49 MB / 478.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.002284/0.5556, allocations: 2.783 MB / 0.6611 GB, free: 12.68 MB / 478.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0008325/0.5564, allocations: 0.5892 MB / 0.6617 GB, free: 12.09 MB / 478.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001315/0.5578, allocations: 0.9845 MB / 0.6627 GB, free: 11.09 MB / 478.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001374/0.5579, allocations: 305.1 kB / 0.663 GB, free: 10.79 MB / 478.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002308/0.5602, allocations: 1.528 MB / 0.6645 GB, free: 9.258 MB / 478.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004903/0.5651, allocations: 4.459 MB / 0.6688 GB, free: 3.336 MB / 478.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.00818/0.5733, allocations: 6.537 MB / 0.6752 GB, free: 11.36 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.519e-05/0.5734, allocations: 32 kB / 0.6752 GB, free: 11.32 MB / 494.1 MB
Notification: Performance of matching and sorting (n=636) (initialization): time 0.01106/0.5844, allocations: 5.844 MB / 0.6809 GB, free: 5.477 MB / 494.1 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001148/0.5845, allocations: 48.94 kB / 0.681 GB, free: 5.43 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001581/0.5847, allocations: 104 kB / 0.6811 GB, free: 5.328 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001742/0.5849, allocations: 136 kB / 0.6812 GB, free: 5.195 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003331/0.5882, allocations: 1.061 MB / 0.6823 GB, free: 4.133 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0002139/0.5884, allocations: 201.3 kB / 0.6824 GB, free: 3.938 MB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003375/0.5918, allocations: 0.6901 MB / 0.6831 GB, free: 3.242 MB / 494.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0006273/0.5924, allocations: 311.8 kB / 0.6834 GB, free: 2.938 MB / 494.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 470
 * Number of states: 0 ()
 * Number of discrete variables: 29 (arrow.arrowLine.twoHeadedArrow,arrow.headAtOrigin,arrow.color[3],arrow.color[2],arrow.color[1],world.groundColor[3],world.groundColor[2],world.groundColor[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1],world.axisColor_x[3],world.axisColor_x[2],world.axisColor_x[1],fixed1.color[3],fixed1.color[2],fixed1.color[1],frameT2.color_x[3],frameT2.color_x[2],frameT2.color_x[1],frameT1.color_x[3],frameT1.color_x[2],frameT1.color_x[1],fixedTranslation.color[3],fixedTranslation.color[2],fixedTranslation.color[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (588):
 * Single equations (assignments): 582
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.003246/0.5957, allocations: 1.938 MB / 0.6853 GB, free: 0.9844 MB / 494.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004772/0.5961, allocations: 281.2 kB / 0.6856 GB, free: 0.7109 MB / 494.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.000728/0.5969, allocations: 323.8 kB / 0.6859 GB, free: 396 kB / 494.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.651e-05/0.5969, allocations: 20 kB / 0.6859 GB, free: 376 kB / 494.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.568e-06/0.5969, allocations: 0 / 0.6859 GB, free: 376 kB / 494.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.111e-05/0.5969, allocations: 3.984 kB / 0.6859 GB, free: 372 kB / 494.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01411/0.611, allocations: 6.882 MB / 0.6926 GB, free: 9.328 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.45e-05/0.6111, allocations: 11.94 kB / 0.6926 GB, free: 9.316 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0007876/0.6118, allocations: 179.7 kB / 0.6928 GB, free: 9.141 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.665e-05/0.6119, allocations: 8 kB / 0.6928 GB, free: 9.133 MB / 0.4981 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 9.286e-05/0.612, allocations: 28 kB / 0.6928 GB, free: 9.105 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.651e-05/0.612, allocations: 12 kB / 0.6929 GB, free: 9.094 MB / 0.4981 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.14e-05/0.612, allocations: 15.88 kB / 0.6929 GB, free: 9.078 MB / 0.4981 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.003271/0.6153, allocations: 2.265 MB / 0.6951 GB, free: 6.703 MB / 0.4981 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0009469/0.6162, allocations: 366.9 kB / 0.6954 GB, free: 6.324 MB / 0.4981 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005576/0.6168, allocations: 23.98 kB / 0.6955 GB, free: 6.301 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001036/0.6178, allocations: 43.95 kB / 0.6955 GB, free: 6.258 MB / 0.4981 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001788/0.618, allocations: 86.02 kB / 0.6956 GB, free: 6.176 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001115/0.6181, allocations: 51.97 kB / 0.6956 GB, free: 6.125 MB / 0.4981 GB
Notification: Performance of sorting global known variables: time 0.003955/0.622, allocations: 1.975 MB / 0.6976 GB, free: 4.148 MB / 0.4981 GB
Notification: Performance of sort global known variables: time 1.1e-07/0.622, allocations: 0 / 0.6976 GB, free: 4.148 MB / 0.4981 GB
Notification: Performance of remove unused functions: time 0.004407/0.6264, allocations: 1.005 MB / 0.6985 GB, free: 3.141 MB / 0.4981 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 29
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (100):
 * Single equations (assignments): 94
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.004459/0.6309, allocations: 2.919 MB / 0.7014 GB, free: 112 kB / 0.4981 GB
Notification: Performance of simCode: created initialization part: time 0.006247/0.6372, allocations: 3.433 MB / 0.7047 GB, free: 12.7 MB / 0.5137 GB
Notification: Performance of simCode: created event and clocks part: time 1.253e-05/0.6372, allocations: 0 / 0.7047 GB, free: 12.7 MB / 0.5137 GB
Notification: Performance of simCode: created simulation system equations: time 0.001405/0.6386, allocations: 0.8611 MB / 0.7056 GB, free: 11.84 MB / 0.5137 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002182/0.6408, allocations: 320 kB / 0.7059 GB, free: 11.53 MB / 0.5137 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.06052/0.7013, allocations: 33.51 MB / 0.7386 GB, free: 5.699 MB / 0.545 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.005212/0.7065, allocations: 3 MB / 0.7415 GB, free: 2.672 MB / 0.545 GB
Notification: Performance of simCode: alias equations: time 0.004179/0.7107, allocations: 1.726 MB / 0.7432 GB, free: 0.9414 MB / 0.545 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002628/0.7133, allocations: 1.542 MB / 0.7447 GB, free: 15.39 MB / 0.5606 GB
Notification: Performance of SimCode: time 1.332e-06/0.7133, allocations: 0 / 0.7447 GB, free: 15.39 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.09703/0.8103, allocations: 45.91 MB / 0.7896 GB, free: 1.648 MB / 0.5919 GB
Notification: Performance of buildModelFMU: Generate platform static: time 15.64/16.45, allocations: 7.984 kB / 0.7896 GB, free: 1.641 MB / 0.5919 GB
"
[Timeout remaining time 643]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.FixedTranslation.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.FixedTranslation.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.FixedTranslation.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.FixedTranslation.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.FixedTranslation_res.mat --tempDir=temp_ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Parts_FixedTranslation_fmu --startTime=0 --stopTime=1.1 --stepSize=0.000219912 --timeout=50 --tolerance=1e-06 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Parts_FixedTranslation.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.FixedTranslation.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 19.622826400212944]
Failed to read output from testmodel.py, exit status != 0:
['time', 'fixedTranslation.frame_b.r_0[1]', 'fixedTranslation.frame_b.r_0[2]', 'fixedTranslation.frame_b.r_0[3]', 'fixedTranslation.frame_b.f[1]', 'fixedTranslation.frame_b.f[2]', 'fixedTranslation.frame_b.f[3]', 'fixedTranslation.frame_b.t[1]', 'fixedTranslation.frame_b.t[2]', 'fixedTranslation.frame_b.t[3]', 'fixed1.frame_b.r_0[1]', 'fixed1.frame_b.r_0[2]', 'fixed1.frame_b.r_0[3]', 'fixed1.frame_b.f[1]', 'fixed1.frame_b.f[2]', 'fixed1.frame_b.f[3]', 'fixed1.frame_b.t[1]', 'fixed1.frame_b.t[2]', 'fixed1.frame_b.t[3]']
16.554536757059395 16.575656894 16.395004103
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f5671276020>