Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Arrow.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.001541/0.001541, allocations: 122.6 kB / 18.5 MB, free: 4.578 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.002507/0.002507, allocations: 206.6 kB / 21.45 MB, free: 1.629 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.529/1.529, allocations: 230.4 MB / 254.7 MB, free: 15.05 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.2446/0.2446, allocations: 46.93 MB / 357.7 MB, free: 10.23 MB / 286.1 MB
"
[Timeout remaining time 180]
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.Visualizers.Arrow,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Visualizers_Arrow",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.Visualizers.Arrow,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Visualizers_Arrow",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.00238/0.00238, allocations: 83.86 kB / 0.4917 GB, free: 7.758 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1285/0.1308, allocations: 66.04 MB / 0.5562 GB, free: 5.59 MB / 414.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Visualizers.Arrow): time 0.008369/0.1392, allocations: 6.275 MB / 0.5623 GB, free: 15.28 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.004547/0.1437, allocations: 1.982 MB / 0.5642 GB, free: 13.29 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001001/0.1447, allocations: 27.94 kB / 0.5643 GB, free: 13.26 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001702/0.1465, allocations: 0.6307 MB / 0.5649 GB, free: 12.62 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0007905/0.1472, allocations: 198.9 kB / 0.5651 GB, free: 12.43 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0003225/0.1476, allocations: 55.88 kB / 0.5651 GB, free: 12.38 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0007367/0.1483, allocations: 414.1 kB / 0.5655 GB, free: 11.97 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0007063/0.149, allocations: 438.8 kB / 0.5659 GB, free: 11.54 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0004589/0.1495, allocations: 231.1 kB / 0.5662 GB, free: 11.31 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.000502/0.15, allocations: 302.8 kB / 0.5664 GB, free: 11.01 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 8.641e-05/0.1501, allocations: 39.94 kB / 0.5665 GB, free: 10.97 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0002563/0.1503, allocations: 87.88 kB / 0.5666 GB, free: 10.89 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0003122/0.1506, allocations: 267.2 kB / 0.5668 GB, free: 10.62 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0003378/0.151, allocations: 314 kB / 0.5671 GB, free: 10.32 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.001194/0.1522, allocations: 0.8244 MB / 0.5679 GB, free: 9.488 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.713e-06/0.1522, allocations: 0 / 0.5679 GB, free: 9.488 MB / 430.1 MB
Notification: Performance of FrontEnd: time 2.074e-06/0.1522, allocations: 0 / 0.5679 GB, free: 9.488 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 2.158e-05/0.1522, allocations: 4 kB / 0.5679 GB, free: 9.484 MB / 430.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 180
 * Number of variables: 180
