Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ExternData_ExternData.Examples.XMLTestXPath.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.001583/0.001583, allocations: 106.1 kB / 19.6 MB, free: 2.34 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.001756/0.001756, allocations: 215.1 kB / 22.89 MB, free: 5.637 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.433/1.433, allocations: 230.5 MB / 256.5 MB, free: 8.078 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ExternData 3.2.0-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ExternData 3.2.0-master/package.mo): time 0.01511/0.01511, allocations: 3.268 MB / 316.2 MB, free: 15.87 MB / 254.1 MB
"
[Timeout remaining time 180]
Using package ExternData with version 3.2.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ExternData 3.2.0-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(ExternData.Examples.XMLTestXPath,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ExternData_ExternData.Examples.XMLTestXPath")
translateModel(ExternData.Examples.XMLTestXPath,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ExternData_ExternData.Examples.XMLTestXPath") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.934e-05/1.934e-05, allocations: 3.406 kB / 440.4 MB, free: 12.13 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(ExternData.Examples.XMLTestXPath): time 0.002237/0.002257, allocations: 1.698 MB / 442.1 MB, free: 10.42 MB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.001595/0.003852, allocations: 1.757 MB / 443.8 MB, free: 8.656 MB / 302.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 5.385e-05/0.003906, allocations: 4 kB / 443.8 MB, free: 8.652 MB / 302.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ExternData 3.2.0-master/package.mo:269:3-299:15:writable] Warning: Pure function 'ExternData.XML2File' contains a call to impure function 'ExternData.Types.ExternXML2File.constructor'.
Notification: Performance of NFTyping.typeComponents: time 0.0003046/0.00421, allocations: 47.77 kB / 443.9 MB, free: 8.605 MB / 302.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0002962/0.004507, allocations: 123.5 kB / 444 MB, free: 8.484 MB / 302.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0001851/0.004692, allocations: 98.58 kB / 444.1 MB, free: 8.391 MB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0001258/0.004817, allocations: 95.73 kB / 444.2 MB, free: 8.297 MB / 302.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 7.483e-05/0.004892, allocations: 15.97 kB / 444.2 MB, free: 8.281 MB / 302.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 5.796e-05/0.00495, allocations: 43.86 kB / 444.3 MB, free: 8.238 MB / 302.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0001197/0.00507, allocations: 47.7 kB / 444.3 MB, free: 8.191 MB / 302.1 MB
Notification: Performance of NFPackage.collectConstants: time 1.51e-05/0.005085, allocations: 4 kB / 444.3 MB, free: 8.188 MB / 302.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0002163/0.005301, allocations: 211.8 kB / 444.5 MB, free: 7.98 MB / 302.1 MB
Notification: Performance of combineBinaries: time 9.661e-05/0.005398, allocations: 170.2 kB / 444.7 MB, free: 7.812 MB / 302.1 MB
Notification: Performance of replaceArrayConstructors: time 3.591e-05/0.005434, allocations: 110.6 kB / 444.8 MB, free: 7.703 MB / 302.1 MB
Notification: Performance of NFVerifyModel.verify: time 2.852e-05/0.005462, allocations: 15.94 kB / 444.8 MB, free: 7.688 MB / 302.1 MB
Notification: Performance of FrontEnd: time 1.124e-05/0.005474, allocations: 0 / 444.8 MB, free: 7.688 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: 12 (8)
 * Number of variables: 12 (12)
Notification: Performance of Bindings: time 0.000445/0.005919, allocations: 0.5454 MB / 445.4 MB, free: 7.02 MB / 302.1 MB
Notification: Performance of FunctionAlias: time 4.978e-05/0.005968, allocations: 51.95 kB / 445.4 MB, free: 6.957 MB / 302.1 MB
Notification: Performance of Early Inline: time 0.0001208/0.006089, allocations: 192.2 kB / 445.6 MB, free: 6.742 MB / 302.1 MB
Notification: Performance of Simplify 1: time 7.5e-05/0.006164, allocations: 93.97 kB / 445.7 MB, free: 6.605 MB / 302.1 MB
Notification: Performance of Alias: time 0.0003872/0.006551, allocations: 256.7 kB / 445.9 MB, free: 6.289 MB / 302.1 MB
Notification: Performance of Simplify 2: time 6.564e-05/0.006617, allocations: 101 kB / 446 MB, free: 6.145 MB / 302.1 MB
Notification: Performance of Remove Stream: time 3.854e-05/0.006656, allocations: 52.41 kB / 446.1 MB, free: 6.066 MB / 302.1 MB
Notification: Performance of Detect States: time 0.000133/0.006789, allocations: 118 kB / 446.2 MB, free: 5.898 MB / 302.1 MB
Notification: Performance of Events: time 9.054e-05/0.006879, allocations: 31.97 kB / 446.2 MB, free: 5.867 MB / 302.1 MB
Notification: Performance of Partitioning: time 0.0001049/0.006984, allocations: 96.19 kB / 446.3 MB, free: 5.758 MB / 302.1 MB
Notification: Performance of Causalize: time 0.0001629/0.007147, allocations: 136.6 kB / 446.5 MB, free: 5.609 MB / 302.1 MB
Notification: Performance of After Index Reduction Inline: time 0.0001006/0.007247, allocations: 155.9 kB / 446.6 MB, free: 5.457 MB / 302.1 MB
Notification: Performance of Simplify: time 0.001962/0.009209, allocations: 392 kB / 447 MB, free: 5.098 MB / 302.1 MB
Notification: Performance of Inline: time 0.0001817/0.009391, allocations: 255.8 kB / 447.2 MB, free: 4.844 MB / 302.1 MB
Notification: Performance of Partitioning: time 1.387e-05/0.009405, allocations: 20.11 kB / 447.3 MB, free: 4.816 MB / 302.1 MB
Notification: Performance of Cleanup: time 6.679e-05/0.009472, allocations: 63.95 kB / 447.3 MB, free: 4.754 MB / 302.1 MB
Notification: Performance of Causalize: time 0.0006909/0.01016, allocations: 0.6587 MB / 448 MB, free: 4.09 MB / 302.1 MB
Notification: Performance of Tearing: time 1.287e-05/0.01018, allocations: 4 kB / 448 MB, free: 4.086 MB / 302.1 MB
Notification: Performance of Initialization: time 3.61e-07/0.01018, allocations: 0 / 448 MB, free: 4.086 MB / 302.1 MB
Notification: Performance of Remove Dummies: time 5.701e-06/0.01018, allocations: 0 / 448 MB, free: 4.086 MB / 302.1 MB
Notification: Performance of Tearing: time 1.413e-06/0.01018, allocations: 0 / 448 MB, free: 4.086 MB / 302.1 MB
Notification: Performance of Categorize: time 1.113e-05/0.01019, allocations: 8 kB / 448 MB, free: 4.078 MB / 302.1 MB
Notification: Performance of Solve: time 0.0001296/0.01032, allocations: 75.95 kB / 448.1 MB, free: 4.004 MB / 302.1 MB
Notification: Performance of Jacobian: time 5.199e-06/0.01033, allocations: 0 / 448.1 MB, free: 4.004 MB / 302.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 0
 * Number of algebraic event partitions: ......... 2
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 0
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 0 (0) {}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 8 (8) {timeTable.a, timeTable.b, timeTable.last, timeTable.nextEvent, timeTable.nextEventScaled, $SEV_1, $SEV_0, $TEV_0}
 * Number of clocks: ............................. 0 (0) {}
 * Number of top-level inputs: ................... 0 (0) {}
Notification: [Simulation] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 5 (scalar:5, array:0, record:0)
 * Number of multi strong components: ............ 1 (algorithm:1, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 12 (scalar:10, array:1, record:1)
 * Number of multi strong components: ............ 1 (algorithm:1, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: Performance of backend: time 3.944e-05/0.01037, allocations: 27.45 kB / 448.1 MB, free: 3.977 MB / 302.1 MB
Notification: Performance of SimCode: time 0.002135/0.0125, allocations: 0.8714 MB / 449 MB, free: 3.215 MB / 302.1 MB
Notification: Performance of Templates: time 0.005121/0.01762, allocations: 2.455 MB / 451.4 MB, free: 0.7695 MB / 302.1 MB
"
[Timeout remaining time 660]
make -j1 -f ExternData_ExternData.Examples.XMLTestXPath.makefile [Timeout 660]
(rm -f ExternData_ExternData.Examples.XMLTestXPath.pipe ; mkfifo ExternData_ExternData.Examples.XMLTestXPath.pipe ; head -c 1048576 < ExternData_ExternData.Examples.XMLTestXPath.pipe >> ../files/ExternData_ExternData.Examples.XMLTestXPath.sim & ./ExternData_ExternData.Examples.XMLTestXPath  -abortSlowSimulation -alarm=480 -lv LOG_STATS > ExternData_ExternData.Examples.XMLTestXPath.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 4.329261779785156]
Failed to read output from testmodel.py, exit status != 0:
0.023924140259623528 0.039521983000000004 0.031067158
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f0ae5a1d780>