Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ThermoPower_4.0.0-dev_ThermoPower.Test.GasComponents.TestFanMech.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.001296/0.001296, allocations: 79.44 kB / 21.28 MB, free: 380 kB / 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.00142/0.00142, allocations: 163.7 kB / 24.54 MB, free: 3.586 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.419/1.419, allocations: 177.2 MB / 204.9 MB, free: 9.277 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoPower 4.0.0-dev/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoPower 4.0.0-dev/package.mo): time 0.1376/0.1376, allocations: 19.71 MB / 281 MB, free: 13.05 MB / 270.1 MB
"
[Timeout remaining time 180]
Using package ThermoPower with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoPower 4.0.0-dev/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: translateModel(ThermoPower.Test.GasComponents.TestFanMech,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ThermoPower_4.0.0-dev_ThermoPower.Test.GasComponents.TestFanMech")
translateModel(ThermoPower.Test.GasComponents.TestFanMech,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ThermoPower_4.0.0-dev_ThermoPower.Test.GasComponents.TestFanMech") [Timeout 660]
"Warning: Ignoring unknown experiment annotation option: Algorithm = \"Dassl\"
Notification: ThermoPower 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.002107/0.002107, allocations: 71.89 kB / 417.6 MB, free: 0.7148 MB / 334.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2908/0.2929, allocations: 60.46 MB / 478.1 MB, free: 15.55 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(ThermoPower.Test.GasComponents.TestFanMech): time 0.1742/0.4671, allocations: 129.6 MB / 0.5934 GB, free: 244 kB / 494.1 MB
Notification: Performance of NFInst.instExpressions: time 0.004662/0.4718, allocations: 2.238 MB / 0.5956 GB, free: 13.99 MB / 0.4981 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0005902/0.4724, allocations: 15.88 kB / 0.5956 GB, free: 13.98 MB / 0.4981 GB
Notification: Performance of NFTyping.typeComponents: time 0.0009896/0.4734, allocations: 270.1 kB / 0.5959 GB, free: 13.71 MB / 0.4981 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoPower 4.0.0-dev/package.mo:1975:21-1977:11:writable] Notification: From here:
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoPower 4.0.0-dev/package.mo:1766:7-1766:51:writable] Warning: Output parameter H was not assigned a value
Notification: Performance of NFTyping.typeBindings: time 0.002162/0.4755, allocations: 1.004 MB / 0.5968 GB, free: 12.7 MB / 0.4981 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002236/0.4778, allocations: 0.9959 MB / 0.5978 GB, free: 11.71 MB / 0.4981 GB
Notification: Performance of NFFlatten.flatten: time 0.002095/0.4799, allocations: 1.601 MB / 0.5994 GB, free: 10.11 MB / 0.4981 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008468/0.4807, allocations: 480.6 kB / 0.5998 GB, free: 9.637 MB / 0.4981 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001675/0.4824, allocations: 0.9506 MB / 0.6008 GB, free: 8.684 MB / 0.4981 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00137/0.4838, allocations: 0.849 MB / 0.6016 GB, free: 7.832 MB / 0.4981 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001953/0.484, allocations: 79.94 kB / 0.6017 GB, free: 7.754 MB / 0.4981 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001678/0.4856, allocations: 0.9867 MB / 0.6026 GB, free: 6.766 MB / 0.4981 GB
Notification: Performance of NFScalarize.scalarize: time 0.0003119/0.486, allocations: 223.3 kB / 0.6028 GB, free: 6.547 MB / 0.4981 GB
Notification: Performance of NFVerifyModel.verify: time 0.0005662/0.4865, allocations: 362.5 kB / 0.6032 GB, free: 6.191 MB / 0.4981 GB
Notification: Performance of NFConvertDAE.convert: time 0.002001/0.4885, allocations: 1.315 MB / 0.6045 GB, free: 4.875 MB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 3.898e-06/0.4885, allocations: 0 / 0.6045 GB, free: 4.875 MB / 0.4981 GB
Notification: Performance of FrontEnd: time 1.162e-06/0.4885, allocations: 4 kB / 0.6045 GB, free: 4.871 MB / 0.4981 GB
Notification: Performance of Transformations before backend: time 2.711e-05/0.4886, allocations: 0 / 0.6045 GB, free: 4.871 MB / 0.4981 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 138
 * Number of variables: 138
