Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.FourbarVariants.JointUSP.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo", uses=false)
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 ModelicaServices with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 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 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Running command: "" <> buildModelFMU(ModelicaTest.MultiBody.FourbarVariants.JointUSP,fileNamePrefix="ModelicaTest_3_2_3_cpp_ModelicaTest_MultiBody_FourbarVariants_JointUSP",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.FourbarVariants.JointUSP,fileNamePrefix="ModelicaTest_3_2_3_cpp_ModelicaTest_MultiBody_FourbarVariants_JointUSP",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002562/0.002562, allocations: 189.5 kB / 16.4 MB, free: 5.625 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 2.159/2.159, allocations: 205.4 MB / 222.5 MB, free: 13.85 MB / 186.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001171/0.001171, allocations: 98.84 kB / 270.9 MB, free: 13.37 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo): time 0.3307/0.3307, allocations: 43.56 MB / 362.7 MB, free: 11.32 MB / 266.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0006842/0.0006842, allocations: 19.98 kB / 431.5 MB, free: 20.17 MB / 282.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1473/0.1481, allocations: 58.85 MB / 490.3 MB, free: 9.238 MB / 330.7 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.FourbarVariants.JointUSP): time 0.4627/0.6109, allocations: 58.59 MB / 0.536 GB, free: 9.773 MB / 362.7 MB
Notification: Performance of NFInst.instExpressions: time 0.04069/0.6516, allocations: 14.17 MB / 0.5499 GB, free: 6.168 MB / 362.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01675/0.6684, allocations: 298.5 kB / 0.5502 GB, free: 6.121 MB / 362.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.01176/0.6802, allocations: 2.91 MB / 0.553 GB, free: 4.789 MB / 362.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.03592/0.7162, allocations: 8.366 MB / 0.5612 GB, free: 240 kB / 362.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0071/0.7233, allocations: 1.148 MB / 0.5623 GB, free: 15.5 MB / 378.7 MB
Notification: Performance of NFFlatten.flatten: time 0.11/0.8334, allocations: 40.33 MB / 0.6017 GB, free: 10.29 MB / 410.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01835/0.8518, allocations: 8.086 MB / 0.6096 GB, free: 2.129 MB / 410.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.005318/0.8572, allocations: 1.76 MB / 0.6113 GB, free: 372 kB / 410.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.006191/0.8634, allocations: 2.356 MB / 0.6136 GB, free: 14 MB / 426.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.002234/0.8657, allocations: 240 kB / 0.6138 GB, free: 13.77 MB / 426.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.005356/0.8711, allocations: 1.206 MB / 0.615 GB, free: 12.56 MB / 426.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.005173/0.8763, allocations: 2.646 MB / 0.6176 GB, free: 9.906 MB / 426.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.003152/0.8796, allocations: 1.333 MB / 0.6189 GB, free: 8.562 MB / 426.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.01757/0.8972, allocations: 9.568 MB / 0.6282 GB, free: 14.97 MB / 442.7 MB
Notification: Performance of FrontEnd - DAE generated: time 4.248e-06/0.8972, allocations: 0 / 0.6282 GB, free: 14.97 MB / 442.7 MB
Notification: Performance of FrontEnd: time 4.077e-06/0.8973, allocations: 0 / 0.6282 GB, free: 14.97 MB / 442.7 MB
Notification: Performance of Transformations before backend: time 0.0004206/0.8977, allocations: 0 / 0.6282 GB, free: 14.97 MB / 442.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2166
 * Number of variables: 2166
