Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR.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.000822/0.000822, allocations: 80.44 kB / 19.92 MB, free: 4.633 MB / 18.57 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.001323/0.001323, allocations: 169.9 kB / 23.21 MB, free: 1.348 MB / 18.57 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 0.897/0.897, allocations: 158.6 MB / 185 MB, free: 3.027 MB / 170.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo): time 0.1379/0.1379, allocations: 32.38 MB / 269.6 MB, free: 14.97 MB / 266.7 MB " [Timeout remaining time 180] Using package ModelicaTest with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo) Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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: "" <> buildModelFMU(ModelicaTest.MultiBody.FourbarVariants.JointUSR,fileNamePrefix="ModelicaTest_3_2_3_ModelicaTest_MultiBody_FourbarVariants_JointUSR",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(ModelicaTest.MultiBody.FourbarVariants.JointUSR,fileNamePrefix="ModelicaTest_3_2_3_ModelicaTest_MultiBody_FourbarVariants_JointUSR",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.593e-06/1.593e-06, allocations: 0 / 406.8 MB, free: 13.54 MB / 346.7 MB Notification: Performance of FrontEnd - Absyn->SCode: time 1.849e-05/2.008e-05, allocations: 5.938 kB / 406.8 MB, free: 13.53 MB / 346.7 MB Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.FourbarVariants.JointUSR): time 0.0123/0.01232, allocations: 15.48 MB / 422.3 MB, free: 13.98 MB / 362.7 MB Notification: Performance of NFInst.instExpressions: time 0.009818/0.02214, allocations: 5.419 MB / 427.7 MB, free: 8.539 MB / 362.7 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.00328/0.02542, allocations: 131.4 kB / 427.9 MB, free: 8.41 MB / 362.7 MB Notification: Performance of NFTyping.typeComponents: time 0.005351/0.03077, allocations: 4.711 MB / 432.6 MB, free: 3.684 MB / 362.7 MB Notification: Performance of NFTyping.typeBindings: time 0.007147/0.03792, allocations: 3.227 MB / 435.8 MB, free: 452 kB / 362.7 MB Notification: Performance of NFTyping.typeClassSections: time 0.1863/0.2242, allocations: 1.666 MB / 437.5 MB, free: 16.09 MB / 362.7 MB Notification: Performance of NFFlatten.flatten: time 0.01218/0.2364, allocations: 9.872 MB / 447.3 MB, free: 15.23 MB / 362.7 MB Notification: Performance of NFFlatten.resolveConnections: time 0.008386/0.2448, allocations: 6.25 MB / 453.6 MB, free: 14.23 MB / 362.7 MB Notification: Performance of NFEvalConstants.evaluate: time 0.007129/0.2519, allocations: 4.727 MB / 458.3 MB, free: 12.5 MB / 362.7 MB Notification: Performance of NFSimplifyModel.simplify: time 0.005068/0.257, allocations: 4.907 MB / 463.2 MB, free: 10.48 MB / 362.7 MB Notification: Performance of NFPackage.collectConstants: time 0.001207/0.2582, allocations: 0.679 MB / 463.9 MB, free: 10.48 MB / 362.7 MB Notification: Performance of NFFlatten.collectFunctions: time 0.002191/0.2604, allocations: 1.832 MB / 465.7 MB, free: 10 MB / 362.7 MB Notification: Performance of NFScalarize.scalarize: time 0.001989/0.2624, allocations: 4.046 MB / 469.8 MB, free: 6.871 MB / 362.7 MB Notification: Performance of NFVerifyModel.verify: time 0.00296/0.2653, allocations: 2.56 MB / 472.3 MB, free: 4.422 MB / 362.7 MB Notification: Performance of NFConvertDAE.convert: time 0.01016/0.2755, allocations: 14 MB / 486.3 MB, free: 7.004 MB / 378.7 MB Notification: Performance of FrontEnd - DAE generated: time 3.456e-06/0.2755, allocations: 0 / 486.3 MB, free: 7.004 MB / 378.7 MB Notification: Performance of FrontEnd: time 2.254e-06/0.2755, allocations: 0 / 486.3 MB, free: 7.004 MB / 378.7 MB Notification: Performance of Transformations before backend: time 0.0002098/0.2757, allocations: 0 / 486.3 MB, free: 7.004 MB / 378.7 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 2782 * Number of variables: 2782 Notification: Performance of Generate backend data structure: time 0.01268/0.2884, allocations: 9.518 MB / 495.9 MB, free: 13.59 MB / 394.7 MB Notification: Performance of prepare preOptimizeDAE: time 5.385e-05/0.2885, allocations: 8.031 kB / 495.9 MB, free: 13.59 MB / 394.7 MB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001561/0.29, allocations: 1.431 MB / 497.3 MB, free: 12.21 MB / 394.7 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01645/0.3065, allocations: 6.475 MB / 0.492 GB, free: 5.73 MB / 394.7 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.0093/0.3158, allocations: 5.953 MB / 0.4978 GB, free: 20 kB / 394.7 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002271/0.316, allocations: 429.8 kB / 0.4982 GB, free: 15.66 MB / 410.7 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0009374/0.3169, allocations: 0.6069 MB / 0.4988 GB, free: 15.13 MB / 410.7 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.0232/0.3401, allocations: 20.68 MB / 0.519 GB, free: 9.633 MB / 426.7 MB Notification: Performance of preOpt findStateOrder (simulation): time 0.0001415/0.3403, allocations: 79.16 kB / 0.5191 GB, free: 9.59 MB / 426.7 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0005284/0.3408, allocations: 273.2 kB / 0.5193 GB, free: 9.391 MB / 426.7 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003167/0.344, allocations: 3.712 MB / 0.5229 GB, free: 5.727 MB / 426.7 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02485/0.3688, allocations: 18.07 MB / 0.5406 GB, free: 3.949 MB / 442.7 MB Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts. Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.06617/0.435, allocations: 71.82 MB / 0.6107 GB, free: 11.21 MB / 0.5105 GB Notification: Performance of preOpt comSubExp (simulation): time 0.01055/0.4455, allocations: 6.489 MB / 0.6171 GB, free: 4.699 MB / 0.5105 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.003766/0.4493, allocations: 3.739 MB / 0.6207 GB, free: 0.9453 MB / 0.5105 GB Notification: Performance of preOpt evalFunc (simulation): time 0.3609/0.8102, allocations: 15.03 MB / 0.6354 GB, free: 144.5 MB / 0.5105 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.507e-05/0.8102, allocations: 72.28 kB / 0.6355 GB, free: 144.4 MB / 0.5105 GB Notification: Performance of pre-optimization done (n=332): time 6.663e-06/0.8102, allocations: 0 / 0.6355 GB, free: 144.4 MB / 0.5105 GB Notification: Performance of matching and sorting (n=413): time 0.06282/0.8731, allocations: 36.53 MB / 0.6711 GB, free: 132.1 MB / 0.5105 GB Notification: Performance of inlineWhenForInitialization (initialization): time 8.197e-05/0.8731, allocations: 196 kB / 0.6713 GB, free: 131.8 MB / 0.5105 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005829/0.879, allocations: 7.695 MB / 0.6788 GB, free: 125.4 MB / 0.5105 GB Notification: Performance of collectPreVariables (initialization): time 0.000186/0.8792, allocations: 85.7 kB / 0.6789 GB, free: 125.3 MB / 0.5105 GB Notification: Performance of collectInitialEqns (initialization): time 0.001445/0.8806, allocations: 5.604 MB / 0.6844 GB, free: 120.1 MB / 0.5105 GB Notification: Performance of collectInitialBindings (initialization): time 0.0006777/0.8813, allocations: 1.567 MB / 0.6859 GB, free: 118.5 MB / 0.5105 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.001459/0.8827, allocations: 2.058 MB / 0.6879 GB, free: 116.4 MB / 0.5105 GB Notification: Performance of setup shared object (initialization): time 2.739e-05/0.8828, allocations: 301.1 kB / 0.6882 GB, free: 116.1 MB / 0.5105 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.003257/0.886, allocations: 4.19 MB / 0.6923 GB, free: 111.9 MB / 0.5105 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.004745/0.8908, allocations: 10.11 MB / 0.7022 GB, free: 99.05 MB / 0.5105 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.009802/0.9006, allocations: 15.38 MB / 0.7172 GB, free: 81.61 MB / 0.5105 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.933e-05/0.9006, allocations: 60 kB / 0.7173 GB, free: 81.55 MB / 0.5105 GB Notification: Performance of matching and sorting (n=1377) (initialization): time 0.01754/0.9182, allocations: 15.98 MB / 0.7329 GB, free: 66.05 MB / 0.5105 GB Notification: Performance of prepare postOptimizeDAE: time 6.924e-05/0.9183, allocations: 92 kB / 0.7329 GB, free: 65.96 MB / 0.5105 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.603e-05/0.9184, allocations: 210.4 kB / 0.7332 GB, free: 65.76 MB / 0.5105 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.003653/0.922, allocations: 2.31 MB / 0.7354 GB, free: 63.45 MB / 0.5105 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003603/0.9256, allocations: 2.417 MB / 0.7378 GB, free: 61.03 MB / 0.5105 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01071/0.9363, allocations: 12.27 MB / 0.7498 GB, free: 48.64 MB / 0.5105 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005806/0.9421, allocations: 0.7608 MB / 0.7505 GB, free: 47.88 MB / 0.5105 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004602/0.9426, allocations: 0.5922 MB / 0.7511 GB, free: 47.29 MB / 0.5105 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 937 * Number of states: 0 () * Number of discrete variables: 49 (jointUSR.revolute.positiveBranch,Body3.sphereColor[3],Body3.sphereColor[2],Body3.sphereColor[1],Body2.sphereColor[3],Body2.sphereColor[2],Body2.sphereColor[1],Body1.sphereColor[3],Body1.sphereColor[2],Body1.sphereColor[1],jointUSR.rod1Color[3],jointUSR.rod1Color[2],jointUSR.rod1Color[1],jointUSR.cylinderColor[3],jointUSR.cylinderColor[2],jointUSR.cylinderColor[1],jointUSR.sphereColor[3],jointUSR.sphereColor[2],jointUSR.sphereColor[1],jointUSR.revoluteColor[3],jointUSR.revoluteColor[2],jointUSR.revoluteColor[1],fixedFrame.color_x[3],fixedFrame.color_x[2],fixedFrame.color_x[1],b3.color[3],b3.color[2],b3.color[1],b2.color[3],b2.color[2],b2.color[1],b2.body.sphereColor[3],b2.body.sphereColor[2],b2.body.sphereColor[1],j2.boxColor[3],j2.boxColor[2],j2.boxColor[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 (1276): * Single equations (assignments): 1269 * Array equations: 6 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 1 system {(2,52,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.004955/0.9475, allocations: 3.765 MB / 0.7547 GB, free: 43.51 MB / 0.5105 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001075/0.9486, allocations: 0.6286 MB / 0.7554 GB, free: 42.88 MB / 0.5105 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.009367/0.958, allocations: 8.137 MB / 0.7633 GB, free: 34.69 MB / 0.5105 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.379e-05/0.958, allocations: 63.98 kB / 0.7634 GB, free: 34.63 MB / 0.5105 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.469e-05/0.958, allocations: 16 kB / 0.7634 GB, free: 34.61 MB / 0.5105 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.877e-05/0.9581, allocations: 19.88 kB / 0.7634 GB, free: 34.59 MB / 0.5105 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01982/0.9779, allocations: 17.57 MB / 0.7806 GB, free: 16.84 MB / 0.5105 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.517e-05/0.9779, allocations: 8 kB / 0.7806 GB, free: 16.84 MB / 0.5105 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001367/0.9793, allocations: 0.617 MB / 0.7812 GB, free: 16.22 MB / 0.5105 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.003167/0.9824, allocations: 1.746 MB / 0.7829 GB, free: 14.47 MB / 0.5105 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001896/0.9826, allocations: 79.03 kB / 0.783 GB, free: 14.4 MB / 0.5105 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.009138/0.9918, allocations: 10.38 MB / 0.7931 GB, free: 3.918 MB / 0.5105 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.464e-06/0.9918, allocations: 19.94 kB / 0.7931 GB, free: 3.898 MB / 0.5105 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01339/1.005, allocations: 12.15 MB / 0.805 GB, free: 7.488 MB / 0.5262 GB Notification: Performance of postOpt removeConstants (simulation): time 0.002425/1.008, allocations: 1.21 MB / 0.8062 GB, free: 6.273 MB / 0.5262 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004938/1.008, allocations: 79.92 kB / 0.8062 GB, free: 6.195 MB / 0.5262 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001728/1.01, allocations: 322.6 kB / 0.8065 GB, free: 5.883 MB / 0.5262 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004158/1.01, allocations: 269.5 kB / 0.8068 GB, free: 5.617 MB / 0.5262 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001727/1.01, allocations: 108 kB / 0.8069 GB, free: 5.512 MB / 0.5262 GB Notification: Performance of sorting global known variables: time 0.005105/1.015, allocations: 4.695 MB / 0.8115 GB, free: 0.8281 MB / 0.5262 GB Notification: Performance of sort global known variables: time 4.11e-07/1.015, allocations: 0 / 0.8115 GB, free: 0.8281 MB / 0.5262 GB Notification: Performance of remove unused functions: time 0.006229/1.022, allocations: 2.151 MB / 0.8136 GB, free: 14.68 MB / 0.5418 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 52 * Number of states: 2 (j2.s,j2.v) * 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 (325): * Single equations (assignments): 318 * Array equations: 6 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 1 system {(2,42,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.2267/1.248, allocations: 12.94 MB / 0.8262 GB, free: 158 MB / 0.5418 GB Notification: Performance of simCode: created initialization part: time 0.01312/1.262, allocations: 9.413 MB / 0.8354 GB, free: 157.7 MB / 0.5418 GB Notification: Performance of simCode: created event and clocks part: time 1.245e-05/1.262, allocations: 1.75 kB / 0.8354 GB, free: 157.7 MB / 0.5418 GB Notification: Performance of simCode: created simulation system equations: time 0.003411/1.265, allocations: 3.043 MB / 0.8384 GB, free: 157 MB / 0.5418 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003938/1.269, allocations: 0.8188 MB / 0.8392 GB, free: 156.6 MB / 0.5418 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.08795/1.357, allocations: 90.61 MB / 0.9277 GB, free: 84.55 MB / 0.5418 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003733/1.361, allocations: 5.13 MB / 0.9327 GB, free: 79.39 MB / 0.5418 GB Notification: Performance of simCode: alias equations: time 0.00474/1.365, allocations: 3.343 MB / 0.9359 GB, free: 76.04 MB / 0.5418 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002805/1.368, allocations: 3.722 MB / 0.9396 GB, free: 72.32 MB / 0.5418 GB Notification: Performance of SimCode: time 2.455e-06/1.368, allocations: 0 / 0.9396 GB, free: 72.32 MB / 0.5418 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.3412/1.709, allocations: 134.3 MB / 1.071 GB, free: 153.6 MB / 0.5574 GB Notification: Performance of buildModelFMU: Generate platform static: time 14.98/16.69, allocations: 2.344 kB / 1.071 GB, free: 153.6 MB / 0.5574 GB " [Timeout remaining time 643] (rm -f ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe ; mkfifo ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe ; head -c 1048576 < ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe >> ../files/ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR.sim & python3 -m fmpy simulate --output-file ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR_res.csv --start-time 0 --stop-time 2 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.00039984 ModelicaTest_3_2_3_ModelicaTest_MultiBody_FourbarVariants_JointUSR.fmu > ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe 2>&1) [Timeout 52.5] [Calling os._exit(0), Time elapsed: 18.95406741183251] Failed to read output from testmodel.py, exit status != 0: ['time', 'j2.s', 'j2.v'] 16.83791403658688 16.843775983 16.566813802 Calling exit ...