Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr AixLib_latest_AixLib.Systems.ModularAHU.Examples.DemandControlledAHU.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.1.1-master/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/SDF master/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/package.mo", uses=false)
Using package AixLib with version 1.4.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/package.mo)
Using package SDF with version 0.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SDF master/package.mo)
Using package Modelica_DeviceDrivers with version 2.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.1.1-master/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(AixLib.Systems.ModularAHU.Examples.DemandControlledAHU,tolerance=1e-05,outputFormat="empty",numberOfIntervals=10078,variableFilter="",fileNamePrefix="AixLib_latest_AixLib.Systems.ModularAHU.Examples.DemandControlledAHU")
translateModel(AixLib.Systems.ModularAHU.Examples.DemandControlledAHU,tolerance=1e-05,outputFormat="empty",numberOfIntervals=10078,variableFilter="",fileNamePrefix="AixLib_latest_AixLib.Systems.ModularAHU.Examples.DemandControlledAHU")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001036/0.001036, allocations: 92.16 kB / 16.02 MB, free: 80 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001413/0.001412, allocations: 173 kB / 16.92 MB, free: 6.652 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.481/1.481, allocations: 223.3 MB / 241 MB, free: 5.332 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.1.1-master/package.mo): time 0.1931/0.1931, allocations: 12.02 MB / 304.1 MB, free: 1.629 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/SDF master/package.mo): time 0.0002606/0.0002605, allocations: 11.98 kB / 358.2 MB, free: 3.113 MB / 286.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/Obsolete/YearIndependent/FastHVAC/Components/HeatExchangers/RadiatorMultiLayer.mo:265:34-265:6228:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/Obsolete/YearIndependent/FastHVAC/Components/HeatExchangers/RadiatorMultiLayer.mo:265:34-265:6853:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/Obsolete/YearIndependent/FastHVAC/Components/HeatExchangers/RadiatorMultiLayer.mo:265:34-265:8435:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/Obsolete/YearIndependent/FastHVAC/Components/HeatExchangers/RadiatorMultiLayer.mo:265:34-265:9369:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/Obsolete/YearIndependent/FastHVAC/Components/HeatExchangers/RadiatorMultiLayer.mo:265:34-265:11505:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/Obsolete/YearIndependent/FastHVAC/Components/HeatExchangers/RadiatorMultiLayer.mo:265:34-265:12875:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/ThermalZones/HighOrder/Components/Examples/DryAir/DryAir_test.mo:71:18-71:405:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/ThermalZones/HighOrder/Components/DryAir/InfiltrationRate_DIN12831.mo:20:192-20:2066:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/ThermalZones/HighOrder/Components/DryAir/InfiltrationRate_DIN12831.mo:20:192-20:4252:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/DataBase/Weather/SurfaceOrientation/SurfaceOrientationData_N_E_S_W_RoofN_Roof_S.mo:5:35-5:362:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/DataBase/Weather/SurfaceOrientation/SurfaceOrientationData_N_E_S_W_Hor.mo:5:35-5:320:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/DataBase/Weather/SurfaceOrientation/SurfaceOrientationData_NE_SE_SW_NW_Hor.mo:5:35-5:324:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/DataBase/CHP/CHPDataSimple/CHP_XRGI_9kWel.mo:25:34-25:588:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/DataBase/CHP/CHPDataSimple/CHP_XRGI_9kWel.mo:25:34-25:3852:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/DataBase/CHP/CHPDataSimple/CHP_Cleanergy_C9G.mo:36:34-36:594:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/BoundaryConditions/InternalGains/Examples/InternalGains/Machines.mo:31:20-31:400:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/BoundaryConditions/InternalGains/Examples/InternalGains/Lights.mo:27:20-27:398:writable] Warning: Lexer treating \ as \\, since \
 is not a valid Modelica escape sequence.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/package.mo): time 1.997/1.997, allocations: 270.9 MB / 0.6672 GB, free: 15.25 MB / 478.1 MB
