Project

General

Profile

gdpTestsRhel11Apr2016.txt

Anonymous, 04/11/2017 12:47 PM

Download (14.8 KB)

 
1
To actually run the tests, run make allTests
2
(cd ../lang/python/test; ./run.sh)
3
#### ../../../test/setupAndRun.sh: Running git pull in /home/cxh/src/ptII/vendors/gdp/gdp_router
4
Already up-to-date.
5
#### ../../../test/setupAndRun.sh: Removing /home/cxh/src/ptII/vendors/gdp/gcls and then recreating it.
6
#### ../../../test/setupAndRun.sh: Set up /home/cxh/src/ptII/vendors/gdp/ep_adm_params
7
 To run with these settings from the command line, use:
8
    export EP_PARAM_PATH=/home/cxh/src/ptII/vendors/gdp/ep_adm_params
9
#### ../../../test/setupAndRun.sh: Starting gdp_router
10
Command to start gdp_router (cd /home/cxh/src/ptII/vendors/gdp/gdp_router; python ./src/gdp_router.py -l routerLog.txt) &
11
#### ../../../test/setupAndRun.sh: Starting gdplogd
12
Command to start gdplogd: /home/cxh/src/ptII/vendors/gdp/gdp/gdplogd/gdplogd -F -N terra.EECS.Berkeley.EDU &
13
#### Creating log gdp.runTests.21260
14
Command to create a log: /home/cxh/src/ptII/vendors/gdp/gdp/apps/gcl-create -k none -s terra.EECS.Berkeley.EDU gdp.runTests.21260
15
exiting with status OK
16
Created new GCL KTGuDUkba49aBnHBfsr8gemRHC0JDhQ3mbMd8TL3HWA
17
	on log server terra.EECS.Berkeley.EDU
18
../../../test/setupAndRun.sh: Invoking ../../../test/_internalRunPythonTests.sh gdp.runTests.21260
19
Command: py.test  --logName=gdp.runTests.21260
20
#### py.test -v --logName=gdp.runTests.21260
21
=============================================================== test session starts ===============================================================
22
platform linux2 -- Python 2.7.10, pytest-2.9.2, py-1.4.31, pluggy-0.3.1 -- /usr/local/python/bin/python
23
cachedir: .cache
24
rootdir: /home/cxh/src/ptII/vendors/gdp/gdp/lang/python/test, inifile: 
25
collecting ... collected 4 items
26

    
27
KVStore_test.py::test_KVStore PASSED
28
test_rw.py::test_answer PASSED
29
writer_subscriber2_test.py::test_answer PASSED
30
writer_subscriber_test.py::test_answer PASSED
31

    
32
============================================================ 4 passed in 0.14 seconds =============================================================
33
#### ../../../test/setupAndRun.sh: Stopping gdplogd and gdp_router
34
../../../test/setupAndRun.sh: line 138: kill: (47241) - No such process
35
2017-04-11 12:44:39.336891 -0700 gdplogd: Terminating on signal 15
36

    
37
./run.sh
38
#### ./setupAndRun.sh: Running git pull in /home/cxh/src/ptII/vendors/gdp/gdp_router
39
My GDP routing name = terra.EECS.Berkeley.EDU
40
Already up-to-date.
41
#### ./setupAndRun.sh: Removing /home/cxh/src/ptII/vendors/gdp/gcls and then recreating it.
42
#### ./setupAndRun.sh: Set up /home/cxh/src/ptII/vendors/gdp/ep_adm_params
43
 To run with these settings from the command line, use:
44
    export EP_PARAM_PATH=/home/cxh/src/ptII/vendors/gdp/ep_adm_params
45
#### ./setupAndRun.sh: Starting gdp_router
46
Command to start gdp_router (cd /home/cxh/src/ptII/vendors/gdp/gdp_router; python ./src/gdp_router.py -l routerLog.txt) &
47
#### ./setupAndRun.sh: Starting gdplogd
48
Command to start gdplogd: /home/cxh/src/ptII/vendors/gdp/gdp/gdplogd/gdplogd -F -N terra.EECS.Berkeley.EDU &
49
#### Creating log gdp.runTests.28330
50
Command to create a log: /home/cxh/src/ptII/vendors/gdp/gdp/apps/gcl-create -k none -s terra.EECS.Berkeley.EDU gdp.runTests.28330
51
exiting with status OK
52
Created new GCL rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
53
	on log server terra.EECS.Berkeley.EDU
