Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.HeatPumps.Validation.Carnot_TCon_etaPL.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.0009422/0.0009422, allocations: 84.23 kB / 20.06 MB, free: 4.461 MB / 18.57 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.0009393/0.0009393, allocations: 173.9 kB / 23.36 MB, free: 1.156 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.9338/0.9338, allocations: 177.1 MB / 203.7 MB, free: 5.715 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Examples/PPD12/Structure.mo:531:21-535:34:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Examples/PPD12/Structure.mo:531:21-535:42:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/package.mo): time 0.7281/0.7281, allocations: 154.6 MB / 414.7 MB, free: 0.793 MB / 378.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/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 master/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 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: translateModel(IDEAS.Fluid.HeatPumps.Validation.Carnot_TCon_etaPL,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.HeatPumps.Validation.Carnot_TCon_etaPL")
translateModel(IDEAS.Fluid.HeatPumps.Validation.Carnot_TCon_etaPL,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.HeatPumps.Validation.Carnot_TCon_etaPL") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.691e-05/2.691e-05, allocations: 3.625 kB / 0.6195 GB, free: 13.08 MB / 0.5886 GB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.HeatPumps.Validation.Carnot_TCon_etaPL): time 0.03882/0.03885, allocations: 56.1 MB / 0.6743 GB, free: 4.773 MB / 0.6355 GB
Notification: Performance of NFInst.instExpressions: time 0.3981/0.4369, allocations: 11.14 MB / 0.6852 GB, free: 57.8 MB / 0.6355 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003009/0.4399, allocations: 82.84 kB / 0.6853 GB, free: 57.8 MB / 0.6355 GB
Notification: Performance of NFTyping.typeComponents: time 0.002334/0.4423, allocations: 0.687 MB / 0.686 GB, free: 57.79 MB / 0.6355 GB
Notification: Performance of NFTyping.typeBindings: time 0.006381/0.4486, allocations: 2.136 MB / 0.6881 GB, free: 57.45 MB / 0.6355 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003385/0.452, allocations: 1.288 MB / 0.6893 GB, free: 57.41 MB / 0.6355 GB
Notification: Performance of NFFlatten.flatten: time 0.005532/0.4576, allocations: 4.047 MB / 0.6933 GB, free: 56.7 MB / 0.6355 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.00228/0.4598, allocations: 1.128 MB / 0.6944 GB, free: 56.61 MB / 0.6355 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005006/0.4648, allocations: 2.34 MB / 0.6967 GB, free: 56.37 MB / 0.6355 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002001/0.4668, allocations: 0.8265 MB / 0.6975 GB, free: 56.27 MB / 0.6355 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001742/0.467, allocations: 117.7 kB / 0.6976 GB, free: 56.27 MB / 0.6355 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002605/0.4696, allocations: 1.208 MB / 0.6988 GB, free: 56.19 MB / 0.6355 GB
Notification: Performance of combineBinaries: time 0.001522/0.4711, allocations: 2.528 MB / 0.7012 GB, free: 54.89 MB / 0.6355 GB
Notification: Performance of replaceArrayConstructors: time 0.0006653/0.4718, allocations: 1.62 MB / 0.7028 GB, free: 53.92 MB / 0.6355 GB
Notification: Performance of NFVerifyModel.verify: time 0.0002854/0.4721, allocations: 125.2 kB / 0.7029 GB, free: 53.92 MB / 0.6355 GB
Notification: Performance of FrontEnd: time 0.0005142/0.4726, allocations: 49.31 kB / 0.703 GB, free: 53.91 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: 192 (180)
 * Number of variables: 192 (189)
Notification: Performance of [SIM] Bindings: time 0.003953/0.4766, allocations: 6.196 MB / 0.709 GB, free: 50.36 MB / 0.6355 GB
Notification: Performance of [SIM] FunctionAlias: time 0.001009/0.4776, allocations: 0.9984 MB / 0.71 GB, free: 49.86 MB / 0.6355 GB
Notification: Performance of [SIM] Early Inline: time 0.002571/0.4801, allocations: 4.081 MB / 0.714 GB, free: 47.45 MB / 0.6355 GB
Notification: Performance of [SIM] Simplify 1: time 0.0004274/0.4806, allocations: 390 kB / 0.7144 GB, free: 47.31 MB / 0.6355 GB
Warning: NBAlias.setStartFixed: Alias set with conflicting unfixed start values detected. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.006378/0.4869, allocations: 5.101 MB / 0.7193 GB, free: 44.59 MB / 0.6355 GB
Notification: Performance of [SIM] Simplify 2: time 0.000452/0.4874, allocations: 309.6 kB / 0.7196 GB, free: 44.47 MB / 0.6355 GB
Notification: Performance of [SIM] Remove Stream: time 0.0002183/0.4876, allocations: 273.2 kB / 0.7199 GB, free: 44.3 MB / 0.6355 GB
Notification: Performance of [SIM] Detect States: time 0.0009617/0.4886, allocations: 0.8237 MB / 0.7207 GB, free: 43.75 MB / 0.6355 GB
Notification: Performance of [SIM] Events: time 0.0001777/0.4888, allocations: 97.77 kB / 0.7208 GB, free: 43.7 MB / 0.6355 GB
Notification: Performance of [SIM] Partitioning: time 0.001174/0.4899, allocations: 1.048 MB / 0.7218 GB, free: 43.14 MB / 0.6355 GB
Notification: Performance of [SIM] Causalize: time 0.002649/0.4926, allocations: 1.983 MB / 0.7237 GB, free: 42.64 MB / 0.6355 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.001258/0.4938, allocations: 1.836 MB / 0.7255 GB, free: 41.57 MB / 0.6355 GB
Notification: Performance of [INI] Simplify: time 0.002574/0.4964, allocations: 1.573 MB / 0.7271 GB, free: 40.84 MB / 0.6355 GB
Notification: Performance of [INI] Inline: time 0.003078/0.4995, allocations: 3.776 MB / 0.7308 GB, free: 38.58 MB / 0.6355 GB
Notification: Performance of [INI] Partitioning: time 0.0001712/0.4997, allocations: 74.58 kB / 0.7308 GB, free: 38.54 MB / 0.6355 GB
Notification: Performance of [INI] Cleanup: time 0.001029/0.5007, allocations: 1.219 MB / 0.732 GB, free: 37.78 MB / 0.6355 GB
Notification: Performance of [INI] Causalize: time 0.01203/0.5127, allocations: 7.918 MB / 0.7398 GB, free: 35.96 MB / 0.6355 GB
Notification: Performance of [INI] Tearing: time 0.0006292/0.5133, allocations: 0.489 MB / 0.7402 GB, free: 35.72 MB / 0.6355 GB
Notification: Performance of [SIM] Initialization: time 2.966e-06/0.5133, allocations: 0 / 0.7402 GB, free: 35.72 MB / 0.6355 GB
Notification: Performance of [SIM] DAE-Mode: time 0.0009719/0.5143, allocations: 0.6534 MB / 0.7409 GB, free: 35.43 MB / 0.6355 GB
Notification: Performance of [SIM] Remove Dummies: time 1.227e-05/0.5143, allocations: 3.219 kB / 0.7409 GB, free: 35.43 MB / 0.6355 GB
[/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelica/OMCompiler/Compiler/NBackEnd/Classes/NBackendDAE.mo:1531:9-1531:143:writable] Error: Internal error NBVariable.VariablePointers.getVarSafe failed for $FUN_26.p
Notification: Performance of [SIM] Tearing: time 0.0004533/0.5148, allocations: 403.9 kB / 0.7413 GB, free: 35.27 MB / 0.6355 GB
Notification: Performance of [SIM] Categorize: time 3.848e-05/0.5148, allocations: 5.875 kB / 0.7413 GB, free: 35.27 MB / 0.6355 GB
Notification: Performance of [SIM] Solve: time 0.004611/0.5194, allocations: 2.61 MB / 0.7438 GB, free: 34.35 MB / 0.6355 GB
[/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelica/OMCompiler/Compiler/NBackEnd/Util/NBDifferentiate.mo:823:30-824:7:writable] Error: Internal error NBVariable.getVarPointer failed for $FUN_26.p, because of wrong InstNode (not VAR_NODE). Show lowering errors with -d=failtrace.
"
[Timeout remaining time 659]
[Calling sys.exit(0), Time elapsed: 3.274006334133446]
Failed to read output from testmodel.py, exit status != 0:
0.5267711132764816 0.536400788 0.061787539
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7d961fecc440>