Warning: Ignoring unknown experiment annotation option: __Dymola_Algorithm = "Dassl"
Notification: Performance of FrontEnd - loaded program: time 0.0005248/0.0005247, allocations: 20.02 kB / 0.7887 GB, free: 31.48 MB / 0.545 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2436/0.2441, allocations: 111 MB / 0.8971 GB, free: 12.48 MB / 0.6075 GB
Notification: Performance of NFInst.instantiate(AixLib.Systems.ModularAHU.Examples.DemandControlledAHU): time 10.34/10.58, allocations: 3.62 GB / 4.518 GB, free: 6.383 MB / 1.748 GB
Notification: Performance of NFInst.instExpressions: time 0.7332/11.31, allocations: 0.5283 GB / 5.046 GB, free: 14.41 MB / 2.045 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.1163/11.43, allocations: 2.182 MB / 5.048 GB, free: 12.22 MB / 2.045 GB
Notification: Performance of NFTyping.typeComponents: time 1.692/13.12, allocations: 30.31 MB / 5.078 GB, free: 9.773 MB / 2.045 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/ThermalZones/ReducedOrder/ThermalZone/ThermalZone.mo:325:47-325:67:writable] Warning: Conditional component ‘volMoiAir‘ is used in a non-connect context.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/ThermalZones/ReducedOrder/ThermalZone/ThermalZone.mo:454:55-454:74:writable] Warning: Conditional component ‘volMoiAir‘ is used in a non-connect context.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/ThermalZones/ReducedOrder/ThermalZone/ThermalZone.mo:472:71-475:46:writable] Warning: Conditional component ‘lights‘ is used in a non-connect context.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/ThermalZones/ReducedOrder/ThermalZone/ThermalZone.mo:472:71-475:46:writable] Warning: Conditional component ‘machinesSenHea‘ is used in a non-connect context.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 1.4.0-development/ThermalZones/ReducedOrder/ThermalZone/ThermalZone.mo:472:71-475:46:writable] Warning: Conditional component ‘humanTotHeaDependent‘ is used in a non-connect context.
Notification: Performance of NFTyping.typeBindings: time 0.2189/13.34, allocations: 70.62 MB / 5.147 GB, free: 9.258 MB / 2.045 GB
Notification: Performance of NFTyping.typeClassSections: time 0.1313/13.47, allocations: 52.41 MB / 5.198 GB, free: 8.543 MB / 2.045 GB
Notification: Performance of NFFlatten.flatten: time 0.2871/13.76, allocations: 229 MB / 5.421 GB, free: 12.49 MB / 2.061 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.1565/13.92, allocations: 70.92 MB / 5.491 GB, free: 4.625 MB / 2.092 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.1384/14.05, allocations: 51.15 MB / 5.541 GB, free: 13.42 MB / 2.139 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.1111/14.17, allocations: 47.74 MB / 5.587 GB, free: 6.211 MB / 2.17 GB
Notification: Performance of NFPackage.collectConstants: time 0.02723/14.19, allocations: 2.738 MB / 5.59 GB, free: 3.473 MB / 2.17 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.1284/14.32, allocations: 35.89 MB / 5.625 GB, free: 9.551 MB / 2.201 GB
Notification: Performance of NFScalarize.scalarize: time 0.03781/14.36, allocations: 17.69 MB / 5.642 GB, free: 12.15 MB / 2.217 GB
Notification: Performance of NFVerifyModel.verify: time 0.09376/14.45, allocations: 38.67 MB / 5.68 GB, free: 7.758 MB / 2.248 GB
Notification: Performance of NFConvertDAE.convert: time 0.1828/14.64, allocations: 140.4 MB / 5.817 GB, free: 14.62 MB / 2.389 GB
Notification: Performance of FrontEnd - DAE generated: time 6.613e-06/14.64, allocations: 0 / 5.817 GB, free: 14.62 MB / 2.389 GB
Notification: Performance of FrontEnd: time 5.09e-06/14.64, allocations: 0 / 5.817 GB, free: 14.62 MB / 2.389 GB
Notification: Performance of Transformations before backend: time 0.004679/14.64, allocations: 3.938 kB / 5.817 GB, free: 14.62 MB / 2.389 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 11457
 * Number of variables: 11457