Notification: Performance of Generate backend data structure: time 0.002286/0.4908, allocations: 1.038 MB / 0.6055 GB, free: 3.797 MB / 0.4981 GB
Notification: Performance of prepare preOptimizeDAE: time 4.129e-05/0.4909, allocations: 12.03 kB / 0.6055 GB, free: 3.785 MB / 0.4981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0006/0.4915, allocations: 100.4 kB / 0.6056 GB, free: 3.688 MB / 0.4981 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0009153/0.4924, allocations: 424.2 kB / 0.606 GB, free: 3.246 MB / 0.4981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001271/0.4925, allocations: 101.3 kB / 0.6061 GB, free: 3.137 MB / 0.4981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001167/0.4926, allocations: 67.88 kB / 0.6062 GB, free: 3.07 MB / 0.4981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001643/0.4943, allocations: 1.078 MB / 0.6072 GB, free: 1.871 MB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.254e-05/0.4943, allocations: 3.938 kB / 0.6072 GB, free: 1.867 MB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 7.465e-05/0.4944, allocations: 24 kB / 0.6072 GB, free: 1.844 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.821e-05/0.4944, allocations: 19.98 kB / 0.6073 GB, free: 1.824 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001322/0.4957, allocations: 0.5906 MB / 0.6078 GB, free: 1.23 MB / 0.4981 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.004285/0.5, allocations: 2.053 MB / 0.6098 GB, free: 15.12 MB / 0.5137 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0009856/0.501, allocations: 412.3 kB / 0.6102 GB, free: 14.7 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0005022/0.5015, allocations: 226.1 kB / 0.6105 GB, free: 14.48 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001613/0.5031, allocations: 0.7287 MB / 0.6112 GB, free: 13.73 MB / 0.5137 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.709e-05/0.5031, allocations: 43.06 kB / 0.6112 GB, free: 13.68 MB / 0.5137 GB
Notification: Performance of pre-optimization done (n=52): time 2.084e-06/0.5031, allocations: 0 / 0.6112 GB, free: 13.68 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=54): time 0.00672/0.5099, allocations: 2.124 MB / 0.6133 GB, free: 11.5 MB / 0.5137 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.791e-05/0.5099, allocations: 107.3 kB / 0.6134 GB, free: 11.37 MB / 0.5137 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0005913/0.5105, allocations: 465.8 kB / 0.6138 GB, free: 10.91 MB / 0.5137 GB
Notification: Performance of collectPreVariables (initialization): time 6.601e-05/0.5106, allocations: 37.7 kB / 0.6139 GB, free: 10.86 MB / 0.5137 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002776/0.5109, allocations: 328.6 kB / 0.6142 GB, free: 10.54 MB / 0.5137 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001863/0.5111, allocations: 202.3 kB / 0.6144 GB, free: 10.34 MB / 0.5137 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002472/0.5113, allocations: 142.6 kB / 0.6145 GB, free: 10.19 MB / 0.5137 GB
Notification: Performance of setup shared object (initialization): time 0.0001934/0.5115, allocations: 366.7 kB / 0.6149 GB, free: 9.824 MB / 0.5137 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0005808/0.5121, allocations: 252.7 kB / 0.6151 GB, free: 9.574 MB / 0.5137 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007484/0.5128, allocations: 484 kB / 0.6156 GB, free: 9.012 MB / 0.5137 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.00118/0.514, allocations: 0.5998 MB / 0.6161 GB, free: 8.34 MB / 0.5137 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.006e-06/0.514, allocations: 0 / 0.6161 GB, free: 8.34 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=86) (initialization): time 0.001987/0.516, allocations: 0.8772 MB / 0.617 GB, free: 7.453 MB / 0.5137 GB
Notification: Performance of prepare postOptimizeDAE: time 2.375e-05/0.516, allocations: 11.94 kB / 0.617 GB, free: 7.441 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.508e-05/0.516, allocations: 8.562 kB / 0.617 GB, free: 7.434 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001967/0.518, allocations: 441.4 kB / 0.6174 GB, free: 6.996 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005415/0.5186, allocations: 127.8 kB / 0.6176 GB, free: 6.871 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001806/0.5204, allocations: 1.463 MB / 0.619 GB, free: 5.359 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0006148/0.521, allocations: 56 kB / 0.619 GB, free: 5.305 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001194/0.5211, allocations: 79.98 kB / 0.6191 GB, free: 5.227 MB / 0.5137 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.000688/0.5218, allocations: 346 kB / 0.6194 GB, free: 4.883 MB / 0.5137 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0006975/0.5225, allocations: 471.4 kB / 0.6199 GB, free: 4.324 MB / 0.5137 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.001033/0.5235, allocations: 0.5624 MB / 0.6204 GB, free: 3.676 MB / 0.5137 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 4.278e-06/0.5235, allocations: 0 / 0.6204 GB, free: 3.676 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=86) (initialization_lambda0): time 0.001857/0.5254, allocations: 0.8033 MB / 0.6212 GB, free: 2.863 MB / 0.5137 GB
Notification: Performance of prepare postOptimizeDAE: time 1.415e-05/0.5254, allocations: 8 kB / 0.6212 GB, free: 2.855 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.815e-06/0.5254, allocations: 4 kB / 0.6212 GB, free: 2.852 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0006849/0.5261, allocations: 269.8 kB / 0.6215 GB, free: 2.582 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006472/0.5267, allocations: 151.8 kB / 0.6216 GB, free: 2.434 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003155/0.5299, allocations: 2.497 MB / 0.6241 GB, free: 15.82 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005982/0.5305, allocations: 60.06 kB / 0.6241 GB, free: 15.76 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.442e-05/0.5305, allocations: 35.98 kB / 0.6242 GB, free: 15.72 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 24
 * Number of states: 0 ()
 * Number of discrete variables: 8 (Clutch1.mode,$PRE.Clutch1.mode,Clutch1.locked,Clutch1.startBackward,$PRE.Clutch1.startBackward,Clutch1.startForward,$PRE.Clutch1.startForward,Clutch1.free)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (64):
 * Single equations (assignments): 62
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * 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): 2 systems
   {(1,9), (3,11)}
