Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2.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.001801/0.001801, allocations: 106.5 kB / 21.22 MB, free: 0.6797 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.001945/0.001945, allocations: 209.3 kB / 24.54 MB, free: 4.055 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.452/1.452, allocations: 230.5 MB / 258.2 MB, free: 8.039 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.2532/0.2532, allocations: 46.93 MB / 361.6 MB, free: 4.203 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.Joints.FreeMotion2,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_FreeMotion2",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(ModelicaTest.MultiBody.Joints.FreeMotion2,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_FreeMotion2",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.002358/0.002358, allocations: 83.88 kB / 0.497 GB, free: 7.758 MB / 366.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.3176/0.32, allocations: 66.08 MB / 0.5615 GB, free: 4.312 MB / 398.1 MB Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.FreeMotion2): time 0.01785/0.3378, allocations: 14.76 MB / 0.5759 GB, free: 5.484 MB / 414.1 MB Notification: Performance of NFInst.instExpressions: time 0.01093/0.3488, allocations: 5.035 MB / 0.5809 GB, free: 444 kB / 414.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.002542/0.3513, allocations: 123.4 kB / 0.581 GB, free: 320 kB / 414.1 MB Notification: Performance of NFTyping.typeComponents: time 0.008335/0.3596, allocations: 4.213 MB / 0.5851 GB, free: 12.09 MB / 430.1 MB Notification: Performance of NFTyping.typeBindings: time 0.006475/0.3661, allocations: 2.393 MB / 0.5874 GB, free: 9.684 MB / 430.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.003616/0.3697, allocations: 1.088 MB / 0.5885 GB, free: 8.594 MB / 430.1 MB Notification: Performance of NFFlatten.flatten: time 0.01211/0.3818, allocations: 7.173 MB / 0.5955 GB, free: 1.406 MB / 430.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.006268/0.3881, allocations: 4.052 MB / 0.5994 GB, free: 13.29 MB / 446.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.004458/0.3926, allocations: 2.274 MB / 0.6017 GB, free: 11 MB / 446.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.005501/0.3981, allocations: 3.283 MB / 0.6049 GB, free: 7.711 MB / 446.1 MB Notification: Performance of NFPackage.collectConstants: time 0.001667/0.3997, allocations: 496 kB / 0.6053 GB, free: 7.227 MB / 446.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.003428/0.4032, allocations: 1.397 MB / 0.6067 GB, free: 5.828 MB / 446.1 MB Notification: Performance of NFScalarize.scalarize: time 0.003974/0.4071, allocations: 2.813 MB / 0.6095 GB, free: 3.008 MB / 446.1 MB Notification: Performance of NFVerifyModel.verify: time 0.005184/0.4123, allocations: 3.275 MB / 0.6127 GB, free: 15.71 MB / 462.1 MB Notification: Performance of NFConvertDAE.convert: time 0.01379/0.4261, allocations: 10.14 MB / 0.6226 GB, free: 5.527 MB / 462.1 MB Notification: Performance of FrontEnd - DAE generated: time 8.125e-06/0.4261, allocations: 0 / 0.6226 GB, free: 5.527 MB / 462.1 MB Notification: Performance of FrontEnd: time 1.492e-06/0.4261, allocations: 0 / 0.6226 GB, free: 5.527 MB / 462.1 MB Notification: Performance of Transformations before backend: time 0.0002863/0.4264, allocations: 0 / 0.6226 GB, free: 5.527 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: 1953 * Number of variables: 1953 Notification: Performance of Generate backend data structure: time 0.01944/0.4458, allocations: 7.234 MB / 0.6296 GB, free: 14.22 MB / 478.1 MB Notification: Performance of prepare preOptimizeDAE: time 4.506e-05/0.4459, allocations: 8.031 kB / 0.6296 GB, free: 14.21 MB / 478.1 MB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001986/0.4479, allocations: 0.93 MB / 0.6305 GB, free: 13.27 MB / 478.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.144/0.5918, allocations: 12.56 MB / 0.6428 GB, free: 0.6953 MB / 478.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.01284/0.6047, allocations: 4.986 MB / 0.6477 GB, free: 11.62 MB / 494.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003762/0.6051, allocations: 328.5 kB / 0.648 GB, free: 11.29 MB / 494.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.002025/0.6071, allocations: 455.7 kB / 0.6484 GB, free: 10.85 MB / 494.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.02952/0.6366, allocations: 12.95 MB / 0.6611 GB, free: 13.02 MB / 0.4981 GB Notification: Performance of preOpt findStateOrder (simulation): time 0.000255/0.6369, allocations: 75.41 kB / 0.6611 GB, free: 12.95 MB / 0.4981 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0008615/0.6377, allocations: 203.8 kB / 0.6613 GB, free: 12.75 MB / 0.4981 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004713/0.6424, allocations: 2.527 MB / 0.6638 GB, free: 10.22 MB / 0.4981 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02425/0.6667, allocations: 11.13 MB / 0.6747 GB, free: 15.08 MB / 0.5137 GB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.07534/0.742, allocations: 52.05 MB / 0.7255 GB, free: 9.512 MB / 0.5606 GB Notification: Performance of preOpt comSubExp (simulation): time 0.01434/0.7564, allocations: 4.892 MB / 0.7303 GB, free: 4.574 MB / 0.5606 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.004757/0.7611, allocations: 3.109 MB / 0.7333 GB, free: 1.457 MB / 0.5606 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0006176/0.7617, allocations: 253.6 kB / 0.7335 GB, free: 1.211 MB / 0.5606 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.651e-05/0.7618, allocations: 68.44 kB / 0.7336 GB, free: 1.133 MB / 0.5606 GB Notification: Performance of pre-optimization done (n=245): time 5.44e-06/0.7618, allocations: 0 / 0.7336 GB, free: 1.133 MB / 0.5606 GB Notification: Performance of matching and sorting (n=315): time 0.4011/1.163, allocations: 34.69 MB / 0.7675 GB, free: 135.9 MB / 0.5606 GB Notification: Performance of inlineWhenForInitialization (initialization): time 9.204e-05/1.163, allocations: 269.3 kB / 0.7677 GB, free: 135.6 MB / 0.5606 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.006562/1.17, allocations: 5.194 MB / 0.7728 GB, free: 130.5 MB / 0.5606 GB Notification: Performance of collectPreVariables (initialization): time 0.0008204/1.17, allocations: 73.7 kB / 0.7729 GB, free: 130.4 MB / 0.5606 GB Notification: Performance of collectInitialEqns (initialization): time 0.002234/1.173, allocations: 4.073 MB / 0.7769 GB, free: 126.8 MB / 0.5606 GB Notification: Performance of collectInitialBindings (initialization): time 0.001326/1.174, allocations: 1.221 MB / 0.7781 GB, free: 125.6 MB / 0.5606 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.002294/1.176, allocations: 1.528 MB / 0.7796 GB, free: 124 MB / 0.5606 GB Notification: Performance of setup shared object (initialization): time 5.293e-05/1.176, allocations: 301.1 kB / 0.7798 GB, free: 123.8 MB / 0.5606 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.004415/1.181, allocations: 3.4 MB / 0.7832 GB, free: 120.3 MB / 0.5606 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.007326/1.188, allocations: 7.565 MB / 0.7905 GB, free: 110.8 MB / 0.5606 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.01204/1.2, allocations: 11.85 MB / 0.8021 GB, free: 96.9 MB / 0.5606 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.000138/1.2, allocations: 44 kB / 0.8022 GB, free: 96.86 MB / 0.5606 GB Notification: Performance of matching and sorting (n=982) (initialization): time 0.02347/1.224, allocations: 12.73 MB / 0.8146 GB, free: 84.08 MB / 0.5606 GB Notification: Performance of prepare postOptimizeDAE: time 9.445e-05/1.224, allocations: 71.94 kB / 0.8147 GB, free: 84.01 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001656/1.224, allocations: 144 kB / 0.8148 GB, free: 83.87 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.005296/1.229, allocations: 2.239 MB / 0.817 GB, free: 81.62 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005558/1.235, allocations: 1.719 MB / 0.8187 GB, free: 79.9 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01667/1.252, allocations: 16.19 MB / 0.8345 GB, free: 63.49 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005956/1.257, allocations: 452.3 kB / 0.8349 GB, free: 63.05 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001119/1.259, allocations: 443.9 kB / 0.8353 GB, free: 62.61 MB / 0.5606 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 656 * Number of states: 0 () * Number of discrete variables: 44 (freeMotion.arrow.twoHeadedArrow,freeMotion.arrow.headAtOrigin,universal1.cylinderColor[3],universal1.cylinderColor[2],universal1.cylinderColor[1],prismatic1.boxColor[3],prismatic1.boxColor[2],prismatic1.boxColor[1],bodyBox1.color[3],bodyBox1.color[2],bodyBox1.color[1],bodyBox1.body.sphereColor[3],bodyBox1.body.sphereColor[2],bodyBox1.body.sphereColor[1],universal.cylinderColor[3],universal.cylinderColor[2],universal.cylinderColor[1],prismatic.boxColor[3],prismatic.boxColor[2],prismatic.boxColor[1],bodyBox.color[3],bodyBox.color[2],bodyBox.color[1],bodyBox.body.sphereColor[3],bodyBox.body.sphereColor[2],bodyBox.body.sphereColor[1],fixed.color[3],fixed.color[2],fixed.color[1],freeMotion.arrowColor[3],freeMotion.arrowColor[2],freeMotion.arrowColor[1],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1],world.axisColor_x[3],world.axisColor_x[2],world.axisColor_x[1],world.groundColor[3],world.groundColor[2],world.groundColor[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1]) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (906): * Single equations (assignments): 901 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 2 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 2 systems {(3,24,100.0%), (3,24,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.004233/1.263, allocations: 2.741 MB / 0.838 GB, free: 59.86 MB / 0.5606 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001004/1.264, allocations: 0.5045 MB / 0.8385 GB, free: 59.35 MB / 0.5606 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01132/1.275, allocations: 7.268 MB / 0.8456 GB, free: 52.02 MB / 0.5606 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.16e-05/1.275, allocations: 35.83 kB / 0.8456 GB, free: 51.98 MB / 0.5606 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 2.333e-05/1.275, allocations: 12 kB / 0.8456 GB, free: 51.97 MB / 0.5606 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.782e-05/1.275, allocations: 16.73 kB / 0.8457 GB, free: 51.96 MB / 0.5606 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.02874/1.304, allocations: 16.04 MB / 0.8613 GB, free: 35.83 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.463e-05/1.304, allocations: 4 kB / 0.8613 GB, free: 35.83 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002876/1.307, allocations: 0.4953 MB / 0.8618 GB, free: 35.33 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.004971/1.312, allocations: 2.003 MB / 0.8638 GB, free: 33.32 MB / 0.5606 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0008752/1.313, allocations: 67.66 kB / 0.8638 GB, free: 33.26 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01635/1.329, allocations: 14.69 MB / 0.8782 GB, free: 18.34 MB / 0.5606 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.101e-06/1.329, allocations: 11.94 kB / 0.8782 GB, free: 18.32 MB / 0.5606 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01511/1.344, allocations: 11.68 MB / 0.8896 GB, free: 6.457 MB / 0.5606 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001249/1.346, allocations: 488.7 kB / 0.8901 GB, free: 5.977 MB / 0.5606 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0007652/1.346, allocations: 60 kB / 0.8901 GB, free: 5.918 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002386/1.349, allocations: 211.7 kB / 0.8903 GB, free: 5.711 MB / 0.5606 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005602/1.349, allocations: 221.2 kB / 0.8905 GB, free: 5.492 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002162/1.349, allocations: 91.98 kB / 0.8906 GB, free: 5.402 MB / 0.5606 GB Notification: Performance of sorting global known variables: time 0.004471/1.354, allocations: 3.286 MB / 0.8938 GB, free: 2.133 MB / 0.5606 GB Notification: Performance of sort global known variables: time 8e-08/1.354, allocations: 0 / 0.8938 GB, free: 2.133 MB / 0.5606 GB Notification: Performance of remove unused functions: time 0.006717/1.361, allocations: 1.469 MB / 0.8953 GB, free: 0.6641 MB / 0.5606 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 28 * Number of states: 12 (freeMotion.r_rel_a[1],freeMotion.v_rel_a[1],universal.phi_a,universal.phi_b,universal.w_a,universal.w_b,prismatic1.s,prismatic1.v,universal1.phi_a,universal1.phi_b,universal1.w_a,universal1.w_b) * 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 (253): * Single equations (assignments): 248 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 2 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 2 systems {(7,17,46.9%), (7,17,46.9%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.02218/1.383, allocations: 12.36 MB / 0.9073 GB, free: 4.105 MB / 0.5762 GB Notification: Performance of simCode: created initialization part: time 0.01698/1.4, allocations: 8.11 MB / 0.9152 GB, free: 11.98 MB / 0.5919 GB Notification: Performance of simCode: created event and clocks part: time 1.398e-05/1.4, allocations: 4 kB / 0.9153 GB, free: 11.98 MB / 0.5919 GB Notification: Performance of simCode: created simulation system equations: time 0.008522/1.408, allocations: 3.874 MB / 0.919 GB, free: 8.078 MB / 0.5919 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004893/1.413, allocations: 0.6952 MB / 0.9197 GB, free: 7.398 MB / 0.5919 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.4149/1.828, allocations: 68.26 MB / 0.9864 GB, free: 227.5 MB / 0.6544 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.005394/1.834, allocations: 4.283 MB / 0.9906 GB, free: 226.7 MB / 0.6544 GB Notification: Performance of simCode: alias equations: time 0.005157/1.839, allocations: 2.456 MB / 0.993 GB, free: 226.6 MB / 0.6544 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.003999/1.843, allocations: 2.714 MB / 0.9956 GB, free: 225.2 MB / 0.6544 GB Notification: Performance of SimCode: time 1.522e-06/1.843, allocations: 0 / 0.9956 GB, free: 225.2 MB / 0.6544 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1708/2.013, allocations: 119.1 MB / 1.112 GB, free: 115.2 MB / 0.6544 GB Notification: Performance of buildModelFMU: Generate platform static: time 22.48/24.49, allocations: 3.438 kB / 1.112 GB, free: 115.2 MB / 0.6544 GB " [Timeout remaining time 635] (rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2_res.mat --tempDir=temp_ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_FreeMotion2_fmu --startTime=0 --stopTime=2 --stepSize=0.00039984 --timeout=50 --tolerance=1e-06 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_FreeMotion2.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2.pipe 2>&1) [Timeout 52.5] [Calling os._exit(0), Time elapsed: 27.556035682093352] Failed to read output from testmodel.py, exit status != 0: ['time', 'freeMotion.r_rel_a[1]', 'freeMotion.v_rel_a[1]', 'prismatic1.s', 'prismatic1.v', 'universal.phi_a', 'universal.phi_b', 'universal.w_a', 'universal.w_b', 'universal1.phi_a', 'universal1.phi_b', 'universal1.w_a', 'universal1.w_b'] 24.652486266102642 24.66214554 24.233307436 Calling exit ...