Notification: Performance of Generate backend data structure: time 0.1939/14.84, allocations: 68.55 MB / 5.884 GB, free: 10.12 MB / 2.451 GB
Notification: Performance of prepare preOptimizeDAE: time 5.852e-05/14.84, allocations: 12.03 kB / 5.884 GB, free: 10.11 MB / 2.451 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1083/14.94, allocations: 20.21 MB / 5.904 GB, free: 5.832 MB / 2.467 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 1.311/16.25, allocations: 96.16 MB / 5.998 GB, free: 1.562 GB / 2.467 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.00408/16.26, allocations: 3.44 MB / 6.001 GB, free: 1.56 GB / 2.467 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01739/16.28, allocations: 4.544 MB / 6.005 GB, free: 1.559 GB / 2.467 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.1885/16.46, allocations: 67.24 MB / 6.071 GB, free: 1.52 GB / 2.467 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.002977/16.47, allocations: 121.9 kB / 6.071 GB, free: 1.52 GB / 2.467 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01579/16.48, allocations: 1.826 MB / 6.073 GB, free: 1.518 GB / 2.467 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001755/16.49, allocations: 1.751 MB / 6.075 GB, free: 1.518 GB / 2.467 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1634/16.65, allocations: 64.06 MB / 6.137 GB, free: 1.485 GB / 2.467 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.4891/17.14, allocations: 323 MB / 6.453 GB, free: 1.17 GB / 2.467 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.09444/17.23, allocations: 49.84 MB / 6.501 GB, free: 1.122 GB / 2.467 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.05107/17.28, allocations: 23.95 MB / 6.525 GB, free: 1.098 GB / 2.467 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 evalFunc (simulation): time 0.2088/17.49, allocations: 112.2 MB / 6.634 GB, free: 0.9843 GB / 2.467 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.05577/17.55, allocations: 23.93 MB / 6.658 GB, free: 0.9609 GB / 2.467 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.03133/17.58, allocations: 2.573 MB / 6.66 GB, free: 0.9584 GB / 2.467 GB
Notification: Performance of pre-optimization done (n=2922): time 8.433e-05/17.58, allocations: 0 / 6.66 GB, free: 0.9584 GB / 2.467 GB
Notification: Performance of matching and sorting (n=2958): time 0.4462/18.03, allocations: 154.3 MB / 6.811 GB, free: 0.807 GB / 2.467 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003416/18.03, allocations: 0.6746 MB / 6.812 GB, free: 0.8063 GB / 2.467 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.1502/18.18, allocations: 69.74 MB / 6.88 GB, free: 0.7386 GB / 2.467 GB
Notification: Performance of collectPreVariables (initialization): time 0.00802/18.18, allocations: 0.6088 MB / 6.88 GB, free: 0.738 GB / 2.467 GB
Notification: Performance of collectInitialEqns (initialization): time 0.03873/18.22, allocations: 32.6 MB / 6.912 GB, free: 0.706 GB / 2.467 GB
Notification: Performance of collectInitialBindings (initialization): time 0.01706/18.24, allocations: 10.34 MB / 6.922 GB, free: 0.696 GB / 2.467 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.01101/18.25, allocations: 2.592 MB / 6.925 GB, free: 0.6935 GB / 2.467 GB
Notification: Performance of setup shared object (initialization): time 5.132e-05/18.25, allocations: 305.1 kB / 6.925 GB, free: 0.6932 GB / 2.467 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.04686/18.3, allocations: 20.66 MB / 6.945 GB, free: 0.673 GB / 2.467 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.05031/18.35, allocations: 26.6 MB / 6.971 GB, free: 0.6447 GB / 2.467 GB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: genericAHU.preheater.dynamicHX.vol1[1].dynBal.medium.p = genericAHU.preheater.dynamicHX.vol1[1].dynBal.p_start (101325.0 = genericAHU.preheater.dynamicHX.vol1[1].dynBal.p_start)
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: genericAHU.cooler.dynamicHX.vol1[1].dynBal.medium.p = genericAHU.cooler.dynamicHX.vol1[1].dynBal.p_start (101325.0 = genericAHU.cooler.dynamicHX.vol1[1].dynBal.p_start)
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: genericAHU.heater.dynamicHX.vol1[1].dynBal.medium.p = genericAHU.heater.dynamicHX.vol1[1].dynBal.p_start (101325.0 = genericAHU.heater.dynamicHX.vol1[1].dynBal.p_start)
Notification: It was not possible to check the given initialization system for consistency symbolically, because the relevant equations are part of an algebraic loop. This is not supported yet.
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: genericAHU.dynamicHX.vol2[1].dynBal.medium.p = genericAHU.dynamicHX.vol2[1].dynBal.p_start (101325.0 = genericAHU.dynamicHX.vol2[1].dynBal.p_start)
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: genericAHU.fanSup.vol.dynBal.medium.p = genericAHU.fanSup.vol.dynBal.p_start (genericAHU.humidifier.vol.dynBal.p_start = genericAHU.fanSup.vol.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 1.657/20.01, allocations: 438.3 MB / 7.399 GB, free: 220.7 MB / 2.467 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.000259/20.01, allocations: 48 kB / 7.399 GB, free: 220.7 MB / 2.467 GB
Notification: Performance of matching and sorting (n=4144) (initialization): time 0.1599/20.17, allocations: 57.54 MB / 7.456 GB, free: 162.9 MB / 2.467 GB
Notification: Performance of prepare postOptimizeDAE: time 0.002866/20.17, allocations: 5.141 MB / 7.461 GB, free: 155.6 MB / 2.467 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0006619/20.17, allocations: 200 kB / 7.461 GB, free: 155.4 MB / 2.467 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02532/20.19, allocations: 6.434 MB / 7.467 GB, free: 148.8 MB / 2.467 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.03932/20.23, allocations: 8.029 MB / 7.475 GB, free: 140.8 MB / 2.467 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05294/20.29, allocations: 78.89 MB / 7.552 GB, free: 56.48 MB / 2.467 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05793/20.35, allocations: 4.51 MB / 7.556 GB, free: 51.98 MB / 2.467 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.009093/20.35, allocations: 1.419 MB / 7.558 GB, free: 50.57 MB / 2.467 GB
Notification: Performance of prepare postOptimizeDAE: time 1.332e-05/20.35, allocations: 4 kB / 7.558 GB, free: 50.56 MB / 2.467 GB
Error: Internal error IndexReduction.pantelidesIndexReduction failed! System is structurally singular and cannot be handled because the number of unassigned equations is larger than the number of states. Use -d=bltdump to get more information.
Notification: Performance of <failed> postOpt replaceHomotopyWithSimplified (initialization): time 0.06279/20.42, allocations: 22.45 MB / 7.58 GB, free: 28.09 MB / 2.467 GB
Error: post-optimization module replaceHomotopyWithSimplified (initialization) failed.
Error: No system for the symbolic initialization was generated