Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt 0.5.1/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt 0.5.1/package.mo): time 0.009056/0.009056, allocations: 1.342 MB / 11.12 MB, free: 3.051 MB / 13.93 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.00207/0.01113, allocations: 0.8268 MB / 11.95 MB, free: 2.219 MB / 13.93 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia 1.9.0-ClaRa/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia 1.9.0-ClaRa/package.mo): time 0.08486/0.08486, allocations: 18.24 MB / 30.93 MB, free: 4.938 MB / 33.03 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.01472/0.09958, allocations: 7.687 MB / 38.61 MB, free: 8.23 MB / 44.04 MB
"
[Timeout remaining time 180]
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.0006173/0.0006173, allocations: 82.98 kB / 39.43 MB, free: 7.426 MB / 44.04 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.001063/0.00168, allocations: 451.4 kB / 39.87 MB, free: 6.992 MB / 44.04 MB
"
[Timeout remaining time 180]
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.0007332/0.0007332, allocations: 154.6 kB / 40.75 MB, free: 6.109 MB / 44.04 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.001004/0.001738, allocations: 465.8 kB / 41.2 MB, free: 5.652 MB / 44.04 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 0.9761/0.9761, allocations: 177.2 MB / 219.1 MB, free: 11.4 MB / 218.7 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.1331/1.109, allocations: 53.67 MB / 272.8 MB, free: 5.734 MB / 266.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/package.mo): time 0.9037/0.9037, allocations: 102.4 MB / 375.9 MB, free: 8.891 MB / 362.8 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.06725/0.9709, allocations: 28.57 MB / 404.5 MB, free: 12.43 MB / 394.8 MB
"
[Timeout remaining time 179]
Using package ClaRa with version 1.9.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/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 Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 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)
Using package TILMedia with version 1.9.0 ClaRa (/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia 1.9.0-ClaRa/package.mo)
Using package SMArtInt with version 0.5.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt 0.5.1/package.mo)
Running command: setCommandLineOptions("--fmiFlags=s:cvode")
Running command: "" <> buildModelFMU(ClaRa.Components.MechanicalSeparation.Check.TestDrum,fileNamePrefix="ClaRa_ClaRa_Components_MechanicalSeparation_Check_TestDrum",fmuType="me_cs",version="2.0+3.0",platforms={"static"})
"" <> buildModelFMU(ClaRa.Components.MechanicalSeparation.Check.TestDrum,fileNamePrefix="ClaRa_ClaRa_Components_MechanicalSeparation_Check_TestDrum",fmuType="me_cs",version="2.0+3.0",platforms={"static"}) [Timeout 800]
"Notification: Performance of FrontEnd - loaded program: time 2.344e-06/2.344e-06, allocations: 0 / 406.3 MB, free: 10.65 MB / 394.8 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.501e-05/2.735e-05, allocations: 5.547 kB / 406.3 MB, free: 10.65 MB / 394.8 MB
Notification: Performance of NFInst.instantiate(ClaRa.Components.MechanicalSeparation.Check.TestDrum): time 0.03558/0.03561, allocations: 23.29 MB / 429.6 MB, free: 3.27 MB / 410.8 MB
Notification: Performance of NFInst.instExpressions: time 0.05591/0.09152, allocations: 23.73 MB / 453.3 MB, free: 11.46 MB / 442.8 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.008796/0.1003, allocations: 127.1 kB / 453.4 MB, free: 11.34 MB / 442.8 MB
Notification: Performance of NFTyping.typeComponents: time 0.009041/0.1094, allocations: 2.76 MB / 456.2 MB, free: 8.57 MB / 442.8 MB
Notification: Performance of NFTyping.typeBindings: time 0.01581/0.1252, allocations: 4.016 MB / 460.2 MB, free: 4.531 MB / 442.8 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01133/0.1365, allocations: 3.73 MB / 464 MB, free: 0.793 MB / 442.8 MB
Notification: Performance of NFFlatten.flatten: time 0.02198/0.1585, allocations: 12.85 MB / 476.8 MB, free: 3.902 MB / 458.8 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01123/0.1697, allocations: 3.968 MB / 480.8 MB, free: 15.84 MB / 474.8 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.1147/0.2844, allocations: 44.25 MB / 0.5127 GB, free: 4.777 MB / 0.4949 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01064/0.295, allocations: 4.211 MB / 0.5168 GB, free: 0.5508 MB / 0.4949 GB
Notification: Performance of NFPackage.collectConstants: time 0.004008/0.2991, allocations: 0.7657 MB / 0.5176 GB, free: 15.78 MB / 0.5105 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01115/0.3102, allocations: 2.429 MB / 0.5199 GB, free: 13.35 MB / 0.5105 GB
Notification: Performance of NFScalarize.scalarize: time 0.006712/0.3169, allocations: 2.185 MB / 0.5221 GB, free: 11.16 MB / 0.5105 GB
Notification: Performance of NFVerifyModel.verify: time 0.01278/0.3297, allocations: 2.198 MB / 0.5242 GB, free: 8.957 MB / 0.5105 GB
Notification: Performance of NFConvertDAE.convert: time 0.0268/0.3565, allocations: 7.887 MB / 0.5319 GB, free: 1.043 MB / 0.5105 GB
Notification: Performance of FrontEnd - DAE generated: time 3.209e-05/0.3565, allocations: 4 kB / 0.5319 GB, free: 1.039 MB / 0.5105 GB
Notification: Performance of FrontEnd: time 2.134e-06/0.3565, allocations: 3.938 kB / 0.5319 GB, free: 1.035 MB / 0.5105 GB
Notification: Performance of Transformations before backend: time 0.0007249/0.3572, allocations: 0 / 0.5319 GB, free: 1.035 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: 1607
 * Number of variables: 1607
