Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenHPL_OpenHPL.Examples.VolumeFlowSource.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001125/0.001125, allocations: 80.44 kB / 19.17 MB, free: 0.7422 MB / 13.93 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.008/1.008, allocations: 177.1 MB / 199.4 MB, free: 5.941 MB / 186.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001108/0.001108, allocations: 152.6 kB / 256 MB, free: 13.34 MB / 250.7 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/package.mo): time 0.2723/0.2723, allocations: 80.25 MB / 392.6 MB, free: 2.449 MB / 314.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenHPL 4.0.0/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenHPL 4.0.0/package.mo): time 0.02043/0.02043, allocations: 4.807 MB / 480.6 MB, free: 4.449 MB / 394.7 MB " [Timeout remaining time 180] Using package OpenHPL with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenHPL 4.0.0/package.mo) Using package OpenIPSL with version 3.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/package.mo) Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo) Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo) Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo) Running command: "" <> buildModelFMU(OpenHPL.Examples.VolumeFlowSource,fileNamePrefix="OpenHPL_OpenHPL_Examples_VolumeFlowSource",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(OpenHPL.Examples.VolumeFlowSource,fileNamePrefix="OpenHPL_OpenHPL_Examples_VolumeFlowSource",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.833e-06/1.833e-06, allocations: 0 / 0.6452 GB, free: 107.7 MB / 0.5105 GB Notification: Performance of FrontEnd - Absyn->SCode: time 1.977e-05/2.16e-05, allocations: 7.078 kB / 0.6452 GB, free: 107.7 MB / 0.5105 GB Notification: Performance of NFInst.instantiate(OpenHPL.Examples.VolumeFlowSource): time 0.005175/0.005197, allocations: 3.904 MB / 0.649 GB, free: 103.8 MB / 0.5105 GB Notification: Performance of NFInst.instExpressions: time 0.004797/0.009994, allocations: 2.642 MB / 0.6516 GB, free: 101.2 MB / 0.5105 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.0006343/0.01063, allocations: 15.88 kB / 0.6516 GB, free: 101.2 MB / 0.5105 GB Notification: Performance of NFTyping.typeComponents: time 0.002289/0.01292, allocations: 1.855 MB / 0.6534 GB, free: 99.33 MB / 0.5105 GB Notification: Performance of NFTyping.typeBindings: time 0.001441/0.01436, allocations: 0.9219 MB / 0.6543 GB, free: 98.4 MB / 0.5105 GB Notification: Performance of NFTyping.typeClassSections: time 0.001015/0.01537, allocations: 0.4945 MB / 0.6548 GB, free: 97.91 MB / 0.5105 GB Notification: Performance of NFFlatten.flatten: time 0.0008046/0.01618, allocations: 1.272 MB / 0.656 GB, free: 96.63 MB / 0.5105 GB Notification: Performance of NFFlatten.resolveConnections: time 0.0004455/0.01662, allocations: 0.5067 MB / 0.6565 GB, free: 96.11 MB / 0.5105 GB Notification: Performance of NFEvalConstants.evaluate: time 0.0004278/0.01705, allocations: 0.7287 MB / 0.6572 GB, free: 95.38 MB / 0.5105 GB Notification: Performance of NFSimplifyModel.simplify: time 0.0005022/0.01755, allocations: 0.8986 MB / 0.6581 GB, free: 94.48 MB / 0.5105 GB Notification: Performance of NFPackage.collectConstants: time 8.089e-05/0.01763, allocations: 72 kB / 0.6582 GB, free: 94.41 MB / 0.5105 GB Notification: Performance of NFFlatten.collectFunctions: time 0.0003596/0.01799, allocations: 359.4 kB / 0.6585 GB, free: 94.05 MB / 0.5105 GB Notification: Performance of NFScalarize.scalarize: time 0.0001575/0.01815, allocations: 332.7 kB / 0.6588 GB, free: 93.73 MB / 0.5105 GB Notification: Performance of NFVerifyModel.verify: time 0.0001805/0.01833, allocations: 322.7 kB / 0.6592 GB, free: 93.41 MB / 0.5105 GB Notification: Performance of NFConvertDAE.convert: time 0.000803/0.01913, allocations: 1.355 MB / 0.6605 GB, free: 92.05 MB / 0.5105 GB Notification: Performance of FrontEnd - DAE generated: time 2.865e-06/0.01914, allocations: 4 kB / 0.6605 GB, free: 92.05 MB / 0.5105 GB Notification: Performance of FrontEnd: time 1.644e-06/0.01914, allocations: 0 / 0.6605 GB, free: 92.05 MB / 0.5105 GB Notification: Performance of Transformations before backend: time 1.048e-05/0.01915, allocations: 0 / 0.6605 GB, free: 92.05 MB / 0.5105 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 114 * Number of variables: 114 Notification: Performance of Generate backend data structure: time 0.001449/0.0206, allocations: 1.763 MB / 0.6622 GB, free: 90.23 MB / 0.5105 GB Notification: Performance of prepare preOptimizeDAE: time 3.755e-05/0.02064, allocations: 8.031 kB / 0.6622 GB, free: 90.22 MB / 0.5105 GB Notification: Performance of preOpt introduceOutputAliases (simulation): time 6.28e-05/0.0207, allocations: 86.7 kB / 0.6623 GB, free: 90.12 MB / 0.5105 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0004193/0.02112, allocations: 211.8 kB / 0.6625 GB, free: 89.92 MB / 0.5105 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.0005884/0.02171, allocations: 0.6832 MB / 0.6632 GB, free: 89.2 MB / 0.5105 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 2.687e-05/0.02173, allocations: 41.31 kB / 0.6632 GB, free: 89.16 MB / 0.5105 GB Notification: Performance of preOpt expandDerOperator (simulation): time 4.813e-05/0.02178, allocations: 52.36 kB / 0.6632 GB, free: 89.11 MB / 0.5105 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.0005173/0.0223, allocations: 0.7689 MB / 0.664 GB, free: 88.23 MB / 0.5105 GB Notification: Performance of preOpt findStateOrder (simulation): time 1.664e-05/0.02232, allocations: 4 kB / 0.664 GB, free: 88.23 MB / 0.5105 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 3.218e-05/0.02235, allocations: 20 kB / 0.664 GB, free: 88.21 MB / 0.5105 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.936e-06/0.02236, allocations: 12 kB / 0.664 GB, free: 88.2 MB / 0.5105 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0004122/0.02277, allocations: 372.5 kB / 0.6644 GB, free: 87.83 MB / 0.5105 GB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.001525/0.02429, allocations: 1.863 MB / 0.6662 GB, free: 85.9 MB / 0.5105 GB Notification: Performance of preOpt comSubExp (simulation): time 0.000491/0.02479, allocations: 446.5 kB / 0.6666 GB, free: 85.44 MB / 0.5105 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0001851/0.02497, allocations: 186.5 kB / 0.6668 GB, free: 85.25 MB / 0.5105 GB Notification: Performance of preOpt evalFunc (simulation): time 2.473e-05/0.025, allocations: 4 kB / 0.6668 GB, free: 85.24 MB / 0.5105 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0002962/0.02529, allocations: 288.6 kB / 0.6671 GB, free: 84.94 MB / 0.5105 GB Notification: Performance of pre-optimization done (n=52): time 1.733e-06/0.02529, allocations: 0 / 0.6671 GB, free: 84.94 MB / 0.5105 GB Notification: Performance of matching and sorting (n=55): time 0.00173/0.02702, allocations: 1.901 MB / 0.6689 GB, free: 82.98 MB / 0.5105 GB Notification: Performance of inlineWhenForInitialization (initialization): time 5.58e-05/0.02708, allocations: 350.1 kB / 0.6693 GB, free: 82.55 MB / 0.5105 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001129/0.02821, allocations: 2.119 MB / 0.6714 GB, free: 80.42 MB / 0.5105 GB Notification: Performance of collectPreVariables (initialization): time 2.503e-05/0.02823, allocations: 37.7 kB / 0.6714 GB, free: 80.38 MB / 0.5105 GB Notification: Performance of collectInitialEqns (initialization): time 0.0002377/0.02847, allocations: 0.6011 MB / 0.672 GB, free: 79.77 MB / 0.5105 GB Notification: Performance of collectInitialBindings (initialization): time 6.71e-05/0.02854, allocations: 194.4 kB / 0.6722 GB, free: 79.58 MB / 0.5105 GB Notification: Performance of simplifyInitialFunctions (initialization): time 8.76e-05/0.02863, allocations: 114.2 kB / 0.6723 GB, free: 79.46 MB / 0.5105 GB Notification: Performance of setup shared object (initialization): time 3.562e-05/0.02866, allocations: 326 kB / 0.6726 GB, free: 79.14 MB / 0.5105 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001969/0.02886, allocations: 165.2 kB / 0.6727 GB, free: 78.98 MB / 0.5105 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.000258/0.02912, allocations: 0.523 MB / 0.6732 GB, free: 78.35 MB / 0.5105 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.0004067/0.02952, allocations: 420 kB / 0.6736 GB, free: 77.93 MB / 0.5105 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.532e-06/0.02953, allocations: 0 / 0.6736 GB, free: 77.93 MB / 0.5105 GB Notification: Performance of matching and sorting (n=70) (initialization): time 0.0005774/0.0301, allocations: 0.6225 MB / 0.6743 GB, free: 77.3 MB / 0.5105 GB Notification: Performance of prepare postOptimizeDAE: time 1.645e-05/0.03012, allocations: 8 kB / 0.6743 GB, free: 77.29 MB / 0.5105 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.072e-06/0.03012, allocations: 96 / 0.6743 GB, free: 77.29 MB / 0.5105 GB Notification: Performance of postOpt tearingSystem (initialization): time 1.392e-05/0.03014, allocations: 7.938 kB / 0.6743 GB, free: 77.29 MB / 0.5105 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0003634/0.0305, allocations: 163.8 kB / 0.6744 GB, free: 77.12 MB / 0.5105 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 8.176e-06/0.03051, allocations: 8.109 kB / 0.6744 GB, free: 77.12 MB / 0.5105 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.000227/0.03074, allocations: 39.88 kB / 0.6745 GB, free: 77.08 MB / 0.5105 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 2.611e-05/0.03076, allocations: 23.98 kB / 0.6745 GB, free: 77.05 MB / 0.5105 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 7 * Number of states: 0 () * Number of discrete variables: 5 ($PRE.logdata.nextTimeEvent,$whenCondition1,logdata.nextTimeEventScaled,$PRE.logdata.nextTimeEventScaled,logdata.nextTimeEvent) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (70): * Single equations (assignments): 70 * 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.0002524/0.03102, allocations: 375.1 kB / 0.6749 GB, free: 76.68 MB / 0.5105 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 8.318e-05/0.0311, allocations: 151 kB / 0.675 GB, free: 76.54 MB / 0.5105 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0006257/0.03172, allocations: 0.6403 MB / 0.6756 GB, free: 75.89 MB / 0.5105 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.248e-06/0.03173, allocations: 8 kB / 0.6756 GB, free: 75.88 MB / 0.5105 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 4.348e-06/0.03173, allocations: 4 kB / 0.6756 GB, free: 75.88 MB / 0.5105 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.893e-06/0.03174, allocations: 7.969 kB / 0.6756 GB, free: 75.87 MB / 0.5105 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001028/0.03277, allocations: 1.108 MB / 0.6767 GB, free: 74.7 MB / 0.5105 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.497e-06/0.03277, allocations: 3.984 kB / 0.6767 GB, free: 74.69 MB / 0.5105 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004868/0.03326, allocations: 199.9 kB / 0.6769 GB, free: 74.5 MB / 0.5105 GB Notification: Performance of postOpt tearingSystem (simulation): time 3.888e-06/0.03326, allocations: 4 kB / 0.6769 GB, free: 74.49 MB / 0.5105 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.165e-05/0.03328, allocations: 23.92 kB / 0.6769 GB, free: 74.47 MB / 0.5105 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0009305/0.03421, allocations: 3.837 MB / 0.6807 GB, free: 70.29 MB / 0.5105 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.884e-06/0.03422, allocations: 3.984 kB / 0.6807 GB, free: 70.29 MB / 0.5105 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0007852/0.035, allocations: 0.9469 MB / 0.6816 GB, free: 69.3 MB / 0.5105 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0001007/0.0351, allocations: 147 kB / 0.6818 GB, free: 69.16 MB / 0.5105 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 6.983e-05/0.03517, allocations: 19.95 kB / 0.6818 GB, free: 69.14 MB / 0.5105 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0002323/0.0354, allocations: 23.98 kB / 0.6818 GB, free: 69.12 MB / 0.5105 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 6.956e-05/0.03547, allocations: 51.8 kB / 0.6819 GB, free: 69.07 MB / 0.5105 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 2.573e-05/0.0355, allocations: 19.98 kB / 0.6819 GB, free: 69.05 MB / 0.5105 GB Notification: Performance of sorting global known variables: time 0.000889/0.03639, allocations: 1.562 MB / 0.6834 GB, free: 67.49 MB / 0.5105 GB Notification: Performance of sort global known variables: time 8e-08/0.03639, allocations: 0 / 0.6834 GB, free: 67.49 MB / 0.5105 GB Notification: Performance of remove unused functions: time 0.000433/0.03682, allocations: 223.8 kB / 0.6836 GB, free: 67.27 MB / 0.5105 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 7 * Number of states: 4 (tail1.m,tail2.m,tail3.m,volumeFlowFiltered.firstOrder.y) * Number of discrete variables: 3 ($whenCondition1,logdata.nextTimeEvent,logdata.nextTimeEventScaled) * Number of discrete states: 2 (logdata.nextTimeEvent,logdata.nextTimeEventScaled) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (59): * Single equations (assignments): 54 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 2 * If-equations: 0 * Equation systems (not torn): 3 * Torn equation systems: 0 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details (not torn): * Constant Jacobian (size): 0 systems * Linear Jacobian (size,density): 0 systems * Non-linear Jacobian (size): 3 systems {1, 1, 1} * Without analytic Jacobian (size): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.001745/0.03857, allocations: 1.901 MB / 0.6855 GB, free: 65.54 MB / 0.5105 GB Notification: Performance of simCode: created initialization part: time 0.0004963/0.03906, allocations: 419.1 kB / 0.6859 GB, free: 65.14 MB / 0.5105 GB Notification: Performance of simCode: created event and clocks part: time 1.633e-06/0.03906, allocations: 4.062 kB / 0.6859 GB, free: 65.13 MB / 0.5105 GB Notification: Performance of simCode: created simulation system equations: time 0.0003952/0.03946, allocations: 283.8 kB / 0.6861 GB, free: 64.86 MB / 0.5105 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.000597/0.04006, allocations: 192.4 kB / 0.6863 GB, free: 64.68 MB / 0.5105 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.007148/0.0472, allocations: 9.201 MB / 0.6953 GB, free: 55.28 MB / 0.5105 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0004925/0.0477, allocations: 0.6012 MB / 0.6959 GB, free: 54.65 MB / 0.5105 GB Notification: Performance of simCode: alias equations: time 0.0003859/0.04808, allocations: 121.5 kB / 0.696 GB, free: 54.53 MB / 0.5105 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000554/0.04864, allocations: 0.5433 MB / 0.6965 GB, free: 53.98 MB / 0.5105 GB Notification: Performance of SimCode: time 6.51e-07/0.04864, allocations: 0 / 0.6965 GB, free: 53.98 MB / 0.5105 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.09504/0.1437, allocations: 27.65 MB / 0.7235 GB, free: 26.63 MB / 0.5105 GB Notification: Performance of buildModelFMU: Generate platform static: time 7.885/8.029, allocations: 21.22 kB / 0.7236 GB, free: 26.62 MB / 0.5105 GB " [Timeout remaining time 652] (rm -f OpenHPL_OpenHPL.Examples.VolumeFlowSource.pipe ; mkfifo OpenHPL_OpenHPL.Examples.VolumeFlowSource.pipe ; head -c 1048576 < OpenHPL_OpenHPL.Examples.VolumeFlowSource.pipe >> ../files/OpenHPL_OpenHPL.Examples.VolumeFlowSource.sim & (cd run_OMSimulator && /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator --stripRoot --tempDir=temp_OpenHPL_OpenHPL_Examples_VolumeFlowSource_fmu --startTime=0 --stopTime=120 --stepSize=0.048 --timeout=50 --tolerance=1e-06 ../OpenHPL_OpenHPL_Examples_VolumeFlowSource.fmu) > OpenHPL_OpenHPL.Examples.VolumeFlowSource.pipe 2>&1) [Timeout 52.5] (rm -f OpenHPL_OpenHPL.Examples.VolumeFlowSource_fmpy.pipe ; mkfifo OpenHPL_OpenHPL.Examples.VolumeFlowSource_fmpy.pipe ; head -c 1048576 < OpenHPL_OpenHPL.Examples.VolumeFlowSource_fmpy.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/OpenHPL_OpenHPL.Examples.VolumeFlowSource_fmpy.sim & (cd run_fmpy && python3 -m fmpy simulate --output-file ../OpenHPL_OpenHPL.Examples.VolumeFlowSource_fmpy_res.csv --start-time 0 --stop-time 120 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.048 ../OpenHPL_OpenHPL_Examples_VolumeFlowSource.fmu) > OpenHPL_OpenHPL.Examples.VolumeFlowSource_fmpy.pipe 2>&1) [Timeout 52.5] [Calling sys.exit(0), Time elapsed: 11.446505875326693]