Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSP.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 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 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.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(ModelicaTest.MultiBody.FourbarVariants.JointUSP,fileNamePrefix="ModelicaTest_3_2_3_ModelicaTest_MultiBody_FourbarVariants_JointUSP",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.FourbarVariants.JointUSP,fileNamePrefix="ModelicaTest_3_2_3_ModelicaTest_MultiBody_FourbarVariants_JointUSP",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.001344/0.001344, allocations: 95.34 kB / 15.68 MB, free: 488 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.00136/0.001361, allocations: 177.9 kB / 16.57 MB, free: 6.379 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.829/1.829, allocations: 205.4 MB / 222.7 MB, free: 5.684 MB / 174.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo): time 0.562/0.562, allocations: 43.54 MB / 314.5 MB, free: 8.793 MB / 254.1 MB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.0006001/0.0006006, allocations: 20 kB / 384 MB, free: 7.922 MB / 318.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1737/0.1744, allocations: 58.85 MB / 442.8 MB, free: 12.97 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.FourbarVariants.JointUSP): time 0.3837/0.5581, allocations: 58.6 MB / 0.4897 GB, free: 13.21 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.0518/0.61, allocations: 14.16 MB / 0.5035 GB, free: 3.559 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.02337/0.6334, allocations: 297 kB / 0.5038 GB, free: 3.512 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01321/0.6467, allocations: 2.918 MB / 0.5067 GB, free: 2.543 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.04187/0.6887, allocations: 8.363 MB / 0.5148 GB, free: 14.54 MB / 398.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.009343/0.698, allocations: 1.145 MB / 0.5159 GB, free: 14.1 MB / 398.1 MB
Notification: Performance of NFFlatten.flatten: time 0.07031/0.7684, allocations: 40.33 MB / 0.5553 GB, free: 12.73 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01636/0.7848, allocations: 8.102 MB / 0.5632 GB, free: 4.594 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.005447/0.7903, allocations: 1.748 MB / 0.5649 GB, free: 2.84 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004847/0.7952, allocations: 2.364 MB / 0.5672 GB, free: 480 kB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001069/0.7963, allocations: 240 kB / 0.5675 GB, free: 240 kB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003326/0.7996, allocations: 1.198 MB / 0.5686 GB, free: 15.04 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.003305/0.803, allocations: 2.654 MB / 0.5712 GB, free: 12.38 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.00213/0.8051, allocations: 1.33 MB / 0.5725 GB, free: 11.04 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01444/0.8196, allocations: 9.559 MB / 0.5819 GB, free: 1.453 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 1.143e-05/0.8197, allocations: 0 / 0.5819 GB, free: 1.453 MB / 446.1 MB
Notification: Performance of FrontEnd: time 6.141e-06/0.8197, allocations: 3.938 kB / 0.5819 GB, free: 1.449 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0007314/0.8204, allocations: 0 / 0.5819 GB, free: 1.449 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: 2166
 * Number of variables: 2166