54
./setupAndRun.sh: Invoking ./_internalRunPythonTests.sh run_tests.py gdp.runTests.28330
55
Command: py.test run_tests.py --logName=gdp.runTests.28330
56
#### py.test -v run_tests.py --logName=gdp.runTests.28330
57
=============================================================== test session starts ===============================================================
58
platform linux2 -- Python 2.7.10, pytest-2.9.2, py-1.4.31, pluggy-0.3.1 -- /usr/local/python/bin/python
59
cachedir: .cache
60
rootdir: /home/cxh/src/ptII/vendors/gdp/gdp/test, inifile: 
61
collecting ... collected 4 items
62

    
63
run_tests.py::test_t_fwd_append FAILED
64
run_tests.py::test_gcl_create PASSED
65
run_tests.py::test_t_multimultiread FAILED
66
run_tests.py::test_t_sub_and_append PASSED
67

    
68
==================================================================== FAILURES =====================================================================
69
________________________________________________________________ test_t_fwd_append ________________________________________________________________
70

    
71
logName = 'gdp.runTests.28330'
72

    
73
    def test_t_fwd_append(logName):
74
>       subprocess.check_call(["./t_fwd_append", "-D *=18", logName, socket.gethostname()])
75

    
76
run_tests.py:42: 
77
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
78

    
79
popenargs = (['./t_fwd_append', '-D *=18', 'gdp.runTests.28330', 'terra.EECS.Berkeley.EDU'],), kwargs = {}, retcode = 1
80
cmd = ['./t_fwd_append', '-D *=18', 'gdp.runTests.28330', 'terra.EECS.Berkeley.EDU']
81

    
82
    def check_call(*popenargs, **kwargs):
83
        """Run command with arguments.  Wait for command to complete.  If
84
        the exit code was zero then return, otherwise raise
85
        CalledProcessError.  The CalledProcessError object will have the
86
        return code in the returncode attribute.
87
    
88
        The arguments are the same as for the Popen constructor.  Example:
89
    
90
        check_call(["ls", "-l"])
91
        """
92
        retcode = call(*popenargs, **kwargs)
93
        if retcode:
94
            cmd = kwargs.get("args")
95
            if cmd is None:
96
                cmd = popenargs[0]