Notification: Performance of Generate backend data structure: time 0.4038/0.761, allocations: 8.008 MB / 0.5398 GB, free: 77.37 MB / 0.5105 GB
Notification: Performance of prepare preOptimizeDAE: time 6.53e-05/0.7611, allocations: 10.62 kB / 0.5398 GB, free: 77.37 MB / 0.5105 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.002399/0.7635, allocations: 0.7797 MB / 0.5405 GB, free: 77.29 MB / 0.5105 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003129/0.7666, allocations: 0.9863 MB / 0.5415 GB, free: 77.19 MB / 0.5105 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01125/0.7779, allocations: 3.79 MB / 0.5452 GB, free: 76.01 MB / 0.5105 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000333/0.7782, allocations: 307 kB / 0.5455 GB, free: 76 MB / 0.5105 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001615/0.7798, allocations: 437.8 kB / 0.5459 GB, free: 76 MB / 0.5105 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0177/0.7975, allocations: 9.295 MB / 0.555 GB, free: 70.86 MB / 0.5105 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.000146/0.7977, allocations: 46.56 kB / 0.555 GB, free: 70.86 MB / 0.5105 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0006609/0.7983, allocations: 201.5 kB / 0.5552 GB, free: 70.86 MB / 0.5105 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.000369/0.7987, allocations: 399.8 kB / 0.5556 GB, free: 70.86 MB / 0.5105 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01082/0.8095, allocations: 4.346 MB / 0.5598 GB, free: 70.65 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 preOpt removeSimpleEquations (simulation): time 0.03465/0.8442, allocations: 37.15 MB / 0.5961 GB, free: 40.95 MB / 0.5105 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.008871/0.853, allocations: 4.181 MB / 0.6002 GB, free: 37.17 MB / 0.5105 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002259/0.8553, allocations: 1.012 MB / 0.6012 GB, free: 36.18 MB / 0.5105 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0006342/0.8559, allocations: 291.3 kB / 0.6015 GB, free: 35.93 MB / 0.5105 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0001123/0.8561, allocations: 108.6 kB / 0.6016 GB, free: 35.86 MB / 0.5105 GB
Notification: Performance of pre-optimization done (n=816): time 9.027e-06/0.8561, allocations: 2.078 kB / 0.6016 GB, free: 35.86 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=817): time 0.0258/0.8819, allocations: 10.99 MB / 0.6123 GB, free: 25.32 MB / 0.5105 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002713/0.8821, allocations: 1.503 MB / 0.6138 GB, free: 23.48 MB / 0.5105 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.007181/0.8893, allocations: 4.596 MB / 0.6183 GB, free: 20.02 MB / 0.5105 GB
Notification: Performance of collectPreVariables (initialization): time 0.00127/0.8906, allocations: 93.89 kB / 0.6184 GB, free: 19.97 MB / 0.5105 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001172/0.8918, allocations: 2.07 MB / 0.6204 GB, free: 18.22 MB / 0.5105 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002663/0.8944, allocations: 2.775 MB / 0.6231 GB, free: 15.82 MB / 0.5105 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002784/0.8972, allocations: 1.383 MB / 0.6244 GB, free: 14.95 MB / 0.5105 GB
Notification: Performance of setup shared object (initialization): time 4.555e-05/0.8972, allocations: 301.1 kB / 0.6247 GB, free: 14.66 MB / 0.5105 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002/0.8992, allocations: 0.9566 MB / 0.6257 GB, free: 13.77 MB / 0.5105 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002769/0.902, allocations: 4.178 MB / 0.6297 GB, free: 8.547 MB / 0.5105 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.006665/0.9087, allocations: 5.704 MB / 0.6353 GB, free: 2.27 MB / 0.5105 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.261e-05/0.9088, allocations: 20 kB / 0.6353 GB, free: 2.25 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=1148) (initialization): time 0.008707/0.9175, allocations: 4.331 MB / 0.6396 GB, free: 14.34 MB / 0.5262 GB
Notification: Performance of prepare postOptimizeDAE: time 5.574e-05/0.9175, allocations: 32 kB / 0.6396 GB, free: 14.31 MB / 0.5262 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003751/0.9179, allocations: 263.8 kB / 0.6398 GB, free: 14.05 MB / 0.5262 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02205/0.9399, allocations: 4.214 MB / 0.644 GB, free: 9.871 MB / 0.5262 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004932/0.9449, allocations: 1.434 MB / 0.6454 GB, free: 8.523 MB / 0.5262 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007798/0.9527, allocations: 9.246 MB / 0.6544 GB, free: 14.78 MB / 0.5418 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006218/0.9589, allocations: 251.3 kB / 0.6546 GB, free: 14.53 MB / 0.5418 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001791/0.9607, allocations: 275.9 kB / 0.6549 GB, free: 14.26 MB / 0.5418 GB
Warning: Assuming fixed start value for the following 3 variables:
         drum.volume.h_liq:VARIABLE(start = drum.volume.h_liq_start unit = \"J/kg\" fixed = true nominal = 1000.0 )  \"Specific enthalpy of liquid phase\" type: Real
         drum.volume.h_vap:VARIABLE(start = drum.volume.h_vap_start unit = \"J/kg\" fixed = true nominal = 1000.0 )  \"Specific enthalpy of vapour phase\" type: Real
         drum.volume.p_vap:VARIABLE(min = 0.0 start = drum.volume.p_start unit = \"Pa\" fixed = true nominal = 1e5 stateSelect=StateSelect.prefer )  \"Vapour pressure\" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.005654/0.9663, allocations: 1.126 MB / 0.656 GB, free: 13.13 MB / 0.5418 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.00509/0.9714, allocations: 4.168 MB / 0.6601 GB, free: 7.867 MB / 0.5418 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01044/0.9819, allocations: 5.656 MB / 0.6656 GB, free: 1.625 MB / 0.5418 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 9.197e-05/0.982, allocations: 20 kB / 0.6656 GB, free: 1.605 MB / 0.5418 GB
Notification: Performance of matching and sorting (n=1148) (initialization_lambda0): time 0.01231/0.9943, allocations: 4.309 MB / 0.6698 GB, free: 13.71 MB / 0.5574 GB
Notification: Performance of prepare postOptimizeDAE: time 5.688e-05/0.9943, allocations: 36 kB / 0.6698 GB, free: 13.68 MB / 0.5574 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0005219/0.9948, allocations: 255.8 kB / 0.6701 GB, free: 13.43 MB / 0.5574 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02114/1.016, allocations: 3.848 MB / 0.6738 GB, free: 9.605 MB / 0.5574 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004908/1.021, allocations: 1.415 MB / 0.6752 GB, free: 8.273 MB / 0.5574 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008192/1.029, allocations: 9.233 MB / 0.6842 GB, free: 14.49 MB / 0.573 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006491/1.036, allocations: 246 kB / 0.6845 GB, free: 14.25 MB / 0.573 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001688/1.037, allocations: 275.8 kB / 0.6847 GB, free: 13.98 MB / 0.573 GB
Warning: Assuming fixed start value for the following 3 variables:
         drum.volume.h_liq:VARIABLE(start = drum.volume.h_liq_start unit = \"J/kg\" fixed = true nominal = 1000.0 )  \"Specific enthalpy of liquid phase\" type: Real
         drum.volume.h_vap:VARIABLE(start = drum.volume.h_vap_start unit = \"J/kg\" fixed = true nominal = 1000.0 )  \"Specific enthalpy of vapour phase\" type: Real
         drum.volume.p_vap:VARIABLE(min = 0.0 start = drum.volume.p_start unit = \"Pa\" fixed = true nominal = 1e5 stateSelect=StateSelect.prefer )  \"Vapour pressure\" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 324
 * 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 initialization (662):
 * Single equations (assignments): 586
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 69
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 4
 * Torn equation systems: 3
 * 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): 4 systems
   {1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(4,3,87.5%), (1,6,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(2,19)}
Notification: Performance of prepare postOptimizeDAE: time 0.002796/1.04, allocations: 0.5819 MB / 0.6853 GB, free: 13.39 MB / 0.573 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002996/1.043, allocations: 459.5 kB / 0.6857 GB, free: 12.94 MB / 0.573 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01439/1.057, allocations: 11.4 MB / 0.6969 GB, free: 3.684 MB / 0.573 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.946e-05/1.058, allocations: 52 kB / 0.6969 GB, free: 3.633 MB / 0.573 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.708e-05/1.058, allocations: 3.938 kB / 0.6969 GB, free: 3.629 MB / 0.573 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.004586/1.062, allocations: 2.498 MB / 0.6994 GB, free: 1.008 MB / 0.573 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.02143/1.084, allocations: 7.394 MB / 0.7066 GB, free: 10.17 MB / 0.5887 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0003793/1.084, allocations: 151.8 kB / 0.7067 GB, free: 10.02 MB / 0.5887 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003348/1.087, allocations: 0.9055 MB / 0.7076 GB, free: 9.117 MB / 0.5887 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.002486/1.09, allocations: 0.7359 MB / 0.7083 GB, free: 8.371 MB / 0.5887 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001334/1.091, allocations: 119.9 kB / 0.7085 GB, free: 8.254 MB / 0.5887 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.005181/1.096, allocations: 5.063 MB / 0.7134 GB, free: 2.895 MB / 0.5887 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.841e-06/1.096, allocations: 7.938 kB / 0.7134 GB, free: 2.887 MB / 0.5887 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01206/1.108, allocations: 4.291 MB / 0.7176 GB, free: 14.56 MB / 0.6043 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004685/1.113, allocations: 1.213 MB / 0.7188 GB, free: 13.32 MB / 0.6043 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.00232/1.115, allocations: 115.6 kB / 0.7189 GB, free: 13.21 MB / 0.6043 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004061/1.119, allocations: 87.98 kB / 0.719 GB, free: 13.12 MB / 0.6043 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001134/1.121, allocations: 290.3 kB / 0.7193 GB, free: 12.84 MB / 0.6043 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0005872/1.121, allocations: 115.9 kB / 0.7194 GB, free: 12.72 MB / 0.6043 GB
Notification: Performance of sorting global known variables: time 0.00488/1.126, allocations: 2.247 MB / 0.7216 GB, free: 10.75 MB / 0.6043 GB
Notification: Performance of sort global known variables: time 2.3e-07/1.126, allocations: 4 kB / 0.7216 GB, free: 10.75 MB / 0.6043 GB
Notification: Performance of remove unused functions: time 0.01618/1.142, allocations: 2.932 MB / 0.7244 GB, free: 7.816 MB / 0.6043 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 16
 * Number of states: 9 (drum.volume.h_liq,drum.volume.h_vap,drum.volume.volume_vap,drum.volume.p_vap,drum.wall.U[1],drum.wall.U[2],drum.wall.U[3],evaporator.h,evaporator.p)
 * 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 (381):
 * Single equations (assignments): 328
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 49
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 4
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 3 systems
   {(4,3,87.5%), (1,1,100.0%), (1,5,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,4)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.01555/1.158, allocations: 6.341 MB / 0.7306 GB, free: 2.238 MB / 0.6043 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/Basics/ControlVolumes/FluidVolumes/VolumeVLE_L3_TwoZonesNPort.mo:130:3-130:139:writable] Warning: Variable $DER.drum.volume.volume_vap has attribute stateSelect=StateSelect.always, but can't be selected as a state.
Notification: Performance of simCode: created initialization part: time 0.01934/1.177, allocations: 10.33 MB / 0.7407 GB, free: 7.176 MB / 0.6199 GB
Notification: Performance of simCode: created event and clocks part: time 0.0002826/1.177, allocations: 95.94 kB / 0.7408 GB, free: 7.074 MB / 0.6199 GB
Notification: Performance of simCode: created simulation system equations: time 0.00546/1.183, allocations: 3.36 MB / 0.7441 GB, free: 3.426 MB / 0.6199 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.009076/1.192, allocations: 1.673 MB / 0.7457 GB, free: 1.785 MB / 0.6199 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.3742/1.566, allocations: 28.62 MB / 0.7737 GB, free: 175.1 MB / 0.6355 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002483/1.569, allocations: 1.525 MB / 0.7752 GB, free: 174.9 MB / 0.6355 GB
Notification: Performance of simCode: alias equations: time 0.007273/1.576, allocations: 1.912 MB / 0.777 GB, free: 174.8 MB / 0.6355 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.01158/1.587, allocations: 4.693 MB / 0.7816 GB, free: 174.7 MB / 0.6355 GB
Notification: Performance of SimCode: time 3.096e-06/1.587, allocations: 0 / 0.7816 GB, free: 174.7 MB / 0.6355 GB
Notification: FMU-only work: backend 0.0107, simCode 0.3422, templates 0
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.3487/1.936, allocations: 203.2 MB / 0.98 GB, free: 10.5 MB / 0.6512 GB
Notification: Building FMU for platform 'static' (1/1).
Notification: Finished FMU for platform 'static' (1/1).
Notification: Performance of buildModelFMU: Generate platform static: time 5.649/7.585, allocations: 19.34 kB / 0.98 GB, free: 10.48 MB / 0.6512 GB
"
[Timeout remaining time 792]
(rm -f ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum.pipe ; mkfifo ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum.pipe ; head -c 1048576 < ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum.pipe >> ../files/ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum.sim & (cd run_OMSimulator && /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator --resultFile=../ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_res.mat --stripRoot --tempDir=temp_ClaRa_ClaRa_Components_MechanicalSeparation_Check_TestDrum_fmu --startTime=0 --stopTime=800 --stepSize=0.32 --mode=me --timeout=240 --tolerance=1e-06 ../ClaRa_ClaRa_Components_MechanicalSeparation_Check_TestDrum.fmu) > ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum.pipe 2>&1) [Timeout 252.0]
diffSimulationResults("ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/ClaRa/ReferenceResults/ClaRa.Components.MechanicalSeparation.Check.TestDrum.mat","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 800]
""
[Timeout remaining time 800]
Reference file matches
(rm -f ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_OMSimulator-cs-cvode.pipe ; mkfifo ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_OMSimulator-cs-cvode.pipe ; head -c 1048576 < ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_OMSimulator-cs-cvode.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_OMSimulator-cs-cvode.sim & (cd run_OMSimulator-cs-cvode && /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator --resultFile=../ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_OMSimulator-cs-cvode_res.mat --stripRoot --tempDir=temp_ClaRa_ClaRa_Components_MechanicalSeparation_Check_TestDrum_OMSimulator-cs-cvode_fmu --startTime=0 --stopTime=800 --stepSize=0.32 --mode=cs --timeout=240 --tolerance=1e-06 ../ClaRa_ClaRa_Components_MechanicalSeparation_Check_TestDrum_OMSimulator-cs-cvode.fmu) > ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_OMSimulator-cs-cvode.pipe 2>&1) [Timeout 252.0]
diffSimulationResults("ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_OMSimulator-cs-cvode_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/ClaRa/ReferenceResults/ClaRa.Components.MechanicalSeparation.Check.TestDrum.mat","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 800]
""
[Timeout remaining time 800]
Reference file matches
(rm -f ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_OMSimulator-cs-euler.pipe ; mkfifo ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_OMSimulator-cs-euler.pipe ; head -c 1048576 < ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_OMSimulator-cs-euler.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_OMSimulator-cs-euler.sim & (cd run_OMSimulator-cs-euler && /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator --resultFile=../ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_OMSimulator-cs-euler_res.mat --stripRoot --tempDir=temp_ClaRa_ClaRa_Components_MechanicalSeparation_Check_TestDrum_OMSimulator-cs-euler_fmu --startTime=0 --stopTime=800 --stepSize=0.32 --mode=cs --timeout=240 --tolerance=1e-06 ../ClaRa_ClaRa_Components_MechanicalSeparation_Check_TestDrum_OMSimulator-cs-euler.fmu) > ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_OMSimulator-cs-euler.pipe 2>&1) [Timeout 252.0]
OMSimulator-cs-euler timed out simulating the FMU
(rm -f ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy.pipe ; mkfifo ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy.pipe ; head -c 1048576 < ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy.sim & (cd run_fmpy && python3 -m fmpy simulate --output-file ../ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy_res.csv --output-variables Time drum.volume.h_liq drum.volume.h_vap drum.volume.p_vap drum.volume.volume_vap 'drum.wall.U[1]' 'drum.wall.U[2]' 'drum.wall.U[3]' evaporator.h evaporator.p --start-time 0 --stop-time 800 --timeout 240 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.32 ../ClaRa_ClaRa_Components_MechanicalSeparation_Check_TestDrum.fmu) > ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy.pipe 2>&1) [Timeout 252.0]
diffSimulationResults("ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy_res.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/ClaRa/ReferenceResults/ClaRa.Components.MechanicalSeparation.Check.TestDrum.mat","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 800]
"Error: Could not read variable Time in file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy_res.csv.
Warning: Get data of variable Time from file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy_res.csv failed!
"
[Timeout remaining time 800]
""

Variables in the reference:Time,drum.volume.h_liq,drum.volume.h_vap,drum.volume.p_vap,drum.volume.volume_vap,drum.wall.U[1],drum.wall.U[2],drum.wall.U[3],evaporator.h,evaporator.p

Variables in the result:time,drum.volume.h_liq,drum.volume.h_vap,drum.volume.p_vap,drum.volume.volume_vap,drum.wall.U[1],drum.wall.U[2],drum.wall.U[3],evaporator.h,evaporator.p
filterSimulationResults("ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy_res.csv", "/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy.diff.mat", vars={"Time"}, removeDescription=false) [Timeout 800]
"Error: Could not read variable Time in file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy_res.csv.
"
[Timeout remaining time 800]
Failed to write the differing variables of ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy_res.csv to /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy.diff.mat:
""
filterSimulationResults("/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/ClaRa/ReferenceResults/ClaRa.Components.MechanicalSeparation.Check.TestDrum.mat", "/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy.diff.ref.mat", vars={"Time"}, removeDescription=false) [Timeout 800]
""
[Timeout remaining time 800]
(rm -f ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode.pipe ; mkfifo ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode.pipe ; head -c 1048576 < ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode.sim & (cd run_fmpy-fmi3-cs-cvode && python3 -m fmpy simulate --output-file ../ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv --start-time 0 --stop-time 800 --timeout 240 --relative-tolerance 1e-06 --interface-type CoSimulation --output-interval 0.32 ../ClaRa_ClaRa_Components_MechanicalSeparation_Check_TestDrum_fmpy-fmi3-cs-cvode.fmu) > ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode.pipe 2>&1) [Timeout 252.0]
diffSimulationResults("ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/ClaRa/ReferenceResults/ClaRa.Components.MechanicalSeparation.Check.TestDrum.mat","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 800]
"Error: Could not read variable Time in file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv.
Warning: Get data of variable Time from file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv failed!
Error: Could not read variable drum.wall.U[2] in file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv.
Warning: Get data of variable drum.wall.U[2] from file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv failed!
Error: Could not read variable drum.wall.U[3] in file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv.
Warning: Get data of variable drum.wall.U[3] from file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv failed!
Error: Could not read variable evaporator.h in file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv.
Warning: Get data of variable evaporator.h from file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv failed!
Error: Could not read variable evaporator.p in file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv.
Warning: Get data of variable evaporator.p from file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv failed!
"
[Timeout remaining time 800]
""

Variables in the reference:Time,drum.volume.h_liq,drum.volume.h_vap,drum.volume.p_vap,drum.volume.volume_vap,drum.wall.U[1],drum.wall.U[2],drum.wall.U[3],evaporator.h,evaporator.p

Variables in the result:time,drum.volume.h_liq,drum.volume.h_vap,drum.volume.p_vap,drum.volume.volume_vap,drum.wall.U[1]
filterSimulationResults("ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv", "/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode.diff.mat", vars={"evaporator.p", "evaporator.h", "drum.wall.U[3]", "drum.wall.U[2]", "Time"}, removeDescription=false) [Timeout 800]
"Error: Could not read variable evaporator.p in file ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv.
"
[Timeout remaining time 800]
Failed to write the differing variables of ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode_res.csv to /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode.diff.mat:
""
filterSimulationResults("/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/ClaRa/ReferenceResults/ClaRa.Components.MechanicalSeparation.Check.TestDrum.mat", "/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestDrum_fmpy-fmi3-cs-cvode.diff.ref.mat", vars={"evaporator.p", "evaporator.h", "drum.wall.U[3]", "drum.wall.U[2]", "Time"}, removeDescription=false) [Timeout 800]
""
[Timeout remaining time 800]
[Calling sys.exit(0), Time elapsed: 14.424119655042887]