Notification: Performance of Generate backend data structure: time 0.02755/0.9252, allocations: 7.028 MB / 0.6351 GB, free: 7.859 MB / 442.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.391e-05/0.9253, allocations: 8.031 kB / 0.6351 GB, free: 7.852 MB / 442.7 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.004748/0.9301, allocations: 1.16 MB / 0.6362 GB, free: 6.68 MB / 442.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1331/1.063, allocations: 11.1 MB / 0.6471 GB, free: 11.56 MB / 458.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02154/1.085, allocations: 5.871 MB / 0.6528 GB, free: 5.609 MB / 458.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005949/1.086, allocations: 381.9 kB / 0.6532 GB, free: 5.234 MB / 458.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003091/1.089, allocations: 0.5113 MB / 0.6537 GB, free: 4.723 MB / 458.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.05427/1.143, allocations: 15.63 MB / 0.6689 GB, free: 4.195 MB / 474.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0003815/1.143, allocations: 67.42 kB / 0.669 GB, free: 4.129 MB / 474.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002249/1.146, allocations: 235.8 kB / 0.6692 GB, free: 3.898 MB / 474.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.007479/1.153, allocations: 2.548 MB / 0.6717 GB, free: 1.348 MB / 474.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04977/1.203, allocations: 13.12 MB / 0.6845 GB, free: 4.207 MB / 490.7 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.4976/1.701, allocations: 57.4 MB / 0.7406 GB, free: 150.6 MB / 0.5105 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.03262/1.733, allocations: 6.449 MB / 0.7469 GB, free: 149.9 MB / 0.5105 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.03153/1.765, allocations: 11.43 MB / 0.758 GB, free: 144 MB / 0.5105 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.882e-05/1.765, allocations: 55.78 kB / 0.7581 GB, free: 143.9 MB / 0.5105 GB
Notification: Performance of pre-optimization done (n=245): time 6.853e-06/1.765, allocations: 0 / 0.7581 GB, free: 143.9 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=338): time 0.2555/2.021, allocations: 40.87 MB / 0.798 GB, free: 113.9 MB / 0.5105 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001086/2.021, allocations: 243.3 kB / 0.7982 GB, free: 113.6 MB / 0.5105 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0131/2.034, allocations: 6.126 MB / 0.8042 GB, free: 107.5 MB / 0.5105 GB
Notification: Performance of collectPreVariables (initialization): time 0.0009322/2.035, allocations: 78.25 kB / 0.8043 GB, free: 107.4 MB / 0.5105 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003768/2.039, allocations: 4.261 MB / 0.8085 GB, free: 103.1 MB / 0.5105 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002134/2.041, allocations: 1.229 MB / 0.8097 GB, free: 101.9 MB / 0.5105 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001284/2.042, allocations: 168 kB / 0.8098 GB, free: 101.7 MB / 0.5105 GB
Notification: Performance of setup shared object (initialization): time 0.0006265/2.043, allocations: 0.6461 MB / 0.8105 GB, free: 101.1 MB / 0.5105 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.009543/2.052, allocations: 3.741 MB / 0.8141 GB, free: 97.34 MB / 0.5105 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01264/2.065, allocations: 8.24 MB / 0.8222 GB, free: 86.93 MB / 0.5105 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01252/2.078, allocations: 5.443 MB / 0.8275 GB, free: 81.5 MB / 0.5105 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001378/2.078, allocations: 44 kB / 0.8275 GB, free: 81.45 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=1067) (initialization): time 0.05056/2.129, allocations: 15.16 MB / 0.8423 GB, free: 66.24 MB / 0.5105 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001267/2.129, allocations: 72 kB / 0.8424 GB, free: 66.17 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002295/2.129, allocations: 165 kB / 0.8425 GB, free: 66.01 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02366/2.153, allocations: 4.115 MB / 0.8466 GB, free: 61.89 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01192/2.165, allocations: 1.797 MB / 0.8483 GB, free: 60.09 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05129/2.216, allocations: 20.29 MB / 0.8681 GB, free: 39.66 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.009942/2.226, allocations: 0.4954 MB / 0.8686 GB, free: 39.17 MB / 0.5105 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001921/2.228, allocations: 467.9 kB / 0.8691 GB, free: 38.71 MB / 0.5105 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 717
 * Number of states: 0 ()
 * Number of discrete variables: 46 (jointUSP.prismatic.positiveBranch,body2.color[3],body2.color[2],body2.color[1],body2.body.sphereColor[3],body2.body.sphereColor[2],body2.body.sphereColor[1],jointUSP.rod1Color[3],jointUSP.rod1Color[2],jointUSP.rod1Color[1],jointUSP.cylinderColor[3],jointUSP.cylinderColor[2],jointUSP.cylinderColor[1],jointUSP.sphereColor[3],jointUSP.sphereColor[2],jointUSP.sphereColor[1],ground_rod.color[3],ground_rod.color[2],ground_rod.color[1],body3.color[3],body3.color[2],body3.color[1],body3.body.sphereColor[3],body3.body.sphereColor[2],body3.body.sphereColor[1],body1.color[3],body1.color[2],body1.color[1],body1.body.sphereColor[3],body1.body.sphereColor[2],body1.body.sphereColor[1],revolute.cylinderColor[3],revolute.cylinderColor[2],revolute.cylinderColor[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 (966):
 * Single equations (assignments): 962
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(1,100.0%) 77}
 * Non-linear torn systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.008314/2.236, allocations: 3.114 MB / 0.8721 GB, free: 35.59 MB / 0.5105 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001575/2.238, allocations: 0.5323 MB / 0.8726 GB, free: 35.05 MB / 0.5105 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03718/2.275, allocations: 9.592 MB / 0.882 GB, free: 25.39 MB / 0.5105 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.948e-05/2.275, allocations: 35.98 kB / 0.882 GB, free: 25.36 MB / 0.5105 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.589e-05/2.275, allocations: 16 kB / 0.882 GB, free: 25.34 MB / 0.5105 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.749e-05/2.275, allocations: 16.42 kB / 0.8821 GB, free: 25.33 MB / 0.5105 GB
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 postOpt removeSimpleEquations (simulation): time 0.06624/2.342, allocations: 19.8 MB / 0.9014 GB, free: 5.352 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.432e-05/2.342, allocations: 12.12 kB / 0.9014 GB, free: 5.34 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0044/2.346, allocations: 497.2 kB / 0.9019 GB, free: 4.855 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0224/2.369, allocations: 3.661 MB / 0.9054 GB, free: 1.188 MB / 0.5105 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.000903/2.37, allocations: 69.41 kB / 0.9055 GB, free: 1.121 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.4152/2.785, allocations: 17.83 MB / 0.9229 GB, free: 179.9 MB / 0.5261 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.341e-05/2.785, allocations: 7.219 kB / 0.9229 GB, free: 179.9 MB / 0.5261 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.05075/2.836, allocations: 15.16 MB / 0.9377 GB, free: 175.9 MB / 0.5261 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004663/2.84, allocations: 1.157 MB / 0.9389 GB, free: 175.5 MB / 0.5261 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002214/2.843, allocations: 63.91 kB / 0.9389 GB, free: 175.5 MB / 0.5261 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.006149/2.849, allocations: 228.3 kB / 0.9391 GB, free: 175.4 MB / 0.5261 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001611/2.85, allocations: 226.9 kB / 0.9394 GB, free: 175.4 MB / 0.5261 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0007556/2.851, allocations: 93.52 kB / 0.9394 GB, free: 175.3 MB / 0.5261 GB
Notification: Performance of sorting global known variables: time 0.0109/2.862, allocations: 3.939 MB / 0.9433 GB, free: 174 MB / 0.5261 GB
Notification: Performance of sort global known variables: time 4.81e-07/2.862, allocations: 0 / 0.9433 GB, free: 174 MB / 0.5261 GB
Notification: Performance of remove unused functions: time 0.01213/2.874, allocations: 1.736 MB / 0.945 GB, free: 173.9 MB / 0.5261 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 29
 * Number of states: 2 (revolute.phi,revolute.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 (256):
 * Single equations (assignments): 252
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(1,100.0%) 64}
 * Non-linear torn systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.04919/2.924, allocations: 17.18 MB / 0.9618 GB, free: 165.5 MB / 0.5261 GB
Notification: Performance of simCode: created initialization part: time 0.02165/2.945, allocations: 9.187 MB / 0.9707 GB, free: 160.8 MB / 0.5261 GB
Notification: Performance of simCode: created event and clocks part: time 1.661e-05/2.945, allocations: 4 kB / 0.9707 GB, free: 160.8 MB / 0.5261 GB
Notification: Performance of simCode: created simulation system equations: time 0.009355/2.955, allocations: 3.62 MB / 0.9743 GB, free: 157.6 MB / 0.5261 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.006718/2.962, allocations: 0.6938 MB / 0.975 GB, free: 157 MB / 0.5261 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.081/3.043, allocations: 138.1 MB / 1.11 GB, free: 15.45 MB / 0.5261 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01038/3.053, allocations: 4.97 MB / 1.115 GB, free: 10.44 MB / 0.5261 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.006938/3.06, allocations: 3.143 MB / 1.118 GB, free: 7.297 MB / 0.5261 GB
Notification: Performance of SimCode: time 2.104e-06/3.06, allocations: 0 / 1.118 GB, free: 7.297 MB / 0.5261 GB
Notification: Performance of buildModelFMU: Generate C++ for platform static: time 5.555/8.615, allocations: 156.6 MB / 1.271 GB, free: 55.27 MB / 0.5261 GB
(rm -f ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.FourbarVariants.JointUSP.pipe ; mkfifo ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.FourbarVariants.JointUSP.pipe ; head -c 1048576 < ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.FourbarVariants.JointUSP.pipe >> ../files/ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.FourbarVariants.JointUSP.sim & /home/hudson/saved_omc/OMSimulator/install/linux/bin/OMSimulator -r=ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.FourbarVariants.JointUSP_res.mat --tempDir=temp_ModelicaTest_3_2_3_cpp_ModelicaTest_MultiBody_FourbarVariants_JointUSP_fmu --startTime=0 --stopTime=5 --timeout=50 --tolerance=1e-06 ModelicaTest_3_2_3_cpp_ModelicaTest_MultiBody_FourbarVariants_JointUSP.fmu > ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.FourbarVariants.JointUSP.pipe 2>&1)
TODO: How the !@#!# did the simulation report success but simulation result ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.FourbarVariants.JointUSP_res.mat does not exist to compare? outputFormat=mat