Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: "" <> buildModelFMU(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a,fileNamePrefix="Modelica_4_0_0_Modelica_Mechanics_MultiBody_Examples_Loops_Engine1a",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a,fileNamePrefix="Modelica_4_0_0_Modelica_Mechanics_MultiBody_Examples_Loops_Engine1a",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001813/0.001813, allocations: 107.3 kB / 16.92 MB, free: 5.77 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001844/0.001844, allocations: 191.3 kB / 17.85 MB, free: 5.168 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 2.283/2.283, allocations: 222.9 MB / 241.5 MB, free: 15.29 MB / 206.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.002164/0.002164, allocations: 51.23 kB / 300.7 MB, free: 5.797 MB / 238.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1464/0.1486, allocations: 49.45 MB / 350.2 MB, free: 4.34 MB / 286.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a): time 0.03034/0.179, allocations: 19.42 MB / 369.6 MB, free: 0.8281 MB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.2933/0.4724, allocations: 5.967 MB / 375.6 MB, free: 8.668 MB / 302.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.007677/0.4801, allocations: 157.9 kB / 375.7 MB, free: 8.668 MB / 302.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01778/0.498, allocations: 5.855 MB / 381.6 MB, free: 7.637 MB / 302.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01456/0.5126, allocations: 3.337 MB / 384.9 MB, free: 6.391 MB / 302.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.006036/0.5187, allocations: 1.569 MB / 386.5 MB, free: 5.617 MB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.02318/0.5419, allocations: 12.36 MB / 398.8 MB, free: 36 kB / 302.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01727/0.5592, allocations: 7.654 MB / 406.5 MB, free: 9.082 MB / 318.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.01059/0.5699, allocations: 3.55 MB / 410 MB, free: 5.734 MB / 318.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01165/0.5816, allocations: 4.743 MB / 414.8 MB, free: 1.262 MB / 318.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.004014/0.5857, allocations: 0.7109 MB / 415.5 MB, free: 0.5508 MB / 318.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.007657/0.5934, allocations: 1.678 MB / 417.2 MB, free: 14.92 MB / 334.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.008777/0.6022, allocations: 4.176 MB / 421.3 MB, free: 10.75 MB / 334.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.01301/0.6153, allocations: 4.771 MB / 426.1 MB, free: 5.98 MB / 334.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.02667/0.642, allocations: 14.04 MB / 440.2 MB, free: 8.246 MB / 350.1 MB
Notification: Performance of FrontEnd - DAE generated: time 9.989e-06/0.6421, allocations: 0 / 440.2 MB, free: 8.246 MB / 350.1 MB
Notification: Performance of FrontEnd: time 2.485e-06/0.6421, allocations: 0 / 440.2 MB, free: 8.246 MB / 350.1 MB
Notification: Performance of Transformations before backend: time 0.0006827/0.6428, allocations: 0 / 440.2 MB, free: 8.246 MB / 350.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2386
 * Number of variables: 2386
