Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.0.0_Modelica.Electrical.Digital.Examples.Adder4.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001869/0.001869, allocations: 104 kB / 21.25 MB, free: 0.5664 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.002087/0.002087, allocations: 191.7 kB / 24.53 MB, free: 3.852 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.58/1.58, allocations: 225.7 MB / 253.4 MB, free: 12.79 MB / 206.1 MB
"
[Timeout remaining time 178]
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(Modelica.Electrical.Digital.Examples.Adder4,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5021,variableFilter="time|Adder1.Adder1.AND.G2.x|Adder1.Adder1.AND.G2.y|Adder1.Adder1.XOR.G2.x|Adder1.Adder1.XOR.G2.y|Adder1.Adder2.AND.G2.x|Adder1.Adder2.AND.G2.y|Adder1.Adder2.XOR.G2.x|Adder1.Adder2.XOR.G2.y|Adder2.Adder1.AND.G2.x|Adder2.Adder1.AND.G2.y|Adder2.Adder1.XOR.G2.x|Adder2.Adder1.XOR.G2.y|Adder2.Adder2.AND.G2.x|Adder2.Adder2.AND.G2.y|Adder2.Adder2.XOR.G2.x|Adder2.Adder2.XOR.G2.y|Adder3.Adder1.AND.G2.x|Adder3.Adder1.AND.G2.y|Adder3.Adder1.XOR.G2.x|Adder3.Adder1.XOR.G2.y|Adder3.Adder2.AND.G2.x|Adder3.Adder2.AND.G2.y|Adder3.Adder2.XOR.G2.x|Adder3.Adder2.XOR.G2.y|Adder4.Adder1.AND.G2.x|Adder4.Adder1.AND.G2.y|Adder4.Adder1.XOR.G2.x|Adder4.Adder1.XOR.G2.y|Adder4.Adder2.AND.G2.x|Adder4.Adder2.AND.G2.y|Adder4.Adder2.XOR.G2.x|Adder4.Adder2.XOR.G2.y",fileNamePrefix="Modelica_4.0.0_Modelica.Electrical.Digital.Examples.Adder4")
translateModel(Modelica.Electrical.Digital.Examples.Adder4,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5021,variableFilter="time|Adder1.Adder1.AND.G2.x|Adder1.Adder1.AND.G2.y|Adder1.Adder1.XOR.G2.x|Adder1.Adder1.XOR.G2.y|Adder1.Adder2.AND.G2.x|Adder1.Adder2.AND.G2.y|Adder1.Adder2.XOR.G2.x|Adder1.Adder2.XOR.G2.y|Adder2.Adder1.AND.G2.x|Adder2.Adder1.AND.G2.y|Adder2.Adder1.XOR.G2.x|Adder2.Adder1.XOR.G2.y|Adder2.Adder2.AND.G2.x|Adder2.Adder2.AND.G2.y|Adder2.Adder2.XOR.G2.x|Adder2.Adder2.XOR.G2.y|Adder3.Adder1.AND.G2.x|Adder3.Adder1.AND.G2.y|Adder3.Adder1.XOR.G2.x|Adder3.Adder1.XOR.G2.y|Adder3.Adder2.AND.G2.x|Adder3.Adder2.AND.G2.y|Adder3.Adder2.XOR.G2.x|Adder3.Adder2.XOR.G2.y|Adder4.Adder1.AND.G2.x|Adder4.Adder1.AND.G2.y|Adder4.Adder1.XOR.G2.x|Adder4.Adder1.XOR.G2.y|Adder4.Adder2.AND.G2.x|Adder4.Adder2.AND.G2.y|Adder4.Adder2.XOR.G2.x|Adder4.Adder2.XOR.G2.y",fileNamePrefix="Modelica_4.0.0_Modelica.Electrical.Digital.Examples.Adder4") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.057e-05/2.057e-05, allocations: 5.656 kB / 371.7 MB, free: 9.148 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Electrical.Digital.Examples.Adder4): time 0.006814/0.006835, allocations: 4.981 MB / 376.7 MB, free: 4.141 MB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.006052/0.01289, allocations: 2.413 MB / 379.1 MB, free: 1.719 MB / 302.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0006442/0.01353, allocations: 27.75 kB / 379.1 MB, free: 1.691 MB / 302.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0006607/0.01419, allocations: 245.8 kB / 379.4 MB, free: 1.449 MB / 302.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0007955/0.01499, allocations: 325.7 kB / 379.7 MB, free: 1.129 MB / 302.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001987/0.01697, allocations: 0.889 MB / 380.6 MB, free: 248 kB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.2239/0.2409, allocations: 2.825 MB / 383.4 MB, free: 13.59 MB / 302.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001038/0.242, allocations: 474.9 kB / 383.9 MB, free: 13.5 MB / 302.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004446/0.2464, allocations: 3.184 MB / 387.1 MB, free: 13.38 MB / 302.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003121/0.2495, allocations: 1.328 MB / 388.4 MB, free: 13.17 MB / 302.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0009801/0.2505, allocations: 295.5 kB / 388.7 MB, free: 13.17 MB / 302.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0009018/0.2514, allocations: 302.9 kB / 389 MB, free: 13.17 MB / 302.1 MB
Notification: Performance of combineBinaries: time 0.005206/0.2566, allocations: 3.739 MB / 392.7 MB, free: 10.89 MB / 302.1 MB
Notification: Performance of replaceArrayConstructors: time 0.001817/0.2584, allocations: 1.805 MB / 394.5 MB, free: 9.652 MB / 302.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0009125/0.2593, allocations: 140.7 kB / 394.7 MB, free: 9.648 MB / 302.1 MB
Notification: Performance of FrontEnd: time 0.000314/0.2596, allocations: 65.44 kB / 394.7 MB, free: 9.613 MB / 302.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 341 (245)
 * Number of variables: 341 (285)
