Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_IDEAS.Fluid.Interfaces.Examples.EightPortHeatMassExchanger.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.0009081/0.0009081, 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/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.0008879/0.0008879, allocations: 165.9 kB / 22.46 MB, free: 2.105 MB / 18.57 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.9123/0.9123, allocations: 177.1 MB / 202.8 MB, free: 5.762 MB / 186.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo", uses=false) [Timeout 180] "[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-531:686:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-531:694:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.order:0:0-0:0:readonly] Warning: Found duplicate names in package.order file: A, B, C. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.mo:2:1-70:10:writable] Warning: C was referenced in the package.order file, but was not found in package.mo, C/package.mo or C.mo. Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo): time 0.7381/0.7381, allocations: 154.6 MB / 413.8 MB, free: 0.8008 MB / 378.7 MB [/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/Cryptographics/BaseClasses/sha.mo:2:1-19:8:writable] Warning: External function annotation should occur on the external-clause, not on the function. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/File/sha2.mo:2:1-9:9:writable] Warning: External function annotation should occur on the external-clause, not on the function. " [Timeout remaining time 179] Using package IDEAS with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.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) Running command: "" <> buildModelFMU(IDEAS.Fluid.Interfaces.Examples.EightPortHeatMassExchanger,fileNamePrefix="IDEAS_IDEAS_Fluid_Interfaces_Examples_EightPortHeatMassExchanger",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(IDEAS.Fluid.Interfaces.Examples.EightPortHeatMassExchanger,fileNamePrefix="IDEAS_IDEAS_Fluid_Interfaces_Examples_EightPortHeatMassExchanger",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.783e-06/1.783e-06, allocations: 0 / 0.6181 GB, free: 13.68 MB / 0.5886 GB Notification: Performance of FrontEnd - Absyn->SCode: time 1.681e-05/1.86e-05, allocations: 6 kB / 0.6181 GB, free: 13.68 MB / 0.5886 GB Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Interfaces.Examples.EightPortHeatMassExchanger): time 0.3485/0.3485, allocations: 70.52 MB / 0.687 GB, free: 44.29 MB / 0.6355 GB Notification: Performance of NFInst.instExpressions: time 0.01598/0.3645, allocations: 17.62 MB / 0.7042 GB, free: 43.93 MB / 0.6355 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.00232/0.3668, allocations: 156.7 kB / 0.7043 GB, free: 43.93 MB / 0.6355 GB Notification: Performance of NFTyping.typeComponents: time 0.001889/0.3687, allocations: 1.255 MB / 0.7056 GB, free: 43.82 MB / 0.6355 GB Notification: Performance of NFTyping.typeBindings: time 0.005398/0.3741, allocations: 3.987 MB / 0.7095 GB, free: 42.29 MB / 0.6355 GB Notification: Performance of NFTyping.typeClassSections: time 0.003161/0.3773, allocations: 2.268 MB / 0.7117 GB, free: 41.36 MB / 0.6355 GB Notification: Performance of NFFlatten.flatten: time 0.005153/0.3824, allocations: 6.876 MB / 0.7184 GB, free: 38.21 MB / 0.6355 GB Notification: Performance of NFFlatten.resolveConnections: time 0.001628/0.384, allocations: 1.588 MB / 0.7199 GB, free: 37.46 MB / 0.6355 GB Notification: Performance of NFEvalConstants.evaluate: time 0.004438/0.3885, allocations: 3.575 MB / 0.7234 GB, free: 36.08 MB / 0.6355 GB Notification: Performance of NFSimplifyModel.simplify: time 0.002436/0.3909, allocations: 1.569 MB / 0.725 GB, free: 35.7 MB / 0.6355 GB Notification: Performance of NFPackage.collectConstants: time 0.0003438/0.3913, allocations: 199.4 kB / 0.7251 GB, free: 35.7 MB / 0.6355 GB Notification: Performance of NFFlatten.collectFunctions: time 0.003115/0.3944, allocations: 1.348 MB / 0.7265 GB, free: 35.34 MB / 0.6355 GB Notification: Performance of NFScalarize.scalarize: time 0.0004481/0.3948, allocations: 0.5862 MB / 0.727 GB, free: 35 MB / 0.6355 GB Notification: Performance of NFVerifyModel.verify: time 0.0009654/0.3958, allocations: 0.8531 MB / 0.7279 GB, free: 34.67 MB / 0.6355 GB Notification: Performance of NFConvertDAE.convert: time 0.004651/0.4004, allocations: 3.802 MB / 0.7316 GB, free: 33.52 MB / 0.6355 GB Notification: Performance of FrontEnd - DAE generated: time 5.66e-06/0.4004, allocations: 1.188 kB / 0.7316 GB, free: 33.52 MB / 0.6355 GB Notification: Performance of FrontEnd: time 1.943e-06/0.4004, allocations: 0 / 0.7316 GB, free: 33.52 MB / 0.6355 GB Notification: Performance of Transformations before backend: time 3.264e-05/0.4005, allocations: 4 kB / 0.7316 GB, free: 33.52 MB / 0.6355 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 344 * Number of variables: 344 Notification: Performance of Generate backend data structure: time 0.003202/0.4037, allocations: 2.692 MB / 0.7342 GB, free: 31.88 MB / 0.6355 GB Notification: Performance of prepare preOptimizeDAE: time 4.653e-05/0.4037, allocations: 13 kB / 0.7342 GB, free: 31.87 MB / 0.6355 GB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0001942/0.4039, allocations: 201.9 kB / 0.7344 GB, free: 31.75 MB / 0.6355 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0004751/0.4044, allocations: 278.4 kB / 0.7347 GB, free: 31.61 MB / 0.6355 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.002427/0.4068, allocations: 1.983 MB / 0.7366 GB, free: 30.62 MB / 0.6355 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.912e-05/0.4069, allocations: 105.8 kB / 0.7367 GB, free: 30.58 MB / 0.6355 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001426/0.407, allocations: 139.5 kB / 0.7369 GB, free: 30.54 MB / 0.6355 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.001864/0.4089, allocations: 1.828 MB / 0.7386 GB, free: 29.46 MB / 0.6355 GB Notification: Performance of preOpt findStateOrder (simulation): time 2.791e-05/0.4089, allocations: 1.938 kB / 0.7386 GB, free: 29.46 MB / 0.6355 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.438e-05/0.409, allocations: 59.12 kB / 0.7387 GB, free: 29.41 MB / 0.6355 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.487e-05/0.409, allocations: 40.02 kB / 0.7387 GB, free: 29.41 MB / 0.6355 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001561/0.4106, allocations: 1.121 MB / 0.7398 GB, free: 29.04 MB / 0.6355 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.004884/0.4155, allocations: 5.33 MB / 0.745 GB, free: 25.7 MB / 0.6355 GB Notification: Performance of preOpt comSubExp (simulation): time 0.0006832/0.4162, allocations: 451.4 kB / 0.7455 GB, free: 25.54 MB / 0.6355 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0003948/0.4166, allocations: 302.3 kB / 0.7458 GB, free: 25.44 MB / 0.6355 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0002528/0.4168, allocations: 103.8 kB / 0.7459 GB, free: 25.43 MB / 0.6355 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.163e-05/0.4168, allocations: 44.47 kB / 0.7459 GB, free: 25.38 MB / 0.6355 GB Notification: Performance of pre-optimization done (n=59): time 2.264e-06/0.4168, allocations: 4 kB / 0.7459 GB, free: 25.38 MB / 0.6355 GB Notification: Performance of matching and sorting (n=59): time 0.001384/0.4182, allocations: 0.9743 MB / 0.7469 GB, free: 25.01 MB / 0.6355 GB Notification: Performance of inlineWhenForInitialization (initialization): time 2.412e-05/0.4183, allocations: 59.22 kB / 0.7469 GB, free: 24.95 MB / 0.6355 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001654/0.4199, allocations: 1.824 MB / 0.7487 GB, free: 24.21 MB / 0.6355 GB Notification: Performance of collectPreVariables (initialization): time 4.494e-05/0.42, allocations: 43.83 kB / 0.7487 GB, free: 24.17 MB / 0.6355 GB Notification: Performance of collectInitialEqns (initialization): time 0.0004669/0.4204, allocations: 0.9425 MB / 0.7497 GB, free: 23.43 MB / 0.6355 GB Notification: Performance of collectInitialBindings (initialization): time 0.0001524/0.4206, allocations: 225 kB / 0.7499 GB, free: 23.25 MB / 0.6355 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002046/0.4208, allocations: 232.6 kB / 0.7501 GB, free: 23.13 MB / 0.6355 GB Notification: Performance of setup shared object (initialization): time 5.118e-05/0.4208, allocations: 349.2 kB / 0.7504 GB, free: 22.81 MB / 0.6355 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004287/0.4213, allocations: 333.9 kB / 0.7507 GB, free: 22.71 MB / 0.6355 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0005896/0.4218, allocations: 0.9649 MB / 0.7517 GB, free: 21.83 MB / 0.6355 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.0009706/0.4228, allocations: 1.013 MB / 0.7527 GB, free: 21.16 MB / 0.6355 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.432e-06/0.4228, allocations: 2.219 kB / 0.7527 GB, free: 21.16 MB / 0.6355 GB Notification: Performance of matching and sorting (n=135) (initialization): time 0.001424/0.4242, allocations: 1.238 MB / 0.7539 GB, free: 20.67 MB / 0.6355 GB Notification: Performance of prepare postOptimizeDAE: time 1.867e-05/0.4243, allocations: 9.438 kB / 0.7539 GB, free: 20.67 MB / 0.6355 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.043e-05/0.4243, allocations: 9.969 kB / 0.7539 GB, free: 20.67 MB / 0.6355 GB Notification: Performance of postOpt tearingSystem (initialization): time 2.225e-05/0.4243, allocations: 17.88 kB / 0.7539 GB, free: 20.67 MB / 0.6355 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005619/0.4249, allocations: 290.2 kB / 0.7542 GB, free: 20.64 MB / 0.6355 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.842e-05/0.4249, allocations: 19.78 kB / 0.7542 GB, free: 20.64 MB / 0.6355 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0007308/0.4256, allocations: 167.6 kB / 0.7544 GB, free: 20.55 MB / 0.6355 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.138e-05/0.4257, allocations: 47.12 kB / 0.7544 GB, free: 20.54 MB / 0.6355 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 45 * 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 (135): * Single equations (assignments): 135 * 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.0004224/0.4261, allocations: 447.4 kB / 0.7548 GB, free: 20.24 MB / 0.6355 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001891/0.4263, allocations: 214.1 kB / 0.755 GB, free: 20.12 MB / 0.6355 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001114/0.4274, allocations: 0.8716 MB / 0.7559 GB, free: 19.82 MB / 0.6355 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.947e-05/0.4274, allocations: 12.39 kB / 0.7559 GB, free: 19.81 MB / 0.6355 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 5.069e-06/0.4274, allocations: 1.125 kB / 0.7559 GB, free: 19.81 MB / 0.6355 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0007668/0.4282, allocations: 0.8017 MB / 0.7567 GB, free: 19.38 MB / 0.6355 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.002458/0.4306, allocations: 2.238 MB / 0.7589 GB, free: 18.32 MB / 0.6355 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.978e-06/0.4307, allocations: 1.531 kB / 0.7589 GB, free: 18.32 MB / 0.6355 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004005/0.4311, allocations: 180.1 kB / 0.7591 GB, free: 18.31 MB / 0.6355 GB Notification: Performance of postOpt tearingSystem (simulation): time 7.434e-06/0.4311, allocations: 4.062 kB / 0.7591 GB, free: 18.31 MB / 0.6355 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.834e-05/0.4311, allocations: 19.81 kB / 0.7591 GB, free: 18.3 MB / 0.6355 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 5.14e-06/0.4311, allocations: 11.19 kB / 0.7591 GB, free: 18.29 MB / 0.6355 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.956e-06/0.4311, allocations: 5.766 kB / 0.7591 GB, free: 18.29 MB / 0.6355 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001405/0.4325, allocations: 1.358 MB / 0.7604 GB, free: 17.64 MB / 0.6355 GB Notification: Performance of postOpt removeConstants (simulation): time 0.000385/0.4329, allocations: 297.1 kB / 0.7607 GB, free: 17.47 MB / 0.6355 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001346/0.433, allocations: 21.33 kB / 0.7607 GB, free: 17.46 MB / 0.6355 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0004978/0.4335, allocations: 66.52 kB / 0.7608 GB, free: 17.44 MB / 0.6355 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 8.52e-05/0.4336, allocations: 55.45 kB / 0.7608 GB, free: 17.43 MB / 0.6355 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.375e-05/0.4336, allocations: 33.42 kB / 0.7609 GB, free: 17.42 MB / 0.6355 GB Notification: Performance of sorting global known variables: time 0.00119/0.4348, allocations: 1.256 MB / 0.7621 GB, free: 16.92 MB / 0.6355 GB Notification: Performance of sort global known variables: time 9e-08/0.4348, allocations: 1.875 kB / 0.7621 GB, free: 16.92 MB / 0.6355 GB Notification: Performance of remove unused functions: time 0.001516/0.4364, allocations: 0.6597 MB / 0.7627 GB, free: 16.77 MB / 0.6355 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 9 * Number of states: 4 (eigPor.vol1.dynBal.U,eigPor.vol2.dynBal.U,eigPor.vol3.dynBal.U,eigPor.vol4.dynBal.U) * 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 (64): * Single equations (assignments): 59 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 5 * 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.002348/0.4387, allocations: 2.403 MB / 0.7651 GB, free: 15.46 MB / 0.6355 GB Notification: Performance of simCode: created initialization part: time 0.001001/0.4397, allocations: 0.788 MB / 0.7659 GB, free: 15.21 MB / 0.6355 GB Notification: Performance of simCode: created event and clocks part: time 4.648e-06/0.4397, allocations: 2.406 kB / 0.7659 GB, free: 15.21 MB / 0.6355 GB Notification: Performance of simCode: created simulation system equations: time 0.0004048/0.4401, allocations: 337.2 kB / 0.7662 GB, free: 15.12 MB / 0.6355 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002718/0.4428, allocations: 1.195 MB / 0.7674 GB, free: 14.79 MB / 0.6355 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01296/0.4558, allocations: 12.64 MB / 0.7797 GB, free: 8.582 MB / 0.6355 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0005958/0.4564, allocations: 0.8318 MB / 0.7805 GB, free: 8.367 MB / 0.6355 GB Notification: Performance of simCode: alias equations: time 0.001252/0.4576, allocations: 379.2 kB / 0.7809 GB, free: 8.281 MB / 0.6355 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000578/0.4582, allocations: 0.7678 MB / 0.7816 GB, free: 7.699 MB / 0.6355 GB Notification: Performance of SimCode: time 1.473e-06/0.4582, allocations: 2.875 kB / 0.7816 GB, free: 7.699 MB / 0.6355 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.0699/0.5281, allocations: 63.12 MB / 0.8433 GB, free: 11.77 MB / 0.698 GB Notification: Performance of buildModelFMU: Generate platform static: time 8.819/9.347, allocations: 4.062 kB / 0.8433 GB, free: 11.77 MB / 0.698 GB " [Timeout remaining time 651] (rm -f IDEAS_IDEAS.Fluid.Interfaces.Examples.EightPortHeatMassExchanger.pipe ; mkfifo IDEAS_IDEAS.Fluid.Interfaces.Examples.EightPortHeatMassExchanger.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.Interfaces.Examples.EightPortHeatMassExchanger.pipe >> ../files/IDEAS_IDEAS.Fluid.Interfaces.Examples.EightPortHeatMassExchanger.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator --tempDir=temp_IDEAS_IDEAS_Fluid_Interfaces_Examples_EightPortHeatMassExchanger_fmu --startTime=0 --stopTime=5 --stepSize=0.002 --timeout=50 --tolerance=1e-06 IDEAS_IDEAS_Fluid_Interfaces_Examples_EightPortHeatMassExchanger.fmu > IDEAS_IDEAS.Fluid.Interfaces.Examples.EightPortHeatMassExchanger.pipe 2>&1) [Timeout 52.5] [Calling sys.exit(0), Time elapsed: 12.181552928872406] Failed to read output from testmodel.py, exit status != 0: 9.458191752433777 9.468751866 9.065591677 Calling exit ...