Notification: Performance of Generate backend data structure: time 0.05199/0.6948, allocations: 10.32 MB / 450.5 MB, free: 13.98 MB / 366.1 MB
Notification: Performance of prepare preOptimizeDAE: time 8.237e-05/0.6949, allocations: 12.03 kB / 450.5 MB, free: 13.97 MB / 366.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.006854/0.7018, allocations: 1.141 MB / 451.6 MB, free: 12.87 MB / 366.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.4272/1.129, allocations: 24.25 MB / 475.9 MB, free: 4.574 MB / 382.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.03651/1.166, allocations: 9.571 MB / 485.4 MB, free: 11.07 MB / 398.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0008477/1.167, allocations: 429.1 kB / 485.9 MB, free: 10.7 MB / 398.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003987/1.171, allocations: 0.5879 MB / 486.4 MB, free: 10.16 MB / 398.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.06136/1.232, allocations: 16.17 MB / 0.4908 GB, free: 9.086 MB / 414.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0005651/1.233, allocations: 103.1 kB / 0.4909 GB, free: 8.984 MB / 414.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002283/1.235, allocations: 264.8 kB / 0.4912 GB, free: 8.773 MB / 414.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.005449/1.24, allocations: 2.763 MB / 0.4939 GB, free: 6.016 MB / 414.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03638/1.277, allocations: 12.87 MB / 0.5065 GB, free: 9.137 MB / 430.1 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.7711/2.048, allocations: 66.44 MB / 0.5713 GB, free: 99.01 MB / 446.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.03965/2.088, allocations: 7.598 MB / 0.5788 GB, free: 96.55 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01033/2.098, allocations: 4.207 MB / 0.5829 GB, free: 94.76 MB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.03561/2.134, allocations: 11.69 MB / 0.5943 GB, free: 88.53 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0001363/2.134, allocations: 70.66 kB / 0.5943 GB, free: 88.47 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=280): time 1.571e-05/2.134, allocations: 0 / 0.5943 GB, free: 88.47 MB / 446.1 MB
Notification: Performance of matching and sorting (n=339): time 0.3281/2.462, allocations: 47.38 MB / 0.6406 GB, free: 48.3 MB / 446.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.000244/2.462, allocations: 398.1 kB / 0.641 GB, free: 47.82 MB / 446.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.02484/2.487, allocations: 7.155 MB / 0.648 GB, free: 40.7 MB / 446.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.001364/2.489, allocations: 77.7 kB / 0.648 GB, free: 40.62 MB / 446.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.006433/2.495, allocations: 5.18 MB / 0.6531 GB, free: 35.43 MB / 446.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.002981/2.498, allocations: 1.244 MB / 0.6543 GB, free: 34.2 MB / 446.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.008796/2.507, allocations: 1.65 MB / 0.6559 GB, free: 32.54 MB / 446.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001022/2.507, allocations: 301.1 kB / 0.6562 GB, free: 32.24 MB / 446.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01058/2.518, allocations: 3.959 MB / 0.6601 GB, free: 28.27 MB / 446.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01449/2.532, allocations: 8.587 MB / 0.6685 GB, free: 17.4 MB / 446.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.024/2.556, allocations: 13.49 MB / 0.6816 GB, free: 1.641 MB / 446.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0003094/2.557, allocations: 44 kB / 0.6817 GB, free: 1.598 MB / 446.1 MB
Notification: Performance of matching and sorting (n=1070) (initialization): time 0.0461/2.603, allocations: 17.17 MB / 0.6985 GB, free: 372 kB / 462.1 MB
Notification: Performance of prepare postOptimizeDAE: time 0.000137/2.603, allocations: 76.25 kB / 0.6985 GB, free: 296 kB / 462.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002372/2.603, allocations: 160 kB / 0.6987 GB, free: 136 kB / 462.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.4241/3.028, allocations: 5.772 MB / 0.7043 GB, free: 141.7 MB / 462.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01381/3.041, allocations: 1.758 MB / 0.706 GB, free: 141.7 MB / 462.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06936/3.111, allocations: 31.67 MB / 0.737 GB, free: 128.1 MB / 462.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01004/3.121, allocations: 0.5179 MB / 0.7375 GB, free: 128 MB / 462.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002819/3.124, allocations: 477.1 kB / 0.7379 GB, free: 127.9 MB / 462.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 727
 * Number of states: 0 ()
 * Number of discrete variables: 66 (cylinder.boxColor[3],cylinder.boxColor[2],cylinder.boxColor[1],b2.cylinderColor[3],b2.cylinderColor[2],b2.cylinderColor[1],b1.cylinderColor[3],b1.cylinderColor[2],b1.cylinderColor[1],cylPosition.color[3],cylPosition.color[2],cylPosition.color[1],mid.color[3],mid.color[2],mid.color[1],crank4.color[3],crank4.color[2],crank4.color[1],crank4.body.sphereColor[3],crank4.body.sphereColor[2],crank4.body.sphereColor[1],crank3.color[3],crank3.color[2],crank3.color[1],crank3.body.sphereColor[3],crank3.body.sphereColor[2],crank3.body.sphereColor[1],crank2.color[3],crank2.color[2],crank2.color[1],crank2.body.sphereColor[3],crank2.body.sphereColor[2],crank2.body.sphereColor[1],crank1.color[3],crank1.color[2],crank1.color[1],crank1.body.sphereColor[3],crank1.body.sphereColor[2],crank1.body.sphereColor[1],bearing.cylinderColor[3],bearing.cylinderColor[2],bearing.cylinderColor[1],connectingRod.color[3],connectingRod.color[2],connectingRod.color[1],connectingRod.body.sphereColor[3],connectingRod.body.sphereColor[2],connectingRod.body.sphereColor[1],piston.color[3],piston.color[2],piston.color[1],piston.body.sphereColor[3],piston.body.sphereColor[2],piston.body.sphereColor[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 (934):
 * Single equations (assignments): 928
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(8,93,51.6%), (2,6,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(2,4)}
Notification: Performance of prepare postOptimizeDAE: time 0.01162/3.136, allocations: 3.456 MB / 0.7413 GB, free: 126.5 MB / 462.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003642/3.139, allocations: 0.6506 MB / 0.7419 GB, free: 126.5 MB / 462.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04807/3.187, allocations: 11.48 MB / 0.7531 GB, free: 116.4 MB / 462.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0001064/3.188, allocations: 32 kB / 0.7532 GB, free: 116.3 MB / 462.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.739e-05/3.188, allocations: 20 kB / 0.7532 GB, free: 116.3 MB / 462.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.00012/3.188, allocations: 15.94 kB / 0.7532 GB, free: 116.3 MB / 462.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.08876/3.277, allocations: 24.87 MB / 0.7775 GB, free: 91.16 MB / 462.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001089/3.277, allocations: 4 kB / 0.7775 GB, free: 91.16 MB / 462.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.004163/3.281, allocations: 415.4 kB / 0.7779 GB, free: 90.75 MB / 462.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.03237/3.313, allocations: 5.395 MB / 0.7832 GB, free: 85.42 MB / 462.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001196/3.315, allocations: 63.28 kB / 0.7832 GB, free: 85.36 MB / 462.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.07442/3.389, allocations: 30.34 MB / 0.8129 GB, free: 54.87 MB / 462.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.618e-05/3.389, allocations: 11.94 kB / 0.8129 GB, free: 54.86 MB / 462.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.05727/3.446, allocations: 16.49 MB / 0.829 GB, free: 38.36 MB / 462.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.004203/3.451, allocations: 0.6785 MB / 0.8296 GB, free: 37.66 MB / 462.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.003661/3.454, allocations: 63.98 kB / 0.8297 GB, free: 37.59 MB / 462.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.008306/3.463, allocations: 259.6 kB / 0.8299 GB, free: 37.34 MB / 462.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002136/3.465, allocations: 242.7 kB / 0.8302 GB, free: 37.1 MB / 462.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.001055/3.466, allocations: 99.48 kB / 0.8303 GB, free: 37.01 MB / 462.1 MB
Notification: Performance of sorting global known variables: time 0.01531/3.481, allocations: 4.616 MB / 0.8348 GB, free: 32.43 MB / 462.1 MB
Notification: Performance of sort global known variables: time 7.92e-07/3.481, allocations: 4 kB / 0.8348 GB, free: 32.42 MB / 462.1 MB
Notification: Performance of remove unused functions: time 0.01759/3.499, allocations: 1.969 MB / 0.8367 GB, free: 30.46 MB / 462.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 28
 * Number of states: 2 (inertia.phi,inertia.w)
 * 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 (214):
 * Single equations (assignments): 208
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * 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,87,55.1%), (2,4,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(2,6)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.05873/3.558, allocations: 17.18 MB / 0.8535 GB, free: 13.08 MB / 462.1 MB
Notification: Performance of simCode: created initialization part: time 0.3931/3.951, allocations: 13.31 MB / 0.8665 GB, free: 141.9 MB / 462.1 MB
Notification: Performance of simCode: created event and clocks part: time 2.351e-05/3.951, allocations: 1.844 kB / 0.8665 GB, free: 141.9 MB / 462.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.01688/3.968, allocations: 4.691 MB / 0.8711 GB, free: 141.5 MB / 462.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01478/3.983, allocations: 1.085 MB / 0.8721 GB, free: 141.4 MB / 462.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.3105/4.293, allocations: 114.1 MB / 0.9835 GB, free: 40.77 MB / 462.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01601/4.309, allocations: 5.529 MB / 0.9889 GB, free: 35.2 MB / 462.1 MB
Notification: Performance of simCode: alias equations: time 0.0135/4.323, allocations: 2.622 MB / 0.9915 GB, free: 32.57 MB / 462.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.009465/4.333, allocations: 3.741 MB / 0.9952 GB, free: 28.83 MB / 462.1 MB
Notification: Performance of SimCode: time 1.764e-06/4.333, allocations: 0 / 0.9952 GB, free: 28.83 MB / 462.1 MB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.8974/5.23, allocations: 162.3 MB / 1.154 GB, free: 60.41 MB / 478.1 MB
Notification: Performance of buildModelFMU: Generate platform static: time 26.25/31.48, allocations: 17.42 kB / 1.154 GB, free: 60.39 MB / 478.1 MB
(rm -f Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe ; mkfifo Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe ; head -c 1048576 < Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe >> ../files/Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.sim & python3 -m fmpy simulate  --output-file Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a_res.csv --start-time 0 --stop-time 5 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.002 Modelica_4_0_0_Modelica_Mechanics_MultiBody_Examples_Loops_Engine1a.fmu > Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe 2>&1)
diffSimulationResults("Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a_res.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.0.0/Modelica/Mechanics/MultiBody/Examples/Loops/Engine1a/Engine1a.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches