Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersApartRecord.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.001654/0.001654, allocations: 107 kB / 21.24 MB, free: 0.6055 MB / 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.001863/0.001863, allocations: 220.9 kB / 24.53 MB, free: 3.902 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.489/1.489, allocations: 230.6 MB / 258.3 MB, free: 7.789 MB / 206.1 MB
"
[Timeout remaining time 178]
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: writeFile("Modelica.Electrical.Spice3.Examples.InvertersApartRecord.mo", OpenModelica.Scripting.instantiateModel(Modelica.Electrical.Spice3.Examples.InvertersApartRecord))
writeFile("Modelica.Electrical.Spice3.Examples.InvertersApartRecord.mo", OpenModelica.Scripting.instantiateModel(Modelica.Electrical.Spice3.Examples.InvertersApartRecord)) [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.00174/0.00174, allocations: 47.94 kB / 378.5 MB, free: 2.98 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2721/0.2739, allocations: 53.38 MB / 431.9 MB, free: 6.648 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Electrical.Spice3.Examples.InvertersApartRecord): time 0.01423/0.2881, allocations: 12.38 MB / 444.2 MB, free: 10.21 MB / 350.1 MB
Notification: Performance of NFInst.instExpressions: time 0.02585/0.3139, allocations: 17.9 MB / 462.1 MB, free: 8.246 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003621/0.3176, allocations: 320.4 kB / 462.5 MB, free: 7.93 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003896/0.3214, allocations: 1.135 MB / 463.6 MB, free: 6.789 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.008432/0.3299, allocations: 2.37 MB / 466 MB, free: 4.398 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004808/0.3347, allocations: 1.418 MB / 467.4 MB, free: 2.98 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01074/0.3454, allocations: 6.514 MB / 473.9 MB, free: 12.45 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008508/0.3463, allocations: 281.7 kB / 474.2 MB, free: 12.14 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004424/0.3507, allocations: 2.571 MB / 476.7 MB, free: 9.562 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002023/0.3527, allocations: 1.122 MB / 477.9 MB, free: 8.434 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003053/0.353, allocations: 68 kB / 477.9 MB, free: 8.367 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003863/0.3569, allocations: 1.218 MB / 479.1 MB, free: 7.148 MB / 382.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0004819/0.3574, allocations: 325.6 kB / 479.5 MB, free: 6.828 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004397/0.3618, allocations: 1.789 MB / 481.3 MB, free: 5.031 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 3.251e-05/0.3618, allocations: 7.922 kB / 481.3 MB, free: 5.023 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 2.465e-06/0.3618, allocations: 4 kB / 481.3 MB, free: 5.02 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001021/0.3628, allocations: 0.7812 MB / 482 MB, free: 4.234 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 8.595e-05/0.3629, allocations: 47.61 kB / 482.1 MB, free: 4.188 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 3.136e-06/0.3629, allocations: 0 / 482.1 MB, free: 4.188 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0007121/0.3636, allocations: 0.599 MB / 482.7 MB, free: 3.578 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 4.986e-05/0.3637, allocations: 23.78 kB / 482.7 MB, free: 3.555 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 2.514e-06/0.3637, allocations: 0 / 482.7 MB, free: 3.555 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0004178/0.3641, allocations: 328.5 kB / 483 MB, free: 3.23 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 6.406e-05/0.3642, allocations: 19.8 kB / 483.1 MB, free: 3.211 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 1.553e-06/0.3642, allocations: 0 / 483.1 MB, free: 3.211 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0001856/0.3643, allocations: 131.7 kB / 483.2 MB, free: 3.078 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 4.478e-06/0.3643, allocations: 0 / 483.2 MB, free: 3.078 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 2.956e-06/0.3644, allocations: 4 kB / 483.2 MB, free: 3.074 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0001811/0.3645, allocations: 152.3 kB / 483.3 MB, free: 2.926 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 2.25e-05/0.3646, allocations: 15.92 kB / 483.4 MB, free: 2.91 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 8.01e-07/0.3646, allocations: 0 / 483.4 MB, free: 2.91 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0002141/0.3648, allocations: 181.4 kB / 483.5 MB, free: 2.734 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 2.443e-05/0.3648, allocations: 19.8 kB / 483.5 MB, free: 2.715 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 7.42e-07/0.3648, allocations: 0 / 483.5 MB, free: 2.715 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.000809/0.3656, allocations: 0.7329 MB / 484.3 MB, free: 1.984 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 9.126e-05/0.3657, allocations: 51.58 kB / 484.3 MB, free: 1.934 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 3.316e-06/0.3657, allocations: 0 / 484.3 MB, free: 1.934 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0007155/0.3664, allocations: 0.596 MB / 484.9 MB, free: 1.328 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 5.139e-05/0.3665, allocations: 27.77 kB / 485 MB, free: 1.301 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 2.374e-06/0.3665, allocations: 0 / 485 MB, free: 1.301 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.009574/0.376, allocations: 6.169 MB / 491.1 MB, free: 11.13 MB / 398.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.006714/0.3828, allocations: 3.791 MB / 494.9 MB, free: 7.324 MB / 398.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.873e-06/0.3828, allocations: 4 kB / 494.9 MB, free: 7.32 MB / 398.1 MB
Notification: Performance of runFrontEnd: time 2.044e-06/0.3828, allocations: 0 / 494.9 MB, free: 7.32 MB / 398.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001583/0.001583, allocations: 43.92 kB / 495 MB, free: 7.27 MB / 398.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1318/0.1334, allocations: 53.35 MB / 0.5355 GB, free: 1.812 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Electrical.Spice3.Examples.InvertersApartRecord): time 0.239/0.3724, allocations: 12.39 MB / 0.5476 GB, free: 97.32 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.02077/0.3932, allocations: 17.89 MB / 0.565 GB, free: 85.06 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004422/0.3976, allocations: 318.9 kB / 0.5653 GB, free: 84.84 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004663/0.4023, allocations: 1.133 MB / 0.5664 GB, free: 84.3 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01039/0.4126, allocations: 2.368 MB / 0.5688 GB, free: 82.86 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.005969/0.4186, allocations: 1.418 MB / 0.5701 GB, free: 82.15 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01106/0.4297, allocations: 6.526 MB / 0.5765 GB, free: 79.42 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0006698/0.4303, allocations: 278.5 kB / 0.5768 GB, free: 79.23 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004244/0.4346, allocations: 2.568 MB / 0.5793 GB, free: 78.11 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002498/0.4371, allocations: 1.13 MB / 0.5804 GB, free: 77.47 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003259/0.4374, allocations: 67.91 kB / 0.5805 GB, free: 77.47 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.004621/0.442, allocations: 1.21 MB / 0.5816 GB, free: 77.09 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0003758/0.4424, allocations: 329.4 kB / 0.582 GB, free: 76.83 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004115/0.4465, allocations: 1.775 MB / 0.5837 GB, free: 75.75 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 3.18e-05/0.4466, allocations: 25.09 kB / 0.5837 GB, free: 75.73 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 2.255e-06/0.4466, allocations: 0 / 0.5837 GB, free: 75.73 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0006897/0.4473, allocations: 0.7693 MB / 0.5845 GB, free: 75.14 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 6.802e-05/0.4473, allocations: 52.91 kB / 0.5845 GB, free: 75.1 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 3.176e-06/0.4473, allocations: 3.938 kB / 0.5845 GB, free: 75.09 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0004828/0.4478, allocations: 0.5828 MB / 0.5851 GB, free: 74.53 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 3.865e-05/0.4478, allocations: 27.78 kB / 0.5851 GB, free: 74.5 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 2.284e-06/0.4478, allocations: 4 kB / 0.5851 GB, free: 74.5 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0002845/0.4481, allocations: 337.3 kB / 0.5854 GB, free: 74.17 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 5.582e-05/0.4482, allocations: 19.84 kB / 0.5855 GB, free: 74.15 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 1.352e-06/0.4482, allocations: 4 kB / 0.5855 GB, free: 74.14 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0001242/0.4483, allocations: 132.5 kB / 0.5856 GB, free: 74.02 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 5.6e-06/0.4483, allocations: 3.938 kB / 0.5856 GB, free: 74.02 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 6.21e-07/0.4483, allocations: 0 / 0.5856 GB, free: 74.02 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0001193/0.4484, allocations: 152.3 kB / 0.5857 GB, free: 73.87 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 1.517e-05/0.4485, allocations: 7.922 kB / 0.5857 GB, free: 73.86 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 6.81e-07/0.4485, allocations: 0 / 0.5857 GB, free: 73.86 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0001422/0.4486, allocations: 189.4 kB / 0.5859 GB, free: 73.68 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 1.381e-05/0.4486, allocations: 11.91 kB / 0.5859 GB, free: 73.66 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 7.41e-07/0.4486, allocations: 4 kB / 0.5859 GB, free: 73.66 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.000478/0.4491, allocations: 0.7366 MB / 0.5867 GB, free: 72.93 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 6.09e-05/0.4491, allocations: 51.58 kB / 0.5867 GB, free: 72.88 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 2.825e-06/0.4492, allocations: 4 kB / 0.5867 GB, free: 72.87 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.000434/0.4496, allocations: 0.6037 MB / 0.5873 GB, free: 72.26 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 3.841e-05/0.4496, allocations: 27.77 kB / 0.5873 GB, free: 72.23 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 2.294e-06/0.4496, allocations: 0 / 0.5873 GB, free: 72.23 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.006705/0.4563, allocations: 6.169 MB / 0.5934 GB, free: 66.09 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.006329/0.4627, allocations: 3.784 MB / 0.597 GB, free: 62.29 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.663e-06/0.4627, allocations: 0 / 0.597 GB, free: 62.29 MB / 446.1 MB
Notification: Performance of runFrontEnd: time 1.513e-06/0.4627, allocations: 0 / 0.597 GB, free: 62.29 MB / 446.1 MB
"
[Timeout remaining time 659]
(rm -f Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersApartRecord.pipe ; mkfifo Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersApartRecord.pipe ; head -c 1048576 < Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersApartRecord.pipe >> ../files/Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersApartRecord.sim & /usr/bin/julia Modelica.Electrical.Spice3.Examples.InvertersApartRecord_test.jl > Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersApartRecord.pipe 2>&1) [Timeout 504.0]
[Calling os._exit(0), Time elapsed: 36.59803716605529]
Failed to read output from testmodel.py, exit status != 0:
['time', 'mp1.S.v', 'mp2.S.v', 'v.p.v']
0.8535944749601185 -1.0 -1.0
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f0d393daf20>