97
>           raise CalledProcessError(retcode, cmd)
98
E           CalledProcessError: Command '['./t_fwd_append', '-D *=18', 'gdp.runTests.28330', 'terra.EECS.Berkeley.EDU']' returned non-zero exit status 1
99

    
100
/usr/local/python/lib/python2.7/subprocess.py:540: CalledProcessError
101
-------------------------------------------------------------- Captured stdout call ---------------------------------------------------------------
102
: Cache exhausted
103
: All for now
104
-------------------------------------------------------------- Captured stderr call ---------------------------------------------------------------
105
[INFO] t_fwd_append: Forwarding append to log gdp.runTests.28330 on server terra.EECS.Berkeley.EDU
106
_gdp_lib_init(NULL)
107
	@(#)libgdp 0.7.2 (2017-04-11 12:43) 00cf2f7b1b4a3d02447767de3faf5b7912f7400d
108
My GDP routing name = j64Q59AQ584C4iKPs3SMN0AzjLhHLxnwmt69FMIMWMk
109
gdp_lib_init: OK
110
Trying Zeroconf:
111
_gdp_chan_open(localhost)
112
Trying localhost
113
_gdp_chan_open: talking to router at localhost:8007
114
_gdp_req_new: allocated new pdu @ 0x7a1160
115
_gdp_pdu_out, fd = 14, basemd = (nil): CMD_ADVERTISE
116
gdp_init: OK
117
[INFO] t_fwd_append: gdp_init: OK
118
[INFO] t_fwd_append: gdp_parse_name(gdp.runTests.28330): OK
119
[INFO] t_fwd_append: gdp_parse_name(terra.EECS.Berkeley.EDU): OK
120
_gdp_req_new: allocated new pdu @ 0x7a1160
121

    
122
>>> _gdp_invoke(req=0x7a1050 rid=1): CMD_OPEN_RA (75), gcl@0x7a1cd0
123
	datum @ 0x79e7d0: recno -1, len 0, no timestamp
124
_gdp_pdu_out, fd = 14, basemd = (nil): CMD_OPEN_RA
125
_gdp_pdu_in(ACK_SUCCESS) => OK
126

    
127
*** Processing ack/nak 128=ACK_SUCCESS from socket 14
128
_gdp_req_dispatch >>> ACK_SUCCESS (128) [gcl->refcnt 2]
129
_gdp_req_dispatch <<< ACK_SUCCESS [gcl->refcnt 2]
130
    OK [200 = 0xc8]
131
<<< _gdp_invoke(0x7a1050 rid=1) CMD_OPEN_RA: OK [200 = 0xc8]
132
Opened GCL rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
133
<<< gdp_gcl_open(rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8): OK
134
[INFO] t_fwd_append: gdp_gcl_open: OK
135
Assertion failed at t_fwd_append:gdp_gcl_ops.c:870: mutex &gcl->mutex (0x7a1cd0) is not locked (should be 47353)
136
Assertion failed at t_fwd_append:gdp_req.c:131: mutex &gcl->mutex (0x7a1cd0) is not locked (should be 47353)
137
_gdp_gcl_fwd_append => ABORT: assertion failure [EPLIB:generic:9]
138
[FATAL] t_fwd_append: Cannot fwd_append:
139
	ABORT: assertion failure [EPLIB:generic:9]
140
	(exiting)
141
______________________________________________________________ test_t_multimultiread ______________________________________________________________
142

    
143
logName = 'gdp.runTests.28330'
144

    
145
    def test_t_multimultiread(logName):
146
>       subprocess.check_call(["./t_multimultiread", "-D gdp.proto=37", logName])
147

    
148
run_tests.py:58: 
149
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
150

    
151
popenargs = (['./t_multimultiread', '-D gdp.proto=37', 'gdp.runTests.28330'],), kwargs = {}, retcode = 1
152
cmd = ['./t_multimultiread', '-D gdp.proto=37', 'gdp.runTests.28330']
153

    
154
    def check_call(*popenargs, **kwargs):
155
        """Run command with arguments.  Wait for command to complete.  If
156
        the exit code was zero then return, otherwise raise
157
        CalledProcessError.  The CalledProcessError object will have the
158
        return code in the returncode attribute.
159
    
160
        The arguments are the same as for the Popen constructor.  Example:
161
    
162
        check_call(["ls", "-l"])
163
        """
164
        retcode = call(*popenargs, **kwargs)
165
        if retcode:
166
            cmd = kwargs.get("args")
167
            if cmd is None:
168
                cmd = popenargs[0]
169
>           raise CalledProcessError(retcode, cmd)
170
E           CalledProcessError: Command '['./t_multimultiread', '-D gdp.proto=37', 'gdp.runTests.28330']' returned non-zero exit status 1
171

    
172
/usr/local/python/lib/python2.7/subprocess.py:540: CalledProcessError
173
-------------------------------------------------------------- Captured stdout call ---------------------------------------------------------------
174
gdp_init: OK
175
gdp_parse_name: OK
176
gdp_gcl_open: OK
177
-------------------------------------------------------------- Captured stderr call ---------------------------------------------------------------
178
_gdp_advertise => OK
179

    
180
>>> _gdp_invoke(req=0x23de050 rid=1): CMD_OPEN_RO (68), gcl@0x23decd0
181
	datum @ 0x23db7d0: recno -1, len 0, no timestamp
182
_gdp_invoke: sending 68, retries=2
183
_gdp_invoke: waiting on 0x23de050
184
_gdp_req_dispatch >>> ACK_SUCCESS (128) [gcl->refcnt 2], req@0x23de050:
185
    nextrec=0, numrecs=0, chan=0x23da3f0
186
    postproc=(nil), sub_cb=(nil), udata=(nil)
187
    state=WAITING, stat=OK
188
    act_ts=2017-04-11 19:44:44.353192000Z
189
    flags=0x540<ALLOC_RID,ON_CHAN_LIST,ROUTEFAIL>
190
    GCL@0x23decd0: rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
191
	iomode = 1, refcnt = 2, reqs = (nil), nrecs = 0
192
	flags = 0xa<INCACHE,INUSE>
193
    cPDU@0x23de160: 
194
	v=3, ttl=15, rsvd1=0, cmd=68=CMD_OPEN_RO
195
	dst=rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
196
	src=dNlw5YMBJk5wc9mqjSH7KTaKoW7x_7HhyhvBtXdbiGY
197
	rid=1, olen=0, chan=(nil), seqno=0
198
	flags=0
199
	datum=0x23db7d0, recno=(none), dbuf=0x23dd8a0, dlen=0
200
		ts=(none)
201
	sigmdalg=0x0, siglen=0, sig=(nil)
202
	total header=80
203
    rPDU@0x7fc660000db0: 
204
	v=3, ttl=15, rsvd1=0, cmd=128=ACK_SUCCESS
205
	dst=dNlw5YMBJk5wc9mqjSH7KTaKoW7x_7HhyhvBtXdbiGY
206
	src=rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
207
	rid=1, olen=8, chan=0x23da3f0, seqno=0
208
	flags=0x2<HAS_RECNO>
209
	datum=0x7fc660000e30, recno=0, dbuf=0x7fc660000eb0, dlen=101
210
		ts=(none)
211
	sigmdalg=0x0, siglen=0, sig=(nil)
212
	total header=96
213

    
214
ack_success: received ACK_SUCCESS for CMD_OPEN_RO
215
_gdp_req_dispatch <<< ACK_SUCCESS [gcl->refcnt 2]
216
    OK [200 = 0xc8]
217
<<< _gdp_invoke(0x23de050 rid=1) CMD_OPEN_RO: OK [200 = 0xc8]
218
  req@0x23de050:
219
    nextrec=0, numrecs=0, chan=0x23da3f0
220
    postproc=(nil), sub_cb=(nil), udata=(nil)
221
    state=ACTIVE, stat=OK [200 = 0xc8]
222
    act_ts=2017-04-11 19:44:44.353299000Z
223
    flags=0x543<ASYNCIO,DONE,ALLOC_RID,ON_CHAN_LIST,ROUTEFAIL>
224
    GCL@0x23decd0: rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
225
	iomode = 1, refcnt = 2, reqs = (nil), nrecs = 0
226
	flags = 0xe<INCACHE,ISLOCKED,INUSE>
227
    cPDU@0x23de160: 
228
	v=3, ttl=15, rsvd1=0, cmd=68=CMD_OPEN_RO
229
	dst=rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
230
	src=dNlw5YMBJk5wc9mqjSH7KTaKoW7x_7HhyhvBtXdbiGY
231
	rid=1, olen=0, chan=(nil), seqno=0
232
	flags=0
233
	datum=(nil)
234
	total header=80
235
    rPDU@0x7fc660000db0: 
236
	v=3, ttl=15, rsvd1=0, cmd=128=ACK_SUCCESS
237
	dst=dNlw5YMBJk5wc9mqjSH7KTaKoW7x_7HhyhvBtXdbiGY
238
	src=rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
239
	rid=1, olen=8, chan=0x23da3f0, seqno=0
240
	flags=0x2<HAS_RECNO>
241
	datum=0x23db7d0, recno=0, dbuf=0x23dd8a0, dlen=101
242
		ts=(none)
243
	sigmdalg=0x0, siglen=0, sig=(nil)
244
	total header=96
245

    
246

    
247
>>> _gdp_invoke(req=0x23de050 rid=2): CMD_MULTIREAD (73), gcl@0x23decd0
248
	datum @ 0x23db7d0: recno 1, len 4, no timestamp
249
00000000 00 00 00 00
250
         ?  ?  ?  ? 
251
_gdp_invoke: sending 73, retries=2
252
_gdp_invoke: waiting on 0x23de050
253
_gdp_req_dispatch >>> NAK_C_NOTFOUND (196) [gcl->refcnt 3], req@0x23de050:
254
    nextrec=0, numrecs=0, chan=0x23da3f0
255
    postproc=(nil), sub_cb=0x405a21, udata=0x1
256
    state=WAITING, stat=OK
257
    act_ts=2017-04-11 19:44:44.354164000Z
258
    flags=0x1d4<CLT_SUBSCR,PERSIST,ALLOC_RID,ON_GCL_LIST,ON_CHAN_LIST>
259
    GCL@0x23decd0: rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
260
	iomode = 1, refcnt = 3, reqs = 0x23de050, nrecs = 0
261
	flags = 0xa<INCACHE,INUSE>
262
    cPDU@0x23de160: 
263
	v=3, ttl=15, rsvd1=0, cmd=73=CMD_MULTIREAD
264
	dst=rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
265
	src=dNlw5YMBJk5wc9mqjSH7KTaKoW7x_7HhyhvBtXdbiGY
266
	rid=2, olen=0, chan=(nil), seqno=0
267
	flags=0
268
	datum=0x23db7d0, recno=1, dbuf=0x23dd8a0, dlen=4
269
		ts=(none)
270
	sigmdalg=0x0, siglen=0, sig=(nil)
271
	total header=88
272
    rPDU@0x7fc660000db0: 
273
	v=3, ttl=15, rsvd1=0, cmd=196=NAK_C_NOTFOUND
274
	dst=dNlw5YMBJk5wc9mqjSH7KTaKoW7x_7HhyhvBtXdbiGY
275
	src=rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
276
	rid=2, olen=0, chan=0x23da3f0, seqno=0
277
	flags=0
278
	datum=0x7fc660000e30, recno=(none), dbuf=0x7fc660000eb0, dlen=0
279
		ts=(none)
280
	sigmdalg=0x0, siglen=0, sig=(nil)
281
	total header=80
282

    
283
nak_client: received NAK_C_NOTFOUND for CMD_MULTIREAD
284
_gdp_req_dispatch <<< NAK_C_NOTFOUND [gcl->refcnt 3]
285
    ERROR: 404 not found [Berkeley:Swarm-GDP:404]
286
<<< _gdp_invoke(0x23de050 rid=2) CMD_MULTIREAD: ERROR: 404 not found [Berkeley:Swarm-GDP:404]
287
  req@0x23de050:
288
    nextrec=0, numrecs=0, chan=0x23da3f0
289
    postproc=(nil), sub_cb=0x405a21, udata=0x1
290
    state=ACTIVE, stat=ERROR: 404 not found [Berkeley:Swarm-GDP:404]
291
    act_ts=2017-04-11 19:44:44.354164000Z
292
    flags=0x1d7<ASYNCIO,DONE,CLT_SUBSCR,PERSIST,ALLOC_RID,ON_GCL_LIST,ON_CHAN_LIST>
293
    GCL@0x23decd0: rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
294
	iomode = 1, refcnt = 3, reqs = 0x23de050, nrecs = 0
295
	flags = 0xe<INCACHE,ISLOCKED,INUSE>
296
    cPDU@0x23de160: 
297
	v=3, ttl=15, rsvd1=0, cmd=73=CMD_MULTIREAD
298
	dst=rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
299
	src=dNlw5YMBJk5wc9mqjSH7KTaKoW7x_7HhyhvBtXdbiGY
300
	rid=2, olen=0, chan=(nil), seqno=0
301
	flags=0
302
	datum=(nil)
303
	total header=80
304
    rPDU@0x7fc660000db0: 
305
	v=3, ttl=15, rsvd1=0, cmd=196=NAK_C_NOTFOUND
306
	dst=dNlw5YMBJk5wc9mqjSH7KTaKoW7x_7HhyhvBtXdbiGY
307
	src=rd5P6yEpoSGde8zl6FtlrRIihcqyPywEvznlfrnQYg8
308
	rid=2, olen=0, chan=0x23da3f0, seqno=0
309
	flags=0
310
	datum=0x23db7d0, recno=(none), dbuf=0x23dd8a0, dlen=0
311
		ts=(none)
312
	sigmdalg=0x0, siglen=0, sig=(nil)
313
	total header=80
314

    
315
[FATAL] t_multimultiread: Cannot multiread:
316
	ERROR: 404 not found [Berkeley:Swarm-GDP:404]
317
	(exiting)
318
======================================================= 2 failed, 2 passed in 5.48 seconds ========================================================
319
#### ./setupAndRun.sh: Stopping gdplogd and gdp_router
320
./setupAndRun.sh: line 138: kill: (47332) - No such process
321
2017-04-11 12:44:49.498355 -0700 gdplogd: Terminating on signal 15
322

    
323
make: [allTests] Error 1 (ignored)
324
My GDP routing name = terra.EECS.Berkeley.EDU