Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse1.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.001291/0.001291, allocations: 116.3 kB / 17.84 MB, free: 5.312 MB / 14.72 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.001619/0.001619, allocations: 203.2 kB / 20.8 MB, free: 2.371 MB / 14.72 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.604/1.604, allocations: 230.6 MB / 254.2 MB, free: 15.05 MB / 206.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo): time 1.011/1.011, allocations: 150 MB / 460.2 MB, free: 5.227 MB / 350.1 MB " [Timeout remaining time 179] Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/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(IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse1,fileNamePrefix="IBPSA_latest_IBPSA_Examples_Tutorial_SimpleHouse_SimpleHouse1",fmuType="cs",version="2.0",platforms={"static"}) "" <> buildModelFMU(IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse1,fileNamePrefix="IBPSA_latest_IBPSA_Examples_Tutorial_SimpleHouse_SimpleHouse1",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 660] "Notification: IBPSA requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed. Notification: Performance of FrontEnd - loaded program: time 0.002071/0.002071, allocations: 69.66 kB / 0.6368 GB, free: 30.52 MB / 0.4981 GB Notification: Performance of FrontEnd - Absyn->SCode: time 0.1833/0.1854, allocations: 89.14 MB / 0.7238 GB, free: 4.059 MB / 0.5294 GB Notification: Performance of NFInst.instantiate(IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse1): time 0.008743/0.1941, allocations: 5.146 MB / 0.7289 GB, free: 14.89 MB / 0.545 GB Notification: Performance of NFInst.instExpressions: time 0.6257/0.8198, allocations: 154 MB / 0.8793 GB, free: 2.172 MB / 0.67 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.0008832/0.8207, allocations: 24.06 kB / 0.8793 GB, free: 2.172 MB / 0.67 GB Notification: Performance of NFTyping.typeComponents: time 0.00061/0.8213, allocations: 202.1 kB / 0.8795 GB, free: 2.172 MB / 0.67 GB Notification: Performance of NFTyping.typeBindings: time 0.001719/0.823, allocations: 0.5693 MB / 0.88 GB, free: 2.164 MB / 0.67 GB Notification: Performance of NFTyping.typeClassSections: time 0.002014/0.825, allocations: 0.8624 MB / 0.8809 GB, free: 2.156 MB / 0.67 GB Notification: Performance of NFFlatten.flatten: time 0.001402/0.8264, allocations: 1.449 MB / 0.8823 GB, free: 2.148 MB / 0.67 GB Notification: Performance of NFFlatten.resolveConnections: time 0.0006481/0.8271, allocations: 425 kB / 0.8827 GB, free: 2.086 MB / 0.67 GB Notification: Performance of NFEvalConstants.evaluate: time 0.000601/0.8277, allocations: 0.5092 MB / 0.8832 GB, free: 2.086 MB / 0.67 GB Notification: Performance of NFSimplifyModel.simplify: time 0.0005444/0.8282, allocations: 0.5317 MB / 0.8837 GB, free: 2.086 MB / 0.67 GB Notification: Performance of NFPackage.collectConstants: time 0.0001499/0.8284, allocations: 124.2 kB / 0.8838 GB, free: 2.082 MB / 0.67 GB Notification: Performance of NFFlatten.collectFunctions: time 0.001135/0.8295, allocations: 0.5086 MB / 0.8843 GB, free: 2.066 MB / 0.67 GB Notification: Performance of NFScalarize.scalarize: time 0.0002663/0.8298, allocations: 312.4 kB / 0.8846 GB, free: 2.066 MB / 0.67 GB Notification: Performance of NFVerifyModel.verify: time 0.0005857/0.8304, allocations: 0.583 MB / 0.8852 GB, free: 2.066 MB / 0.67 GB Notification: Performance of NFConvertDAE.convert: time 0.002209/0.8326, allocations: 1.719 MB / 0.8869 GB, free: 1.957 MB / 0.67 GB Notification: Performance of FrontEnd - DAE generated: time 5.12e-06/0.8326, allocations: 0 / 0.8869 GB, free: 1.957 MB / 0.67 GB Notification: Performance of FrontEnd: time 2.825e-06/0.8326, allocations: 1.375 kB / 0.8869 GB, free: 1.957 MB / 0.67 GB Notification: Performance of Transformations before backend: time 1.99e-05/0.8326, allocations: 0 / 0.8869 GB, free: 1.957 MB / 0.67 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 219 * Number of variables: 219 Notification: Performance of Generate backend data structure: time 0.003444/0.8361, allocations: 1.533 MB / 0.8884 GB, free: 17.12 MB / 0.6856 GB Notification: Performance of prepare preOptimizeDAE: time 9.953e-05/0.8362, allocations: 9.688 kB / 0.8884 GB, free: 17.12 MB / 0.6856 GB Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 0.0001014/0.8363, allocations: 23.34 kB / 0.8884 GB, free: 17.12 MB / 0.6856 GB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0002976/0.8366, allocations: 141.9 kB / 0.8885 GB, free: 17.08 MB / 0.6856 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00222/0.8388, allocations: 237.7 kB / 0.8888 GB, free: 17.01 MB / 0.6856 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.002245/0.841, allocations: 0.7947 MB / 0.8895 GB, free: 16.68 MB / 0.6856 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001714/0.8412, allocations: 65.78 kB / 0.8896 GB, free: 16.68 MB / 0.6856 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002548/0.8414, allocations: 82.78 kB / 0.8897 GB, free: 16.68 MB / 0.6856 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.002538/0.844, allocations: 0.9368 MB / 0.8906 GB, free: 16.53 MB / 0.6856 GB Notification: Performance of preOpt findStateOrder (simulation): time 2.717e-05/0.844, allocations: 0.5312 kB / 0.8906 GB, free: 16.53 MB / 0.6856 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001022/0.8441, allocations: 40.97 kB / 0.8906 GB, free: 16.53 MB / 0.6856 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.138e-05/0.8442, allocations: 20.91 kB / 0.8907 GB, free: 16.53 MB / 0.6856 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00235/0.8465, allocations: 1.125 MB / 0.8918 GB, free: 16.51 MB / 0.6856 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.005171/0.8517, allocations: 2.833 MB / 0.8945 GB, free: 15.39 MB / 0.6856 GB Notification: Performance of preOpt comSubExp (simulation): time 0.001606/0.8533, allocations: 0.7592 MB / 0.8953 GB, free: 15.35 MB / 0.6856 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0005431/0.8538, allocations: 282.6 kB / 0.8955 GB, free: 15.34 MB / 0.6856 GB Notification: Performance of preOpt evalFunc (simulation): time 8.409e-05/0.8539, allocations: 31.05 kB / 0.8956 GB, free: 15.34 MB / 0.6856 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0008915/0.8548, allocations: 0.491 MB / 0.896 GB, free: 15.27 MB / 0.6856 GB Notification: Performance of pre-optimization done (n=82): time 2.305e-06/0.8548, allocations: 0 / 0.896 GB, free: 15.27 MB / 0.6856 GB Notification: Performance of matching and sorting (n=82): time 0.003412/0.8582, allocations: 1.212 MB / 0.8972 GB, free: 15.06 MB / 0.6856 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001403/0.8584, allocations: 200.6 kB / 0.8974 GB, free: 14.84 MB / 0.6856 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0009668/0.8593, allocations: 0.6623 MB / 0.8981 GB, free: 14.71 MB / 0.6856 GB Notification: Performance of collectPreVariables (initialization): time 9.403e-05/0.8594, allocations: 47.86 kB / 0.8981 GB, free: 14.68 MB / 0.6856 GB Notification: Performance of collectInitialEqns (initialization): time 0.0003066/0.8597, allocations: 421.9 kB / 0.8985 GB, free: 14.48 MB / 0.6856 GB Notification: Performance of collectInitialBindings (initialization): time 0.0001903/0.8599, allocations: 277.3 kB / 0.8988 GB, free: 14.31 MB / 0.6856 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002343/0.8602, allocations: 151.9 kB / 0.8989 GB, free: 14.29 MB / 0.6856 GB Notification: Performance of setup shared object (initialization): time 0.0002024/0.8604, allocations: 388.8 kB / 0.8993 GB, free: 13.97 MB / 0.6856 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0005072/0.8609, allocations: 250.6 kB / 0.8995 GB, free: 13.97 MB / 0.6856 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0005886/0.8615, allocations: 364 kB / 0.8999 GB, free: 13.89 MB / 0.6856 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.001038/0.8625, allocations: 0.5878 MB / 0.9005 GB, free: 13.84 MB / 0.6856 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.829e-06/0.8625, allocations: 5.094 kB / 0.9005 GB, free: 13.84 MB / 0.6856 GB Notification: Performance of matching and sorting (n=91) (initialization): time 0.002028/0.8645, allocations: 0.8244 MB / 0.9013 GB, free: 13.72 MB / 0.6856 GB Notification: Performance of prepare postOptimizeDAE: time 2.09e-05/0.8646, allocations: 4.688 kB / 0.9013 GB, free: 13.72 MB / 0.6856 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.807e-06/0.8646, allocations: 0 / 0.9013 GB, free: 13.72 MB / 0.6856 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.0006569/0.8652, allocations: 83.34 kB / 0.9014 GB, free: 13.71 MB / 0.6856 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006311/0.8658, allocations: 162.3 kB / 0.9015 GB, free: 13.71 MB / 0.6856 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002309/0.8682, allocations: 1.637 MB / 0.9031 GB, free: 12.31 MB / 0.6856 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0008969/0.8691, allocations: 88.92 kB / 0.9032 GB, free: 12.28 MB / 0.6856 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.11e-05/0.8691, allocations: 33.7 kB / 0.9032 GB, free: 12.28 MB / 0.6856 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 4 * Number of states: 0 () * Number of discrete variables: 15 (weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.locTim.tNext,$PRE.weaDat.locTim.tNext,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (89): * Single equations (assignments): 88 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 0 systems * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(1,2)} Notification: Performance of prepare postOptimizeDAE: time 0.0005372/0.8697, allocations: 276.4 kB / 0.9035 GB, free: 12.15 MB / 0.6856 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002067/0.8699, allocations: 91.11 kB / 0.9036 GB, free: 12.11 MB / 0.6856 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002259/0.8721, allocations: 1.069 MB / 0.9046 GB, free: 11.99 MB / 0.6856 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.406e-06/0.8721, allocations: 11.12 kB / 0.9046 GB, free: 11.99 MB / 0.6856 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 3.657e-06/0.8721, allocations: 0 / 0.9046 GB, free: 11.99 MB / 0.6856 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.063e-05/0.8721, allocations: 5.672 kB / 0.9046 GB, free: 11.99 MB / 0.6856 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001081/0.8732, allocations: 0.9244 MB / 0.9055 GB, free: 11.77 MB / 0.6856 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.887e-06/0.8732, allocations: 1.203 kB / 0.9055 GB, free: 11.77 MB / 0.6856 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0006052/0.8738, allocations: 156.5 kB / 0.9057 GB, free: 11.77 MB / 0.6856 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.0004467/0.8743, allocations: 77.69 kB / 0.9058 GB, free: 11.75 MB / 0.6856 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.485e-05/0.8743, allocations: 28.81 kB / 0.9058 GB, free: 11.75 MB / 0.6856 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001954/0.8763, allocations: 1.655 MB / 0.9074 GB, free: 10.36 MB / 0.6856 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.222e-06/0.8763, allocations: 0 / 0.9074 GB, free: 10.36 MB / 0.6856 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.00249/0.8788, allocations: 1.34 MB / 0.9087 GB, free: 10.2 MB / 0.6856 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0004041/0.8792, allocations: 282.1 kB / 0.909 GB, free: 10.04 MB / 0.6856 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001247/0.8793, allocations: 23.88 kB / 0.909 GB, free: 10.04 MB / 0.6856 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0006439/0.88, allocations: 66.48 kB / 0.9091 GB, free: 10.04 MB / 0.6856 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000134/0.8801, allocations: 70.44 kB / 0.9091 GB, free: 10.04 MB / 0.6856 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.669e-05/0.8802, allocations: 39.3 kB / 0.9092 GB, free: 10.04 MB / 0.6856 GB Notification: Performance of sorting global known variables: time 0.0005759/0.8807, allocations: 482.7 kB / 0.9096 GB, free: 9.941 MB / 0.6856 GB Notification: Performance of sort global known variables: time 8.1e-08/0.8807, allocations: 3.125 kB / 0.9096 GB, free: 9.941 MB / 0.6856 GB Notification: Performance of remove unused functions: time 0.0009294/0.8817, allocations: 328.1 kB / 0.9099 GB, free: 9.883 MB / 0.6856 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 1 * Number of states: 1 (walCap.T) * Number of discrete variables: 10 ($whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,weaDat.locTim.tNext,weaDat.conTimMin.tNext,weaDat.conTim.tNext,weaDat.eqnTim.tNext,weaDat.decAng.tNext) * Number of discrete states: 5 (weaDat.locTim.tNext,weaDat.conTimMin.tNext,weaDat.conTim.tNext,weaDat.eqnTim.tNext,weaDat.decAng.tNext) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (93): * Single equations (assignments): 87 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 5 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 0 systems * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(1,3)} Notification: Performance of Backend phase and start with SimCode phase: time 0.004737/0.8864, allocations: 2.406 MB / 0.9123 GB, free: 9.082 MB / 0.6856 GB Notification: Performance of simCode: created initialization part: time 0.001663/0.8881, allocations: 0.7831 MB / 0.9131 GB, free: 8.941 MB / 0.6856 GB Notification: Performance of simCode: created event and clocks part: time 2.925e-06/0.8881, allocations: 0 / 0.9131 GB, free: 8.941 MB / 0.6856 GB Notification: Performance of simCode: created simulation system equations: time 0.0008856/0.8889, allocations: 0.4894 MB / 0.9135 GB, free: 8.824 MB / 0.6856 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001278/0.8902, allocations: 301.4 kB / 0.9138 GB, free: 8.797 MB / 0.6856 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01145/0.9017, allocations: 6.467 MB / 0.9201 GB, free: 6.785 MB / 0.6856 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00081/0.9025, allocations: 1.037 MB / 0.9212 GB, free: 6.066 MB / 0.6856 GB Notification: Performance of simCode: alias equations: time 0.0009605/0.9034, allocations: 261.6 kB / 0.9214 GB, free: 5.992 MB / 0.6856 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0006022/0.9041, allocations: 405.8 kB / 0.9218 GB, free: 5.984 MB / 0.6856 GB Notification: Performance of SimCode: time 1.173e-06/0.9041, allocations: 3.938 kB / 0.9218 GB, free: 5.98 MB / 0.6856 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.05795/0.962, allocations: 22.35 MB / 0.9436 GB, free: 11.31 MB / 0.7012 GB Notification: Performance of buildModelFMU: Generate platform static: time 13.94/14.9, allocations: 8.062 kB / 0.9436 GB, free: 11.3 MB / 0.7012 GB " [Timeout remaining time 645] (rm -f IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse1.pipe ; mkfifo IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse1.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse1.pipe >> ../files/IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse1.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator --tempDir=temp_IBPSA_latest_IBPSA_Examples_Tutorial_SimpleHouse_SimpleHouse1_fmu --startTime=0 --stopTime=1e+06 --stepSize=400 --timeout=50 --tolerance=1e-06 IBPSA_latest_IBPSA_Examples_Tutorial_SimpleHouse_SimpleHouse1.fmu > IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse1.pipe 2>&1) [Timeout 52.5] [Calling sys.exit(0), Time elapsed: 19.25977457303088] Failed to read output from testmodel.py, exit status != 0: 15.167987942986656 15.193405751 14.355120505 Calling exit ...