Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.SpringDamperSeries.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.001374/0.001374, allocations: 108.7 kB / 19.8 MB, free: 2.516 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.001447/0.001447, allocations: 218.2 kB / 23 MB, free: 5.609 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.444/1.444, allocations: 230.2 MB / 256.2 MB, free: 8.684 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.292/0.292, allocations: 46.9 MB / 359.4 MB, free: 4.91 MB / 286.1 MB " [Timeout remaining time 179] 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.Forces.SpringDamperSeries,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Forces_SpringDamperSeries",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(ModelicaTest.MultiBody.Forces.SpringDamperSeries,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Forces_SpringDamperSeries",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.002458/0.002458, allocations: 87.86 kB / 0.4941 GB, free: 8.996 MB / 366.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.3311/0.3336, allocations: 65.98 MB / 0.5586 GB, free: 5.141 MB / 398.1 MB Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Forces.SpringDamperSeries): time 0.01273/0.3463, allocations: 10.57 MB / 0.5689 GB, free: 10.52 MB / 414.1 MB Notification: Performance of NFInst.instExpressions: time 0.007872/0.3542, allocations: 3.283 MB / 0.5721 GB, free: 7.219 MB / 414.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.00185/0.356, allocations: 51.88 kB / 0.5722 GB, free: 7.168 MB / 414.1 MB Notification: Performance of NFTyping.typeComponents: time 0.00548/0.3615, allocations: 2.414 MB / 0.5745 GB, free: 4.746 MB / 414.1 MB Notification: Performance of NFTyping.typeBindings: time 0.004469/0.366, allocations: 1.252 MB / 0.5757 GB, free: 3.488 MB / 414.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.001856/0.3678, allocations: 0.5185 MB / 0.5762 GB, free: 2.969 MB / 414.1 MB Notification: Performance of NFFlatten.flatten: time 0.005076/0.3729, allocations: 2.924 MB / 0.5791 GB, free: 40 kB / 414.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.003967/0.3769, allocations: 2.257 MB / 0.5813 GB, free: 13.73 MB / 430.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.002599/0.3795, allocations: 1.277 MB / 0.5826 GB, free: 12.45 MB / 430.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.003396/0.3829, allocations: 1.853 MB / 0.5844 GB, free: 10.59 MB / 430.1 MB Notification: Performance of NFPackage.collectConstants: time 0.001028/0.3839, allocations: 296 kB / 0.5846 GB, free: 10.3 MB / 430.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.002777/0.3867, allocations: 1.026 MB / 0.5856 GB, free: 9.277 MB / 430.1 MB Notification: Performance of NFScalarize.scalarize: time 0.002304/0.389, allocations: 1.628 MB / 0.5872 GB, free: 7.645 MB / 430.1 MB Notification: Performance of NFVerifyModel.verify: time 0.0035/0.3925, allocations: 1.957 MB / 0.5891 GB, free: 5.676 MB / 430.1 MB Notification: Performance of NFConvertDAE.convert: time 0.01042/0.4029, allocations: 6.26 MB / 0.5953 GB, free: 15.39 MB / 446.1 MB Notification: Performance of FrontEnd - DAE generated: time 9.267e-06/0.4029, allocations: 0 / 0.5953 GB, free: 15.39 MB / 446.1 MB Notification: Performance of FrontEnd: time 2.685e-06/0.4029, allocations: 4 kB / 0.5953 GB, free: 15.39 MB / 446.1 MB Notification: Performance of Transformations before backend: time 0.000321/0.4032, allocations: 0 / 0.5953 GB, free: 15.39 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: 1473 * Number of variables: 1473 Notification: Performance of Generate backend data structure: time 0.01351/0.4167, allocations: 4.359 MB / 0.5995 GB, free: 10.95 MB / 446.1 MB Notification: Performance of prepare preOptimizeDAE: time 4.632e-05/0.4168, allocations: 8.031 kB / 0.5995 GB, free: 10.95 MB / 446.1 MB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.00182/0.4186, allocations: 0.6913 MB / 0.6002 GB, free: 10.24 MB / 446.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00643/0.425, allocations: 1.835 MB / 0.602 GB, free: 8.402 MB / 446.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.003623/0.4286, allocations: 1.773 MB / 0.6037 GB, free: 6.578 MB / 446.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001929/0.4288, allocations: 233.7 kB / 0.604 GB, free: 6.348 MB / 446.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007596/0.4296, allocations: 295.8 kB / 0.6042 GB, free: 6.059 MB / 446.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.02089/0.4505, allocations: 10.65 MB / 0.6146 GB, free: 10.78 MB / 462.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 0.0001216/0.4506, allocations: 35.67 kB / 0.6147 GB, free: 10.74 MB / 462.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004898/0.4511, allocations: 151.8 kB / 0.6148 GB, free: 10.59 MB / 462.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003635/0.4547, allocations: 2.036 MB / 0.6168 GB, free: 8.551 MB / 462.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02055/0.4753, allocations: 9.356 MB / 0.6259 GB, free: 15.17 MB / 478.1 MB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.04911/0.5244, allocations: 37.91 MB / 0.663 GB, free: 8.254 MB / 0.4981 GB Notification: Performance of preOpt comSubExp (simulation): time 0.01118/0.5356, allocations: 4.044 MB / 0.6669 GB, free: 4.047 MB / 0.4981 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.003092/0.5387, allocations: 2.078 MB / 0.6689 GB, free: 1.953 MB / 0.4981 GB Notification: Performance of preOpt evalFunc (simulation): time 0.00922/0.5479, allocations: 6.117 MB / 0.6749 GB, free: 11.55 MB / 0.5137 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.689e-05/0.5479, allocations: 59.8 kB / 0.675 GB, free: 11.49 MB / 0.5137 GB Notification: Performance of pre-optimization done (n=181): time 4.719e-06/0.548, allocations: 0 / 0.675 GB, free: 11.49 MB / 0.5137 GB Notification: Performance of matching and sorting (n=210): time 0.06631/0.6143, allocations: 22.2 MB / 0.6966 GB, free: 5.133 MB / 0.5294 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001108/0.6144, allocations: 157.8 kB / 0.6968 GB, free: 4.945 MB / 0.5294 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005107/0.6195, allocations: 3.383 MB / 0.7001 GB, free: 1.555 MB / 0.5294 GB Notification: Performance of collectPreVariables (initialization): time 0.000468/0.6199, allocations: 57.7 kB / 0.7002 GB, free: 1.492 MB / 0.5294 GB Notification: Performance of collectInitialEqns (initialization): time 0.002452/0.6224, allocations: 2.916 MB / 0.703 GB, free: 14.55 MB / 0.545 GB Notification: Performance of collectInitialBindings (initialization): time 0.001184/0.6236, allocations: 0.8295 MB / 0.7038 GB, free: 13.71 MB / 0.545 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.00196/0.6255, allocations: 1.203 MB / 0.705 GB, free: 12.5 MB / 0.545 GB Notification: Performance of setup shared object (initialization): time 0.0001285/0.6257, allocations: 305.1 kB / 0.7053 GB, free: 12.2 MB / 0.545 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.003963/0.6296, allocations: 2.342 MB / 0.7076 GB, free: 9.848 MB / 0.545 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.007417/0.637, allocations: 5.788 MB / 0.7132 GB, free: 2.344 MB / 0.545 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.0125/0.6496, allocations: 8.821 MB / 0.7218 GB, free: 7.816 MB / 0.5606 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001145/0.6497, allocations: 36 kB / 0.7219 GB, free: 7.781 MB / 0.5606 GB Notification: Performance of matching and sorting (n=775) (initialization): time 0.2994/0.949, allocations: 8.695 MB / 0.7304 GB, free: 151.3 MB / 0.5606 GB Notification: Performance of prepare postOptimizeDAE: time 0.000164/0.9492, allocations: 58.23 kB / 0.7304 GB, free: 151.3 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003085/0.9495, allocations: 120.8 kB / 0.7305 GB, free: 151.3 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.003149/0.9527, allocations: 1.04 MB / 0.7315 GB, free: 151.3 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004643/0.9573, allocations: 1.381 MB / 0.7329 GB, free: 151.3 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004768/0.9621, allocations: 5.824 MB / 0.7386 GB, free: 148.1 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004185/0.9662, allocations: 328.9 kB / 0.7389 GB, free: 148 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0006871/0.9669, allocations: 358.4 kB / 0.7392 GB, free: 148 MB / 0.5606 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 553 * Number of states: 0 () * Number of discrete variables: 36 (prismatic1.boxColor[3],prismatic1.boxColor[2],prismatic1.boxColor[1],universal1.cylinderColor[3],universal1.cylinderColor[2],universal1.cylinderColor[1],pointMass.sphereColor[3],pointMass.sphereColor[2],pointMass.sphereColor[1],s1.color[3],s1.color[2],s1.color[1],s1.massColor[3],s1.massColor[2],s1.massColor[1],sps1.color[3],sps1.color[2],sps1.color[1],sps1.color_b[3],sps1.color_b[2],sps1.color_b[1],sps1.color_a[3],sps1.color_a[2],sps1.color_a[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 (734): * Single equations (assignments): 727 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 2 * Torn equation systems: 2 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details (not torn): * Constant Jacobian (size): 0 systems * Linear Jacobian (size,density): 2 systems {(3,100.0%), (3,100.0%)} * Non-linear Jacobian (size): 0 systems * Without analytic Jacobian (size): 0 systems Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 2 systems {(1,9,100.0%), (1,4,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.002681/0.9696, allocations: 1.939 MB / 0.7411 GB, free: 147.2 MB / 0.5606 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005368/0.9702, allocations: 325 kB / 0.7414 GB, free: 147.2 MB / 0.5606 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006006/0.9762, allocations: 3.953 MB / 0.7453 GB, free: 145.6 MB / 0.5606 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.396e-05/0.9762, allocations: 26.97 kB / 0.7453 GB, free: 145.6 MB / 0.5606 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.358e-05/0.9762, allocations: 7.844 kB / 0.7453 GB, free: 145.6 MB / 0.5606 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.423e-05/0.9762, allocations: 8.906 kB / 0.7453 GB, free: 145.6 MB / 0.5606 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01272/0.989, allocations: 8.802 MB / 0.7539 GB, free: 142 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.303e-05/0.989, allocations: 0 / 0.7539 GB, free: 142 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00187/0.9908, allocations: 351.8 kB / 0.7543 GB, free: 141.8 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.002248/0.9931, allocations: 0.8715 MB / 0.7551 GB, free: 141.1 MB / 0.5606 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002857/0.9934, allocations: 39.94 kB / 0.7552 GB, free: 141.1 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.003854/0.9972, allocations: 5.123 MB / 0.7602 GB, free: 136 MB / 0.5606 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.427e-06/0.9972, allocations: 8.016 kB / 0.7602 GB, free: 136 MB / 0.5606 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.008749/1.006, allocations: 6.914 MB / 0.7669 GB, free: 129.3 MB / 0.5606 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0008443/1.007, allocations: 375.4 kB / 0.7673 GB, free: 129 MB / 0.5606 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005063/1.007, allocations: 39.97 kB / 0.7673 GB, free: 129 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001526/1.009, allocations: 120 kB / 0.7674 GB, free: 128.9 MB / 0.5606 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003279/1.009, allocations: 120.7 kB / 0.7676 GB, free: 128.8 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001417/1.009, allocations: 55.98 kB / 0.7676 GB, free: 128.7 MB / 0.5606 GB Notification: Performance of sorting global known variables: time 0.002487/1.012, allocations: 2.073 MB / 0.7696 GB, free: 126.6 MB / 0.5606 GB Notification: Performance of sort global known variables: time 8e-08/1.012, allocations: 0 / 0.7696 GB, free: 126.6 MB / 0.5606 GB Notification: Performance of remove unused functions: time 0.004227/1.016, allocations: 0.9525 MB / 0.7706 GB, free: 125.7 MB / 0.5606 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 4 * Number of states: 7 (sps1.s_damper,universal1.phi_a,universal1.phi_b,universal1.w_a,universal1.w_b,prismatic1.s,prismatic1.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 (178): * Single equations (assignments): 171 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 2 * Torn equation systems: 2 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details (not torn): * Constant Jacobian (size): 0 systems * Linear Jacobian (size,density): 2 systems {(3,100.0%), (3,100.0%)} * Non-linear Jacobian (size): 0 systems * Without analytic Jacobian (size): 0 systems Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 2 systems {(1,6,100.0%), (1,4,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.008731/1.025, allocations: 7.563 MB / 0.7779 GB, free: 118.1 MB / 0.5606 GB Notification: Performance of simCode: created initialization part: time 0.008433/1.033, allocations: 5.928 MB / 0.7837 GB, free: 112.2 MB / 0.5606 GB Notification: Performance of simCode: created event and clocks part: time 6.592e-06/1.033, allocations: 0 / 0.7837 GB, free: 112.2 MB / 0.5606 GB Notification: Performance of simCode: created simulation system equations: time 0.003379/1.037, allocations: 2.339 MB / 0.786 GB, free: 109.8 MB / 0.5606 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002077/1.039, allocations: 350.1 kB / 0.7864 GB, free: 109.4 MB / 0.5606 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.05546/1.094, allocations: 44.29 MB / 0.8296 GB, free: 59.64 MB / 0.5606 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003148/1.097, allocations: 3.023 MB / 0.8326 GB, free: 56.59 MB / 0.5606 GB Notification: Performance of simCode: alias equations: time 0.003753/1.101, allocations: 1.853 MB / 0.8344 GB, free: 54.73 MB / 0.5606 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002416/1.103, allocations: 1.718 MB / 0.836 GB, free: 53.01 MB / 0.5606 GB Notification: Performance of SimCode: time 1.392e-06/1.103, allocations: 0 / 0.836 GB, free: 53.01 MB / 0.5606 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1102/1.214, allocations: 65.21 MB / 0.8997 GB, free: 4.113 MB / 0.5762 GB Notification: Performance of buildModelFMU: Generate platform static: time 18.52/19.73, allocations: 10.52 kB / 0.8997 GB, free: 4.105 MB / 0.5762 GB " [Timeout remaining time 640] (rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.SpringDamperSeries.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.SpringDamperSeries.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.SpringDamperSeries.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.SpringDamperSeries.sim & python3 -m fmpy simulate --output-file ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.SpringDamperSeries_res.csv --start-time 0 --stop-time 1 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.00019992 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Forces_SpringDamperSeries.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.SpringDamperSeries.pipe 2>&1) [Timeout 52.5] [Calling os._exit(0), Time elapsed: 22.607098548003705] Failed to read output from testmodel.py, exit status != 0: ['time', 'prismatic1.s', 'prismatic1.v', 'sps1.s_damper', 'universal1.phi_a', 'universal1.phi_b', 'universal1.w_a', 'universal1.w_b'] 19.86070121699595 19.88942372 19.480785548 Calling exit ...