[test-results] [Bamboo] Asterisk Testing - Asterisk Trunk - Asterisk CentOS 6 32-Bit 1336 may have hung.

Wed Jun 5 00:12:57 CDT 2013

-------------------------------------------------------------------------------
TESTING-ASTERISKTRUNK-AST18CENTOS32-1336 may have hung.
-------------------------------------------------------------------------------

This build has been running for 274 minutes, which is 151% longer than usual.
It has been 177 minutes since Bamboo received a log message for this build.
Running on agent asterisk-testsuite-32-centos.digium.internal

http://bamboo.asterisk.org/browse/TESTING-ASTERISKTRUNK-AST18CENTOS32/log

--------------
Code Changes
--------------
rmudgett (390440):

>Add BUGBUG comment.

--------------
Last Logs
--------------
04-Jun-2013 20:41:30 |  --> Running test 'tests/redirecting/macro' ...
04-Jun-2013 20:41:30 |  
04-Jun-2013 20:41:30 |  Making sure Asterisk isn't running ...
04-Jun-2013 20:41:30 |  Running ['tests/redirecting/macro/run-test'] ...
04-Jun-2013 20:41:30 |  --> Running test 'tests/redirecting/subroutine' ...
04-Jun-2013 20:41:30 |  
04-Jun-2013 20:41:30 |  Making sure Asterisk isn't running ...
04-Jun-2013 20:41:30 |  Running ['tests/redirecting/subroutine/run-test'] ...
04-Jun-2013 20:41:30 |  --> Running test 'tests/cause_answered_elsewhere' ...
04-Jun-2013 20:41:30 |  
04-Jun-2013 20:41:30 |  Making sure Asterisk isn't running ...
04-Jun-2013 20:41:30 |  Running ['tests/cause_answered_elsewhere/run-test'] ...
04-Jun-2013 20:41:30 |  --> Running test 'tests/bridge/simple_bridge' ...
04-Jun-2013 20:41:30 |  
04-Jun-2013 20:41:30 |  Making sure Asterisk isn't running ...
04-Jun-2013 20:41:30 |  Running ['./lib/python/asterisk/TestRunner.py', 'tests/bridge/simple_bridge'] ...
04-Jun-2013 20:41:30 |  [Jun 04 21:39:53] WARNING[1894]: ami:172 event_callback: Requirement BridgeType: simple_bridge does not match BridgeType: native_rtp in event
04-Jun-2013 20:41:30 |  [Jun 04 21:39:53] WARNING[1894]: ami:172 event_callback: Requirement BridgeType: simple_bridge does not match BridgeType: native_rtp in event
04-Jun-2013 20:41:30 |  [Jun 04 21:39:53] WARNING[1894]: ami:172 event_callback: Requirement BridgeType: simple_bridge does not match BridgeType: native_rtp in event
04-Jun-2013 20:41:30 |  [Jun 04 21:39:59] WARNING[1894]: ami:172 event_callback: Requirement BridgeType: simple_bridge does not match BridgeType: native_rtp in event
04-Jun-2013 20:41:30 |  [Jun 04 21:39:59] WARNING[1894]: ami:172 event_callback: Requirement BridgeType: simple_bridge does not match BridgeType: native_rtp in event
04-Jun-2013 20:41:30 |  [Jun 04 21:39:59] WARNING[1894]: ami:172 event_callback: Requirement BridgeType: simple_bridge does not match BridgeType: native_rtp in event
04-Jun-2013 20:41:30 |  [Jun 04 21:40:00] WARNING[1894]: astcsv:193 match: More than one CSV permutation results in success
04-Jun-2013 20:41:30 |  [Jun 04 21:40:00] WARNING[1894]: astcsv:126 match: CSV MATCH FAILED, different number of records, self=26 and other=22
04-Jun-2013 20:41:30 |  [Jun 04 21:40:00] ERROR[1894]: cel:81 match_cels: Master.csv - CEL results did not meet expectations.  Test Failed.
04-Jun-2013 20:41:30 |  --> Running test 'tests/bridge/disconnect' ...
04-Jun-2013 20:41:30 |  
04-Jun-2013 20:41:30 |  Making sure Asterisk isn't running ...
04-Jun-2013 20:41:30 |  Running ['./lib/python/asterisk/TestRunner.py', 'tests/bridge/disconnect'] ...
04-Jun-2013 20:41:30 |  [Jun 04 21:40:40] WARNING[15373]: TestCase:383 __reactor_timeout: Reactor timeout: '30' seconds
04-Jun-2013 20:41:30 |  [Jun 04 21:40:45] ERROR[15373]: AMI:264 dispatchEvent: Exception in event handler <bound method BridgeTestCase.hangup_callback of <BridgeTestCase.BridgeTestCase object at 0x95677cc>> on event {'connectedlinenum': '4321', 'cause-txt': 'Normal Clearing', 'uniqueid': '1370396404.0', 'accountcode': '', 'channelstate': '6', 'exten': 'alice_disconnect', 'calleridnum': '1234', 'priority': '1', 'connectedlinename': 'Bob', 'context': 'default', 'calleridname': 'Alice', 'privilege': 'call,all', 'cause': '16', 'event': 'Hangup', 'channel': 'SIP/alice-00000000', 'channelstatedesc': 'Up'}: Tried to cancel an already-called event.
04-Jun-2013 20:41:30 |  [Jun 04 21:40:45] WARNING[15373]: astcsv:126 match: CSV MATCH FAILED, different number of records, self=3 and other=1
04-Jun-2013 20:41:30 |  [Jun 04 21:40:45] ERROR[15373]: cdr:83 match_cdrs: Master.csv - CDR results did not meet expectations.  Test Failed.
04-Jun-2013 20:41:30 |  [Jun 04 21:40:45] WARNING[15373]: astcsv:126 match: CSV MATCH FAILED, different number of records, self=39 and other=11
04-Jun-2013 20:41:30 |  [Jun 04 21:40:45] ERROR[15373]: cel:81 match_cels: Master.csv - CEL results did not meet expectations.  Test Failed.
04-Jun-2013 20:41:30 |  [Jun 04 21:40:45] ERROR[15373]: TestCase:491 evaluate_results: Fail token present: BridgeTestCase hasn't raised the flag to indicate completion of all expected calls.
04-Jun-2013 20:41:30 |  --> Running test 'tests/bridge/atxfer_setup' ...
04-Jun-2013 20:41:30 |  
04-Jun-2013 20:41:30 |  Making sure Asterisk isn't running ...
04-Jun-2013 20:41:30 |  Running ['./lib/python/asterisk/TestRunner.py', 'tests/bridge/atxfer_setup'] ...
04-Jun-2013 20:43:01 |  [Jun 04 21:40:47] WARNING[28702]: asterisk:124 errReceived: Asterisk 127.0.0.3 received error: ast_reentrancy_unlock failed: 'Operation not permitted' (1)
04-Jun-2013 20:43:01 |  
04-Jun-2013 20:43:01 |  [Jun 04 21:41:25] WARNING[28702]: TestCase:383 __reactor_timeout: Reactor timeout: '30' seconds
04-Jun-2013 20:43:01 |  [Jun 04 21:41:30] ERROR[28702]: AMI:264 dispatchEvent: Exception in event handler <bound method BridgeTestCase.hangup_callback of <BridgeTestCase.BridgeTestCase object at 0x8ee35ac>> on event {'connectedlinenum': '1234', 'cause-txt': 'Normal Clearing', 'uniqueid': '1370396449.1', 'accountcode': '', 'channelstate': '6', 'exten': '', 'calleridnum': '4321', 'priority': '1', 'connectedlinename': 'Alice', 'context': 'default', 'calleridname': 'Bob', 'privilege': 'call,all', 'cause': '16', 'event': 'Hangup', 'channel': 'SIP/bob-00000001', 'channelstatedesc': 'Up'}: Tried to cancel an already-called event.
04-Jun-2013 20:43:01 |  [Jun 04 21:41:30] WARNING[28702]: astcsv:126 match: CSV MATCH FAILED, different number of records, self=3 and other=1
04-Jun-2013 20:43:01 |  [Jun 04 21:41:30] ERROR[28702]: cdr:83 match_cdrs: Master.csv - CDR results did not meet expectations.  Test Failed.
04-Jun-2013 20:43:01 |  [Jun 04 21:41:30] WARNING[28702]: astcsv:126 match: CSV MATCH FAILED, different number of records, self=39 and other=11
04-Jun-2013 20:43:01 |  [Jun 04 21:41:30] ERROR[28702]: cel:81 match_cels: Master.csv - CEL results did not meet expectations.  Test Failed.
04-Jun-2013 20:43:01 |  [Jun 04 21:41:30] ERROR[28702]: TestCase:491 evaluate_results: Fail token present: BridgeTestCase hasn't raised the flag to indicate completion of all expected calls.
04-Jun-2013 20:43:01 |  --> Running test 'tests/bridge/atxfer_nominal' ...
04-Jun-2013 20:43:01 |  
04-Jun-2013 20:43:01 |  Making sure Asterisk isn't running ...
04-Jun-2013 20:43:01 |  Running ['./lib/python/asterisk/TestRunner.py', 'tests/bridge/atxfer_nominal'] ...
04-Jun-2013 20:43:01 |  [Jun 04 21:41:30] WARNING[10252]: TestRunner:51 find_module: Unable to find module 'yappcap'
04-Jun-2013 20:43:01 |  [Jun 04 21:42:10] WARNING[10252]: TestCase:383 __reactor_timeout: Reactor timeout: '30' seconds
04-Jun-2013 20:43:01 |  [Jun 04 21:42:15] ERROR[10252]: AMI:264 dispatchEvent: Exception in event handler <bound method BridgeTestCase.hangup_callback of <BridgeTestCase.BridgeTestCase object at 0x8d03cec>> on event {'connectedlinenum': '1234', 'cause-txt': 'Normal Clearing', 'uniqueid': '1370396495.1', 'accountcode': '', 'channelstate': '6', 'exten': '', 'calleridnum': '4321', 'priority': '1', 'connectedlinename': 'Alice', 'context': 'default', 'calleridname': 'Bob', 'privilege': 'call,all', 'cause': '16', 'event': 'Hangup', 'channel': 'SIP/bob-00000001', 'channelstatedesc': 'Up'}: Tried to cancel an already-called event.
04-Jun-2013 20:43:01 |  --> Running test 'tests/bridge/blindxfer_setup' ...
04-Jun-2013 20:43:01 |  
04-Jun-2013 20:43:01 |  Making sure Asterisk isn't running ...
04-Jun-2013 20:43:01 |  Running ['./lib/python/asterisk/TestRunner.py', 'tests/bridge/blindxfer_setup'] ...
04-Jun-2013 20:43:01 |  [Jun 04 21:42:55] WARNING[28548]: TestCase:383 __reactor_timeout: Reactor timeout: '30' seconds
04-Jun-2013 20:43:01 |  [Jun 04 21:43:01] ERROR[28548]: AMI:264 dispatchEvent: Exception in event handler <bound method BridgeTestCase.hangup_callback of <BridgeTestCase.BridgeTestCase object at 0x856fa6c>> on event {'connectedlinenum': '4321', 'cause-txt': 'Normal Clearing', 'uniqueid': '1370396540.0', 'accountcode': '', 'channelstate': '6', 'exten': 'alice_blindxfer', 'calleridnum': '1234', 'priority': '1', 'connectedlinename': 'Bob', 'context': 'default', 'calleridname': 'Alice', 'privilege': 'call,all', 'cause': '16', 'event': 'Hangup', 'channel': 'SIP/alice-00000000', 'channelstatedesc': 'Up'}: Tried to cancel an already-called event.
04-Jun-2013 20:43:01 |  [Jun 04 21:43:01] WARNING[28548]: astcsv:126 match: CSV MATCH FAILED, different number of records, self=3 and other=1
04-Jun-2013 20:43:01 |  [Jun 04 21:43:01] ERROR[28548]: cdr:83 match_cdrs: Master.csv - CDR results did not meet expectations.  Test Failed.
04-Jun-2013 20:43:01 |  [Jun 04 21:43:01] WARNING[28548]: astcsv:126 match: CSV MATCH FAILED, different number of records, self=39 and other=11
04-Jun-2013 20:43:01 |  [Jun 04 21:43:01] ERROR[28548]: cel:81 match_cels: Master.csv - CEL results did not meet expectations.  Test Failed.
04-Jun-2013 20:43:01 |  [Jun 04 21:43:01] ERROR[28548]: TestCase:491 evaluate_results: Fail token present: BridgeTestCase hasn't raised the flag to indicate completion of all expected calls.
04-Jun-2013 20:43:01 |  --> Running test 'tests/bridge/blindxfer_nominal' ...
04-Jun-2013 20:43:01 |  
04-Jun-2013 20:43:01 |  Making sure Asterisk isn't running ...
04-Jun-2013 20:45:47 |  Running ['./lib/python/asterisk/TestRunner.py', 'tests/bridge/blindxfer_nominal'] ...
04-Jun-2013 20:45:47 |  [Jun 04 21:43:01] WARNING[10598]: TestRunner:51 find_module: Unable to find module 'yappcap'
04-Jun-2013 20:45:47 |  --> Running test 'tests/bridge/blonde_nominal' ...
04-Jun-2013 20:45:47 |  
04-Jun-2013 20:45:47 |  Making sure Asterisk isn't running ...
04-Jun-2013 20:45:47 |  Running ['./lib/python/asterisk/TestRunner.py', 'tests/bridge/blonde_nominal'] ...
04-Jun-2013 20:45:47 |  [Jun 04 21:43:32] WARNING[27410]: TestRunner:51 find_module: Unable to find module 'yappcap'
04-Jun-2013 20:45:47 |  [Jun 04 21:44:12] WARNING[27410]: TestCase:383 __reactor_timeout: Reactor timeout: '30' seconds
04-Jun-2013 20:45:47 |  [Jun 04 21:44:17] ERROR[27410]: AMI:264 dispatchEvent: Exception in event handler <bound method BridgeTestCase.hangup_callback of <BridgeTestCase.BridgeTestCase object at 0x9691e8c>> on event {'connectedlinenum': '5678', 'cause-txt': 'Normal Clearing', 'uniqueid': '1370396616.1', 'accountcode': '', 'channelstate': '6', 'exten': '', 'calleridnum': '4321', 'priority': '1', 'connectedlinename': 'Charlie', 'context': 'default', 'calleridname': 'Bob', 'privilege': 'call,all', 'cause': '16', 'event': 'Hangup', 'channel': 'SIP/bob-00000001', 'channelstatedesc': 'Up'}: Tried to cancel an already-called event.
04-Jun-2013 20:45:47 |  --> Running test 'tests/bridge/automon' ...
04-Jun-2013 20:45:47 |  
04-Jun-2013 20:45:47 |  Making sure Asterisk isn't running ...
04-Jun-2013 20:45:47 |  Running ['./lib/python/asterisk/TestRunner.py', 'tests/bridge/automon'] ...
04-Jun-2013 20:45:47 |  [Jun 04 21:44:57] WARNING[12445]: TestCase:383 __reactor_timeout: Reactor timeout: '30' seconds
04-Jun-2013 20:45:47 |  [Jun 04 21:45:02] ERROR[12445]: AMI:264 dispatchEvent: Exception in event handler <bound method BridgeTestCase.hangup_callback of <BridgeTestCase.BridgeTestCase object at 0x94c9b2c>> on event {'connectedlinenum': '4321', 'cause-txt': 'Normal Clearing', 'uniqueid': '1370396661.0', 'accountcode': '', 'channelstate': '6', 'exten': 'alice_automon', 'calleridnum': '1234', 'priority': '1', 'connectedlinename': 'Bob', 'context': 'default', 'calleridname': 'Alice', 'privilege': 'call,all', 'cause': '16', 'event': 'Hangup', 'channel': 'SIP/alice-00000000', 'channelstatedesc': 'Up'}: Tried to cancel an already-called event.
04-Jun-2013 20:45:47 |  [Jun 04 21:45:02] WARNING[12445]: astcsv:126 match: CSV MATCH FAILED, different number of records, self=4 and other=1
04-Jun-2013 20:45:47 |  [Jun 04 21:45:02] ERROR[12445]: cdr:83 match_cdrs: Master.csv - CDR results did not meet expectations.  Test Failed.
04-Jun-2013 20:45:47 |  [Jun 04 21:45:02] WARNING[12445]: astcsv:126 match: CSV MATCH FAILED, different number of records, self=52 and other=11
04-Jun-2013 20:45:47 |  [Jun 04 21:45:02] ERROR[12445]: cel:81 match_cels: Master.csv - CEL results did not meet expectations.  Test Failed.
04-Jun-2013 20:45:47 |  [Jun 04 21:45:02] ERROR[12445]: TestCase:491 evaluate_results: Fail token present: BridgeTestCase hasn't raised the flag to indicate completion of all expected calls.
04-Jun-2013 20:45:47 |  --> Running test 'tests/bridge/automixmon' ...
04-Jun-2013 20:45:47 |  
04-Jun-2013 20:45:47 |  Making sure Asterisk isn't running ...
04-Jun-2013 20:45:47 |  Running ['./lib/python/asterisk/TestRunner.py', 'tests/bridge/automixmon'] ...
04-Jun-2013 20:45:47 |  [Jun 04 21:45:42] WARNING[26787]: TestCase:383 __reactor_timeout: Reactor timeout: '30' seconds
04-Jun-2013 20:45:47 |  [Jun 04 21:45:47] ERROR[26787]: AMI:264 dispatchEvent: Exception in event handler <bound method BridgeTestCase.hangup_callback of <BridgeTestCase.BridgeTestCase object at 0xa21394c>> on event {'connectedlinenum': '4321', 'cause-txt': 'Normal Clearing', 'uniqueid': '1370396706.0', 'accountcode': '', 'channelstate': '6', 'exten': 'alice_automixmon', 'calleridnum': '1234', 'priority': '1', 'connectedlinename': 'Bob', 'context': 'default', 'calleridname': 'Alice', 'privilege': 'call,all', 'cause': '16', 'event': 'Hangup', 'channel': 'SIP/alice-00000000', 'channelstatedesc': 'Up'}: Tried to cancel an already-called event.
04-Jun-2013 20:45:47 |  [Jun 04 21:45:47] WARNING[26787]: astcsv:126 match: CSV MATCH FAILED, different number of records, self=4 and other=1
04-Jun-2013 20:45:47 |  [Jun 04 21:45:47] ERROR[26787]: cdr:83 match_cdrs: Master.csv - CDR results did not meet expectations.  Test Failed.
04-Jun-2013 20:45:47 |  [Jun 04 21:45:47] WARNING[26787]: astcsv:126 match: CSV MATCH FAILED, different number of records, self=52 and other=11
04-Jun-2013 20:45:47 |  [Jun 04 21:45:47] ERROR[26787]: cel:81 match_cels: Master.csv - CEL results did not meet expectations.  Test Failed.

--
This message is automatically generated by Atlassian Bamboo
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.digium.com/pipermail/test-results/attachments/20130605/440a9bf8/attachment-0001.htm>