Notification: Performance of prepare postOptimizeDAE: time 0.0002834/0.5308, allocations: 224 kB / 0.6244 GB, free: 15.5 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001537/0.531, allocations: 67.56 kB / 0.6245 GB, free: 15.43 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001825/0.5328, allocations: 0.7157 MB / 0.6252 GB, free: 14.7 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.412e-06/0.5328, allocations: 0 / 0.6252 GB, free: 14.7 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.624e-06/0.5328, allocations: 8 kB / 0.6252 GB, free: 14.7 MB / 0.5294 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003224/0.5331, allocations: 127.5 kB / 0.6253 GB, free: 14.57 MB / 0.5294 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 9.852e-05/0.5332, allocations: 12 kB / 0.6253 GB, free: 14.56 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.000552/0.5338, allocations: 55.94 kB / 0.6253 GB, free: 14.5 MB / 0.5294 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001817/0.534, allocations: 68.38 kB / 0.6254 GB, free: 14.43 MB / 0.5294 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.00237/0.5363, allocations: 0.7845 MB / 0.6262 GB, free: 13.63 MB / 0.5294 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.001783/0.5381, allocations: 0.9102 MB / 0.6271 GB, free: 12.71 MB / 0.5294 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0004962/0.5386, allocations: 199.9 kB / 0.6273 GB, free: 12.51 MB / 0.5294 GB
Notification: Performance of sorting global known variables: time 0.0004214/0.539, allocations: 311.1 kB / 0.6276 GB, free: 12.21 MB / 0.5294 GB
Notification: Performance of Backend: time 4.71e-07/0.539, allocations: 0 / 0.6276 GB, free: 12.21 MB / 0.5294 GB
Notification: Performance of simCode: created initialization part: time 0.00503/0.5441, allocations: 1.545 MB / 0.6291 GB, free: 10.89 MB / 0.5294 GB
Notification: Performance of SimCode: time 0.002764/0.5468, allocations: 1.903 MB / 0.6309 GB, free: 8.945 MB / 0.5294 GB
Notification: Performance of Templates: time 0.219/0.7659, allocations: 17.28 MB / 0.6478 GB, free: 165.2 MB / 0.5294 GB
"
[Timeout remaining time 659]
make -j1 -f ThermoPower_4.0.0-dev_ThermoPower.Test.GasComponents.TestFanMech.makefile [Timeout 660]
(rm -f ThermoPower_4.0.0-dev_ThermoPower.Test.GasComponents.TestFanMech.pipe ; mkfifo ThermoPower_4.0.0-dev_ThermoPower.Test.GasComponents.TestFanMech.pipe ; head -c 1048576 < ThermoPower_4.0.0-dev_ThermoPower.Test.GasComponents.TestFanMech.pipe >> ../files/ThermoPower_4.0.0-dev_ThermoPower.Test.GasComponents.TestFanMech.sim & ./ThermoPower_4.0.0-dev_ThermoPower.Test.GasComponents.TestFanMech  -abortSlowSimulation -alarm=480 -lv LOG_STATS > ThermoPower_4.0.0-dev_ThermoPower.Test.GasComponents.TestFanMech.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 5.741242639021948]
Failed to read output from testmodel.py, exit status != 0:
0.7743123410036787 0.783296408 0.292427541
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7ff1f0041210>