Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Physiolibrary_Physiolibrary.Organs.Lungs.Examples.LungsTest.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 1.4.1/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 1.4.1/package.mo): time 0.0516/0.0516, allocations: 11.15 MB / 31.01 MB, free: 4.777 MB / 24.77 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.0008537/0.0008537, allocations: 86.22 kB / 38.74 MB, free: 6.84 MB / 33.03 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.0008951/0.0008951, allocations: 166.3 kB / 42.14 MB, free: 3.508 MB / 33.03 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.9158/0.9158, allocations: 177.2 MB / 222.6 MB, free: 6.293 MB / 202.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Physiolibrary 3.0.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Physiolibrary 3.0.0/package.mo): time 0.208/0.208, allocations: 17.62 MB / 296.7 MB, free: 1.266 MB / 266.7 MB
"
[Timeout remaining time 180]
Using package Physiolibrary with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Physiolibrary 3.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)
Using package Chemical with version 1.4.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 1.4.1/package.mo)
Running command: translateModel(Physiolibrary.Organs.Lungs.Examples.LungsTest,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Physiolibrary_Physiolibrary.Organs.Lungs.Examples.LungsTest")
translateModel(Physiolibrary.Organs.Lungs.Examples.LungsTest,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Physiolibrary_Physiolibrary.Organs.Lungs.Examples.LungsTest") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 2.134e-06/2.134e-06, allocations: 3.938 kB / 320.9 MB, free: 14.17 MB / 298.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.957e-05/2.17e-05, allocations: 6.312 kB / 320.9 MB, free: 14.16 MB / 298.7 MB
Notification: Performance of NFInst.instantiate(Physiolibrary.Organs.Lungs.Examples.LungsTest): time 0.04189/0.04192, allocations: 51.7 MB / 372.6 MB, free: 10.29 MB / 346.7 MB
Notification: Performance of NFInst.instExpressions: time 0.02589/0.0678, allocations: 19.72 MB / 392.3 MB, free: 6.496 MB / 362.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003341/0.07114, allocations: 95.44 kB / 392.4 MB, free: 6.402 MB / 362.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001982/0.07313, allocations: 0.95 MB / 393.4 MB, free: 5.445 MB / 362.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.01086/0.08399, allocations: 5.431 MB / 398.8 MB, free: 15.98 MB / 378.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.006866/0.09085, allocations: 4.377 MB / 403.2 MB, free: 11.59 MB / 378.7 MB
Notification: Performance of NFFlatten.flatten: time 0.008975/0.09983, allocations: 10.62 MB / 413.8 MB, free: 0.9375 MB / 378.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.008952/0.1088, allocations: 8.489 MB / 422.3 MB, free: 8.305 MB / 394.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.2359/0.3447, allocations: 38.9 MB / 461.2 MB, free: 43.92 MB / 410.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.1099/0.4546, allocations: 55.73 MB / 0.5048 GB, free: 15.42 MB / 410.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.004036/0.4587, allocations: 1.826 MB / 0.5066 GB, free: 15.42 MB / 410.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.211/0.6697, allocations: 97.06 MB / 0.6014 GB, free: 6.082 MB / 490.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.2852/0.9549, allocations: 10.41 MB / 0.6115 GB, free: 125.2 MB / 490.8 MB
Notification: Performance of NFVerifyModel.verify: time 0.01339/0.9683, allocations: 8.095 MB / 0.6194 GB, free: 125.2 MB / 490.8 MB
Notification: Performance of NFConvertDAE.convert: time 0.02238/0.9906, allocations: 24.88 MB / 0.6437 GB, free: 123.2 MB / 490.8 MB
Notification: Performance of FrontEnd - DAE generated: time 5.531e-06/0.9907, allocations: 0.75 kB / 0.6437 GB, free: 123.2 MB / 490.8 MB
Notification: Performance of FrontEnd: time 1.212e-06/0.9907, allocations: 6.875 kB / 0.6437 GB, free: 123.2 MB / 490.8 MB
Notification: Performance of Transformations before backend: time 0.0006858/0.9913, allocations: 3.75 kB / 0.6438 GB, free: 123.2 MB / 490.8 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2952
 * Number of variables: 2952
Notification: Performance of Generate backend data structure: time 6.812/7.803, allocations: 1.774 GB / 2.418 GB, free: 45.93 MB / 0.5263 GB
Notification: Performance of prepare preOptimizeDAE: time 4.275e-05/7.803, allocations: 12.03 kB / 2.418 GB, free: 45.91 MB / 0.5263 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01241/7.816, allocations: 2.311 MB / 2.42 GB, free: 44.17 MB / 0.5263 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02556/7.841, allocations: 10.18 MB / 2.43 GB, free: 37.13 MB / 0.5263 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001174/7.842, allocations: 1.102 MB / 2.431 GB, free: 36.49 MB / 0.5263 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.006909/7.849, allocations: 1.085 MB / 2.432 GB, free: 35.76 MB / 0.5263 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04201/7.891, allocations: 14.86 MB / 2.447 GB, free: 22.29 MB / 0.5263 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0005543/7.892, allocations: 60.12 kB / 2.447 GB, free: 22.25 MB / 0.5263 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.006381/7.898, allocations: 434.1 kB / 2.447 GB, free: 22.15 MB / 0.5263 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0005241/7.899, allocations: 0.5523 MB / 2.448 GB, free: 21.66 MB / 0.5263 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0276/7.926, allocations: 11.23 MB / 2.459 GB, free: 11.62 MB / 0.5263 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.323/8.249, allocations: 58.25 MB / 2.516 GB, free: 144.2 MB / 0.5263 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01285/8.262, allocations: 7.059 MB / 2.523 GB, free: 143.8 MB / 0.5263 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007798/8.27, allocations: 3.582 MB / 2.526 GB, free: 143.7 MB / 0.5263 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.05195/8.322, allocations: 21.72 MB / 2.547 GB, free: 129.5 MB / 0.5263 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.589e-05/8.322, allocations: 121.8 kB / 2.547 GB, free: 129.3 MB / 0.5263 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.009201/8.331, allocations: 2.596 MB / 2.55 GB, free: 127.1 MB / 0.5263 GB
Notification: Performance of pre-optimization done (n=1142): time 2.998e-05/8.331, allocations: 0 / 2.55 GB, free: 127.1 MB / 0.5263 GB
Notification: Performance of matching and sorting (n=1142): time 0.03819/8.369, allocations: 15.72 MB / 2.565 GB, free: 111.5 MB / 0.5263 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.23e-05/8.369, allocations: 146.7 kB / 2.565 GB, free: 111.4 MB / 0.5263 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01701/8.387, allocations: 13.45 MB / 2.579 GB, free: 100.1 MB / 0.5263 GB
Notification: Performance of collectPreVariables (initialization): time 0.005033/8.392, allocations: 197.1 kB / 2.579 GB, free: 99.86 MB / 0.5263 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003723/8.395, allocations: 4.792 MB / 2.583 GB, free: 95.71 MB / 0.5263 GB
Notification: Performance of collectInitialBindings (initialization): time 0.006225/8.401, allocations: 4.235 MB / 2.588 GB, free: 91.73 MB / 0.5263 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0182/8.42, allocations: 5.58 MB / 2.593 GB, free: 86.97 MB / 0.5263 GB
Notification: Performance of setup shared object (initialization): time 5.141e-05/8.42, allocations: 309 kB / 2.593 GB, free: 86.67 MB / 0.5263 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0176/8.437, allocations: 9.088 MB / 2.602 GB, free: 77.58 MB / 0.5263 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.02283/8.46, allocations: 12.42 MB / 2.614 GB, free: 64.48 MB / 0.5263 GB
Error: Initialization problem is structural singular. Please, check the initial conditions.
Error: No system for the symbolic initialization was generated
"
[Timeout remaining time 638]
[Calling sys.exit(0), Time elapsed: 23.434320829808712]