Server Log

The recognition server writes its logs in the /var/log/cpqd/asr/server directory. The most recent log file is asr-server.log. The oldest files follow the pattern asr-server-<index>.log.gz, where index is a sequential integer. The ASR is configured as explained in the configuration section.

A simplified example of an ASR server log can be found in: numref:server-log-sample and a brief explanation of the messages in :numref:`server-log-desc.

Warning

The log is merely for illustration. Some of the lines have been removed to make it simpler. The log messages displayed depend on how the log and the CPQD ASR have been configured.

Listing 1 ASR server log example
 1
 2
 3
 4
 5
 6
 7
 8
 9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
WARN   [18-03-21 16:21:07.843] [main] b.c.c.a.s.AsrServerApplication [ ] Starting ASR Server
WARN   [18-03-21 16:21:07.845] [main] b.c.c.a.AsrEngine [ ] Loading ASR: /opt/cpqd/asr/engine/lib/libcpqd_asr.so, /opt/cpqd/asr/config/engine/engine.conf
WARN   [18-03-21 16:21:09.913] [main] b.c.c.a.AsrEngine [ ] [0] ASR engine created (3.2.0)
WARN   [18-03-21 16:21:09.913] [main] b.c.c.a.s.AsrServerApplication [ ] Server started after 2 secs

DEBUG  [18-03-21 16:21:20.185] [dw-16] b.c.c.a.s.w.AsrServerEndpoint [ /127.0.0.1:51420] Socket opened
DEBUG  [18-03-21 16:21:20.194] [dw-13] b.c.c.a.e.AsrProtocolEncoder [0 /127.0.0.1:51420] Received message:
ASR 2.3 CREATE_SESSION

INFO   [18-03-21 16:21:20.205] [dw-13] b.c.c.a.s.AsrSessionBean [0 /127.0.0.1:51420] ASR Session created [handle = 201803211612800000]
DEBUG  [18-03-21 16:21:20.215] [dw-13] b.c.c.a.s.AsrServer [0 /127.0.0.1:51420] Sending message ResponseMessage [session-status=IDLE, result=SUCCESS, method=CREATE_SESSION, message=null, errorCode=null, expires=60000, handle=201803211612800000]

DEBUG  [18-03-21 16:21:20.226] [dw-15] b.c.c.a.e.AsrProtocolEncoder [201803211612800000 /127.0.0.1:51420] Received message:
ASR 2.3 START_RECOGNITION
Content-Length: 59
Content-Type: text/uri-list

file:///opt/cpqd/asr/tools/grammar/samples/ptbr/pizza.gram

DEBUG  [18-03-21 16:21:20.494] [dw-15] b.c.c.a.s.AsrSessionBean [201803211612800000 /127.0.0.1:51420] Activating LM: file:///opt/cpqd/asr/tools/grammar/samples/ptbr/pizza.gram
DEBUG  [18-03-21 16:21:20.499] [dw-15] b.c.c.a.s.c.StartRecognitionCommand [201803211612800000 /127.0.0.1:51420] Server is listening
DEBUG  [18-03-21 16:21:20.501] [dw-15] b.c.c.a.s.AsrServer [201803211612800000 /127.0.0.1:51420] Sending message ResponseMessage [session-status=LISTENING, result=SUCCESS, method=START_RECOGNITION, message=null, errorCode=null, expires=60000, handle=201803211612800000]

DEBUG  [18-03-21 16:21:20.505] [dw-17] b.c.c.a.e.AsrProtocolEncoder [201803211612800000 /127.0.0.1:51420] Received message:
ASR 2.3 SEND_AUDIO
LastPacket: false
Content-Length: 8000
Content-Type: application/octet-stream
-- audio content supressed --

DEBUG  [18-03-21 16:21:28.035] [dw-17] b.c.c.a.e.AsrProtocolEncoder [201803211612800000 /127.0.0.1:51420] Received message:
ASR 2.3 SEND_AUDIO
LastPacket: true
Content-Length: 8000
Content-Type: application/octet-stream
-- audio content supressed --

INFO   [18-03-21 16:21:28.155] [Thread-26] b.c.c.a.s.SimpleRecognitionListener [201803211612800000 ] Recognition result: [last] (0) [100] eu quero uma pizza vegetariana
DEBUG  [18-03-21 16:21:28.185] [Thread-26] b.c.c.a.s.AsrServer [201803211612800000 ] Sending message RecognitionResult [result-status=RECOGNIZED, session-status=IDLE, result=RecognitionResult [lastSegment=true, segmentIndex=0, finalResult=true, alternatives=[RecognitionAlternative [text=eu quero uma pizza vegetariana, confidence=100, lm=file:///opt/cpqd/asr/tools/grammar/samples/ptbr/pizza.gram, words=[RecogWord [text=eu, confidence=100, startTime=3.09, endTime=3.21], RecogWord [text=quero, confidence=100, startTime=3.21, endTime=3.4499998], RecogWord [text=uma, confidence=100, startTime=3.4499998, endTime=3.57], RecogWord [text=pizza, confidence=100, startTime=3.57, endTime=3.99], RecogWord [text=vegetariana, confidence=100, startTime=4.020319, endTime=4.83]], interpretations=[pizza_vegetariana]]], recognitionStatus=RECOGNIZED], handle=201803211612800000]

DEBUG  [18-03-21 16:21:28.537] [dw-18] b.c.c.a.e.AsrProtocolEncoder [201803211612800000 /127.0.0.1:51420] Received message:
ASR 2.3 RELEASE_SESSION

INFO   [18-03-21 16:21:28.544] [dw-18] b.c.c.a.s.AsrSessionBean [201803211612800000 /127.0.0.1:51420] Session released [handle = 201803211612800000]
DEBUG  [18-03-21 16:21:28.547] [dw-18] b.c.c.a.s.AsrServer [201803211612800000 /127.0.0.1:51420] Sending message ResponseMessage [session-status=null, result=SUCCESS, method=RELEASE_SESSION, message=null, errorCode=null, expires=null, handle=201803211612800000]
DEBUG  [18-03-21 16:21:30.558] [dw-18] b.c.c.a.s.w.AsrServerEndpoint [ /127.0.0.1:51420] Socket closed because of CloseReason[1000,Close called]

#########################################################
Stopping asr-server at Wed Mar 21 16:21:32 UTC 2018
#########################################################
WARN   [18-03-21 16:21:33.391] [Thread-12] b.c.c.a.s.AsrServerApplication [ ] Server stopped
Table 3 Server log description

Lines

Description

1-4

ASR server startup

6-11

Recognition session created

13-22

Recognition started and LM activated

24-36

Audio sent

38-39

Building recognition results

41-46

Recognition session ended

48-51

ASR server shut down