Notification: Performance of Generate backend data structure: time 0.02363/0.8441, allocations: 7.029 MB / 0.5887 GB, free: 10.34 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 6.352e-05/0.8442, allocations: 12.02 kB / 0.5888 GB, free: 10.33 MB / 462.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.005637/0.8498, allocations: 1.157 MB / 0.5899 GB, free: 9.16 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06294/0.9128, allocations: 11.1 MB / 0.6007 GB, free: 14.03 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0165/0.9293, allocations: 5.867 MB / 0.6065 GB, free: 8.09 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.00049/0.9299, allocations: 381.9 kB / 0.6068 GB, free: 7.715 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003122/0.933, allocations: 0.5074 MB / 0.6073 GB, free: 7.207 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03727/0.9703, allocations: 15.63 MB / 0.6226 GB, free: 6.68 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002892/0.9706, allocations: 67.38 kB / 0.6226 GB, free: 6.613 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0009627/0.9716, allocations: 243.7 kB / 0.6229 GB, free: 6.375 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004686/0.9763, allocations: 2.548 MB / 0.6254 GB, free: 3.824 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.3999/1.376, allocations: 13.1 MB / 0.6382 GB, free: 135.8 MB / 494.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.09399/1.47, allocations: 57.43 MB / 0.6942 GB, free: 98.21 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01978/1.49, allocations: 6.45 MB / 0.7005 GB, free: 96.15 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.02211/1.512, allocations: 11.43 MB / 0.7117 GB, free: 88.27 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.673e-05/1.513, allocations: 62.47 kB / 0.7118 GB, free: 88.21 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=245): time 6.572e-06/1.513, allocations: 2 kB / 0.7118 GB, free: 88.21 MB / 494.1 MB
Notification: Performance of matching and sorting (n=338): time 0.1386/1.651, allocations: 40.88 MB / 0.7517 GB, free: 49.29 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001038/1.651, allocations: 239.9 kB / 0.7519 GB, free: 49 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01013/1.661, allocations: 6.129 MB / 0.7579 GB, free: 42.89 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.001152/1.663, allocations: 73.7 kB / 0.758 GB, free: 42.82 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.002955/1.666, allocations: 4.454 MB / 0.7623 GB, free: 38.36 MB / 494.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001909/1.668, allocations: 1.235 MB / 0.7635 GB, free: 37.14 MB / 494.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007418/1.668, allocations: 164 kB / 0.7637 GB, free: 36.98 MB / 494.1 MB
Notification: Performance of setup shared object (initialization): time 0.0005099/1.669, allocations: 0.6453 MB / 0.7643 GB, free: 36.32 MB / 494.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005815/1.675, allocations: 3.737 MB / 0.768 GB, free: 32.58 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.008429/1.683, allocations: 8.242 MB / 0.776 GB, free: 22.1 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.007864/1.691, allocations: 5.435 MB / 0.7813 GB, free: 16.64 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001105/1.691, allocations: 45.69 kB / 0.7814 GB, free: 16.6 MB / 494.1 MB
Notification: Performance of matching and sorting (n=1067) (initialization): time 0.03297/1.724, allocations: 15.38 MB / 0.7964 GB, free: 1.172 MB / 494.1 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001196/1.724, allocations: 72 kB / 0.7964 GB, free: 1.102 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002216/1.725, allocations: 162 kB / 0.7966 GB, free: 0.9453 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01463/1.739, allocations: 4.127 MB / 0.8006 GB, free: 12.81 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007294/1.747, allocations: 1.797 MB / 0.8024 GB, free: 11.01 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03379/1.78, allocations: 20.28 MB / 0.8222 GB, free: 6.598 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008847/1.789, allocations: 0.4914 MB / 0.8227 GB, free: 6.105 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002344/1.792, allocations: 471.3 kB / 0.8231 GB, free: 5.648 MB / 0.5137 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.008329/1.8, allocations: 3.115 MB / 0.8262 GB, free: 2.52 MB / 0.5137 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001621/1.802, allocations: 0.5323 MB / 0.8267 GB, free: 1.98 MB / 0.5137 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.3702/2.172, allocations: 9.589 MB / 0.836 GB, free: 155.9 MB / 0.5137 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.149e-05/2.172, allocations: 33.81 kB / 0.8361 GB, free: 155.9 MB / 0.5137 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.6e-05/2.172, allocations: 17.69 kB / 0.8361 GB, free: 155.9 MB / 0.5137 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.493e-05/2.172, allocations: 16.64 kB / 0.8361 GB, free: 155.9 MB / 0.5137 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.04751/2.22, allocations: 19.79 MB / 0.8554 GB, free: 150.2 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.814e-05/2.22, allocations: 9.344 kB / 0.8554 GB, free: 150.2 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003358/2.223, allocations: 496.8 kB / 0.8559 GB, free: 150.1 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01601/2.239, allocations: 3.66 MB / 0.8595 GB, free: 149.5 MB / 0.5137 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.00126/2.241, allocations: 68.52 kB / 0.8596 GB, free: 149.4 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.03545/2.276, allocations: 17.84 MB / 0.877 GB, free: 141.8 MB / 0.5137 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.131e-05/2.276, allocations: 11.47 kB / 0.877 GB, free: 141.8 MB / 0.5137 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02733/2.304, allocations: 15.16 MB / 0.8918 GB, free: 134.3 MB / 0.5137 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004468/2.308, allocations: 1.157 MB / 0.8929 GB, free: 133.8 MB / 0.5137 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.00176/2.31, allocations: 61.34 kB / 0.893 GB, free: 133.8 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003623/2.314, allocations: 229.4 kB / 0.8932 GB, free: 133.6 MB / 0.5137 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000724/2.314, allocations: 227.7 kB / 0.8934 GB, free: 133.5 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002546/2.315, allocations: 89.97 kB / 0.8935 GB, free: 133.5 MB / 0.5137 GB
Notification: Performance of sorting global known variables: time 0.006713/2.321, allocations: 3.94 MB / 0.8973 GB, free: 130.6 MB / 0.5137 GB
Notification: Performance of sort global known variables: time 6.11e-07/2.321, allocations: 0 / 0.8973 GB, free: 130.6 MB / 0.5137 GB
Notification: Performance of remove unused functions: time 0.0113/2.333, allocations: 1.735 MB / 0.899 GB, free: 128.9 MB / 0.5137 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.02741/2.36, allocations: 17.17 MB / 0.9158 GB, free: 111.6 MB / 0.5137 GB
Notification: Performance of simCode: created initialization part: time 0.01569/2.376, allocations: 9.18 MB / 0.9248 GB, free: 102.5 MB / 0.5137 GB
Notification: Performance of simCode: created event and clocks part: time 1.896e-05/2.376, allocations: 4 kB / 0.9248 GB, free: 102.5 MB / 0.5137 GB
Notification: Performance of simCode: created simulation system equations: time 0.005363/2.381, allocations: 3.626 MB / 0.9283 GB, free: 98.82 MB / 0.5137 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005054/2.386, allocations: 0.6964 MB / 0.929 GB, free: 98.16 MB / 0.5137 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.3636/2.75, allocations: 137.3 MB / 1.063 GB, free: 105.5 MB / 0.5137 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.005647/2.756, allocations: 4.972 MB / 1.068 GB, free: 104.6 MB / 0.5137 GB
Notification: Performance of simCode: alias equations: time 0.006735/2.762, allocations: 2.603 MB / 1.07 GB, free: 104.5 MB / 0.5137 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.004423/2.767, allocations: 3.089 MB / 1.073 GB, free: 104.5 MB / 0.5137 GB
Notification: Performance of SimCode: time 8.12e-07/2.767, allocations: 0 / 1.073 GB, free: 104.5 MB / 0.5137 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.2016/2.969, allocations: 134.4 MB / 1.205 GB, free: 3.992 MB / 0.5137 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0001784/2.969, allocations: 196.2 kB / 1.205 GB, free: 3.758 MB / 0.5137 GB
Notification: Performance of buildModelFMU: Generate platform static: time 5.45/8.419, allocations: 0 / 1.205 GB, free: 3.758 MB / 0.5137 GB
(rm -f ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSP.pipe ; mkfifo ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSP.pipe ; head -c 1048576 < ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSP.pipe >> ../files/ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSP.sim & /home/hudson/saved_omc/OMSimulator/install/linux/bin/OMSimulator -r=ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSP_res.mat --tempDir=temp_ModelicaTest_3_2_3_ModelicaTest_MultiBody_FourbarVariants_JointUSP_fmu --startTime=0 --stopTime=5 --timeout=50 --tolerance=1e-06 ModelicaTest_3_2_3_ModelicaTest_MultiBody_FourbarVariants_JointUSP.fmu > ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSP.pipe 2>&1)
Environment - environmentSimulation:
TODO: How the !@#!# did the simulation report success but simulation result ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSP_res.mat does not exist to compare? outputFormat=mat