Notification: Performance of Generate backend data structure: time 0.00184/0.154, allocations: 0.9571 MB / 0.5689 GB, free: 8.477 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.553e-05/0.1541, allocations: 8.031 kB / 0.5689 GB, free: 8.469 MB / 430.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0001436/0.1542, allocations: 118.7 kB / 0.569 GB, free: 8.344 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0008601/0.1551, allocations: 168.2 kB / 0.5691 GB, free: 8.18 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0007894/0.1559, allocations: 445.9 kB / 0.5696 GB, free: 7.703 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.914e-05/0.1559, allocations: 37.36 kB / 0.5696 GB, free: 7.668 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 7.173e-05/0.156, allocations: 39.92 kB / 0.5696 GB, free: 7.629 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001555/0.1575, allocations: 1.082 MB / 0.5707 GB, free: 6.438 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.108e-05/0.1576, allocations: 7.938 kB / 0.5707 GB, free: 6.43 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 5.001e-05/0.1576, allocations: 19.98 kB / 0.5707 GB, free: 6.41 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003718/0.158, allocations: 196.3 kB / 0.5709 GB, free: 6.219 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001278/0.1593, allocations: 0.7122 MB / 0.5716 GB, free: 5.504 MB / 430.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.005013/0.1643, allocations: 4.651 MB / 0.5762 GB, free: 0.6719 MB / 430.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0001899/0.1645, allocations: 66.47 kB / 0.5762 GB, free: 0.5977 MB / 430.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0001201/0.1646, allocations: 44.89 kB / 0.5763 GB, free: 0.5508 MB / 430.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 1.936e-05/0.1646, allocations: 0 / 0.5763 GB, free: 0.5508 MB / 430.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.45e-05/0.1646, allocations: 34.56 kB / 0.5763 GB, free: 0.5078 MB / 430.1 MB
Notification: Performance of pre-optimization done (n=3): time 1.522e-06/0.1646, allocations: 0 / 0.5763 GB, free: 0.5078 MB / 430.1 MB
Notification: Performance of matching and sorting (n=3): time 0.000593/0.1652, allocations: 221.1 kB / 0.5765 GB, free: 292 kB / 430.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.279e-05/0.1653, allocations: 55.34 kB / 0.5766 GB, free: 224 kB / 430.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001112/0.1664, allocations: 0.8127 MB / 0.5773 GB, free: 15.39 MB / 446.1 MB
Notification: Performance of collectPreVariables (initialization): time 2.396e-05/0.1664, allocations: 25.7 kB / 0.5774 GB, free: 15.36 MB / 446.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004108/0.1668, allocations: 0.5218 MB / 0.5779 GB, free: 14.84 MB / 446.1 MB
Notification: Performance of collectInitialBindings (initialization): time 3.712e-05/0.1669, allocations: 35.81 kB / 0.5779 GB, free: 14.8 MB / 446.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00022/0.1671, allocations: 170.5 kB / 0.5781 GB, free: 14.63 MB / 446.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001927/0.1673, allocations: 370.8 kB / 0.5784 GB, free: 14.26 MB / 446.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0003365/0.1676, allocations: 185.9 kB / 0.5786 GB, free: 14.07 MB / 446.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007791/0.1684, allocations: 0.7312 MB / 0.5793 GB, free: 13.06 MB / 446.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.00124/0.1696, allocations: 0.982 MB / 0.5803 GB, free: 11.8 MB / 446.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.305e-05/0.1697, allocations: 4 kB / 0.5803 GB, free: 11.79 MB / 446.1 MB
Notification: Performance of matching and sorting (n=92) (initialization): time 0.001605/0.1713, allocations: 0.8685 MB / 0.5811 GB, free: 10.92 MB / 446.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.857e-05/0.1713, allocations: 21.5 kB / 0.5812 GB, free: 10.9 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.722e-05/0.1713, allocations: 23.94 kB / 0.5812 GB, free: 10.88 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 3.831e-05/0.1714, allocations: 31.94 kB / 0.5812 GB, free: 10.84 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005186/0.1719, allocations: 175.7 kB / 0.5814 GB, free: 10.67 MB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.698e-05/0.1719, allocations: 36.42 kB / 0.5814 GB, free: 10.64 MB / 446.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0006473/0.1726, allocations: 123.2 kB / 0.5815 GB, free: 10.52 MB / 446.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.483e-05/0.1726, allocations: 48 kB / 0.5816 GB, free: 10.47 MB / 446.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 92
 * Number of states: 0 ()
 * Number of discrete variables: 25 (fixedArrow.arrowLine.twoHeadedArrow,fixedArrow.headAtOrigin,arrow.arrow.twoHeadedArrow,arrow.headAtOrigin,world.groundColor[3],world.groundColor[2],world.groundColor[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1],world.axisColor_x[3],world.axisColor_x[2],world.axisColor_x[1],fixedArrow.color[3],fixedArrow.color[2],fixedArrow.color[1],arrow.color[3],arrow.color[2],arrow.color[1],fixed3.color[3],fixed3.color[2],fixed3.color[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (92):
 * Single equations (assignments): 92
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0005276/0.1731, allocations: 367.6 kB / 0.5819 GB, free: 10.1 MB / 446.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000118/0.1733, allocations: 63.58 kB / 0.582 GB, free: 10.04 MB / 446.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0001212/0.1734, allocations: 65.22 kB / 0.582 GB, free: 9.961 MB / 446.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.128e-06/0.1734, allocations: 0 / 0.582 GB, free: 9.961 MB / 446.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.603e-06/0.1734, allocations: 0 / 0.582 GB, free: 9.961 MB / 446.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.827e-06/0.1734, allocations: 8 kB / 0.5821 GB, free: 9.953 MB / 446.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0007259/0.1741, allocations: 0.4974 MB / 0.5825 GB, free: 9.406 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.053e-06/0.1741, allocations: 0 / 0.5825 GB, free: 9.406 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0001013/0.1742, allocations: 11.98 kB / 0.5826 GB, free: 9.395 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 3.146e-06/0.1742, allocations: 0 / 0.5826 GB, free: 9.395 MB / 446.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 1.325e-05/0.1742, allocations: 7.938 kB / 0.5826 GB, free: 9.387 MB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 3.006e-06/0.1742, allocations: 0 / 0.5826 GB, free: 9.387 MB / 446.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.122e-06/0.1742, allocations: 0 / 0.5826 GB, free: 9.387 MB / 446.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0004142/0.1747, allocations: 275 kB / 0.5828 GB, free: 9.102 MB / 446.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0001575/0.1748, allocations: 136.7 kB / 0.583 GB, free: 8.949 MB / 446.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 5.963e-05/0.1749, allocations: 3.938 kB / 0.583 GB, free: 8.945 MB / 446.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0002002/0.1751, allocations: 4 kB / 0.583 GB, free: 8.941 MB / 446.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 5.836e-05/0.1751, allocations: 15.97 kB / 0.583 GB, free: 8.926 MB / 446.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.011e-06/0.1751, allocations: 0 / 0.583 GB, free: 8.926 MB / 446.1 MB
Notification: Performance of sorting global known variables: time 0.0007163/0.1759, allocations: 0.5302 MB / 0.5835 GB, free: 8.398 MB / 446.1 MB
Notification: Performance of sort global known variables: time 2e-07/0.1759, allocations: 4 kB / 0.5835 GB, free: 8.395 MB / 446.1 MB
Notification: Performance of remove unused functions: time 0.000748/0.1766, allocations: 139.7 kB / 0.5836 GB, free: 8.258 MB / 446.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 0 ()
 * 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 (3):
 * Single equations (assignments): 3
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.000792/0.1774, allocations: 0.798 MB / 0.5844 GB, free: 7.434 MB / 446.1 MB
Notification: Performance of simCode: created initialization part: time 0.0008164/0.1782, allocations: 412.5 kB / 0.5848 GB, free: 7.035 MB / 446.1 MB
Notification: Performance of simCode: created event and clocks part: time 2.695e-06/0.1782, allocations: 0 / 0.5848 GB, free: 7.035 MB / 446.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.0001232/0.1783, allocations: 52 kB / 0.5849 GB, free: 6.984 MB / 446.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0005822/0.1789, allocations: 92.36 kB / 0.5849 GB, free: 6.898 MB / 446.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.008733/0.1877, allocations: 6.108 MB / 0.5909 GB, free: 144 kB / 446.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.2454/0.433, allocations: 0.9827 MB / 0.5919 GB, free: 35.33 MB / 446.1 MB
Notification: Performance of simCode: alias equations: time 0.0005295/0.4336, allocations: 208.5 kB / 0.5921 GB, free: 35.32 MB / 446.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002643/0.4338, allocations: 233.4 kB / 0.5923 GB, free: 35.31 MB / 446.1 MB
Notification: Performance of SimCode: time 1.022e-06/0.4338, allocations: 0 / 0.5923 GB, free: 35.31 MB / 446.1 MB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.04027/0.4741, allocations: 11.07 MB / 0.6031 GB, free: 33.63 MB / 446.1 MB
Notification: Performance of buildModelFMU: Generate platform static: time 9.771/10.25, allocations: 10.75 kB / 0.6031 GB, free: 33.62 MB / 446.1 MB
"
[Timeout remaining time 650]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Arrow.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Arrow.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Arrow.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Arrow.sim & python3 -m fmpy simulate  --output-file ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Arrow_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_Visualizers_Arrow.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Arrow.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 13.395308800972998]
Failed to read output from testmodel.py, exit status != 0:
['time']
10.318531337194145 10.331656887 10.176892887
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fb6a00717e0>