Notification: Performance of [SIM] Bindings: time 0.01094/0.2706, allocations: 8.511 MB / 403.2 MB, free: 4.488 MB / 302.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.001729/0.2723, allocations: 1.145 MB / 404.4 MB, free: 3.816 MB / 302.1 MB
Notification: Performance of [SIM] Early Inline: time 0.006912/0.2792, allocations: 7.138 MB / 411.5 MB, free: 14.67 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.002742/0.282, allocations: 1.28 MB / 412.8 MB, free: 13.32 MB / 318.1 MB
Notification: Performance of [SIM] Alias: time 0.01165/0.2936, allocations: 7.538 MB / 420.3 MB, free: 5.363 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.001932/0.2956, allocations: 1.167 MB / 421.5 MB, free: 4.145 MB / 318.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0016/0.2972, allocations: 1.151 MB / 422.7 MB, free: 2.977 MB / 318.1 MB
Notification: Performance of [SIM] Detect States: time 0.003517/0.3007, allocations: 2.394 MB / 425.1 MB, free: 0.5547 MB / 318.1 MB
Notification: Performance of [SIM] Events: time 0.002939/0.3036, allocations: 1.96 MB / 427 MB, free: 14.67 MB / 334.1 MB
Notification: Performance of [SIM] Partitioning: time 0.005715/0.3093, allocations: 4.221 MB / 431.2 MB, free: 10.43 MB / 334.1 MB
Notification: Performance of [SIM] Causalize: time 0.006824/0.3161, allocations: 5.201 MB / 436.4 MB, free: 5.23 MB / 334.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.009234/0.3254, allocations: 7.41 MB / 443.8 MB, free: 13.85 MB / 350.1 MB
Notification: Performance of [INI] Simplify: time 0.007651/0.333, allocations: 3.978 MB / 447.8 MB, free: 9.676 MB / 350.1 MB
Notification: Performance of [INI] Inline: time 0.01518/0.3482, allocations: 11.49 MB / 459.3 MB, free: 14.21 MB / 366.1 MB
Notification: Performance of [INI] Partitioning: time 0.0005897/0.3488, allocations: 118.8 kB / 459.4 MB, free: 14.07 MB / 366.1 MB
Notification: Performance of [INI] Cleanup: time 0.007727/0.3565, allocations: 4.214 MB / 463.6 MB, free: 9.867 MB / 366.1 MB
Notification: Performance of [INI] Causalize: time 0.02258/0.3791, allocations: 16.18 MB / 479.8 MB, free: 9.477 MB / 382.1 MB
Notification: Performance of [INI] Tearing: time 0.0001158/0.3792, allocations: 152 kB / 480 MB, free: 9.328 MB / 382.1 MB
Notification: Performance of [SIM] Initialization: time 2.365e-06/0.3792, allocations: 0 / 480 MB, free: 9.328 MB / 382.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.0003252/0.3795, allocations: 110.2 kB / 480.1 MB, free: 9.215 MB / 382.1 MB
Notification: Performance of [SIM] Remove Dummies: time 1.086e-05/0.3796, allocations: 2 kB / 480.1 MB, free: 9.215 MB / 382.1 MB
Notification: Performance of [SIM] Tearing: time 8.067e-05/0.3796, allocations: 132 kB / 480.2 MB, free: 9.086 MB / 382.1 MB
Notification: Performance of [SIM] Categorize: time 5.124e-05/0.3797, allocations: 12 kB / 480.2 MB, free: 9.074 MB / 382.1 MB
Notification: Performance of [SIM] Solve: time 0.006234/0.3859, allocations: 3.021 MB / 483.2 MB, free: 5.816 MB / 382.1 MB
Error: Internal error NFExpression.makeZero failed for: enumeration Modelica.Electrical.Digital.Interfaces.Logic('U', 'X', '0', '1', 'Z', 'W', 'L', 'H', '-')
"
[Timeout remaining time 660]
[Calling sys.exit(0), Time elapsed: 2.822393797978293]
Failed to read output from testmodel.py, exit status != 0:
['time', 'Adder1.Adder1.AND.G2.x', 'Adder1.Adder1.AND.G2.y', 'Adder1.Adder1.XOR.G2.x', 'Adder1.Adder1.XOR.G2.y', 'Adder1.Adder2.AND.G2.x', 'Adder1.Adder2.AND.G2.y', 'Adder1.Adder2.XOR.G2.x', 'Adder1.Adder2.XOR.G2.y', 'Adder2.Adder1.AND.G2.x', 'Adder2.Adder1.AND.G2.y', 'Adder2.Adder1.XOR.G2.x', 'Adder2.Adder1.XOR.G2.y', 'Adder2.Adder2.AND.G2.x', 'Adder2.Adder2.AND.G2.y', 'Adder2.Adder2.XOR.G2.x', 'Adder2.Adder2.XOR.G2.y', 'Adder3.Adder1.AND.G2.x', 'Adder3.Adder1.AND.G2.y', 'Adder3.Adder1.XOR.G2.x', 'Adder3.Adder1.XOR.G2.y', 'Adder3.Adder2.AND.G2.x', 'Adder3.Adder2.AND.G2.y', 'Adder3.Adder2.XOR.G2.x', 'Adder3.Adder2.XOR.G2.y', 'Adder4.Adder1.AND.G2.x', 'Adder4.Adder1.AND.G2.y', 'Adder4.Adder1.XOR.G2.x', 'Adder4.Adder1.XOR.G2.y', 'Adder4.Adder2.AND.G2.x', 'Adder4.Adder2.AND.G2.y', 'Adder4.Adder2.XOR.G2.x', 'Adder4.Adder2.XOR.G2.y']
0.3952962110051885 0.41317980099999996 0.149059832
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fc1acfe6fb0>