From: John H. <jh...@la...> - 2008-04-27 22:19:54
|
Are you sure your firewall is allowing the traffic to be passed. I had a similar problem and it was my external firewall. -----Original Message----- From: vadito [mailto:va...@gm...] Sent: Thursday, 24 April 2008 10:49 PM To: syn...@li... Subject: Re: [funambol-users] how to use ctp instead of stp? ctp sync still wont work... ----------------------ctp server log ------------------------- [2008-04-24 13:23:27,101] [funambol.ctp.server.session-manager] [TRACE] [CTPServer-processor-58] [/89.223.227.245:2989] New session opened. [2008-04-24 13:23:27,260] [funambol.ctp.server.session-manager] [TRACE] [CTPServer-processor-58] [/89.223.227.245:2989] [10fe07b1-7afe-4fce-a4e0-b471e4b3fb7f] [fwm-00F3F517301110900] Authenticating user 'vadito', deviceId: 'fwm-00F3F517301110900' [2008-04-24 13:23:42,879] [funambol.ctp.server.session-manager] [TRACE] [CTPServer-processor-58] [/89.223.227.245:2989] [10fe07b1-7afe-4fce-a4e0-b471e4b3fb7f] [fwm-00F3F517301110900] Authenticated [2008-04-24 13:23:42,879] [funambol.ctp.server.session-manager] [TRACE] [CTPServer-processor-58] [/89.223.227.245:2989] [10fe07b1-7afe-4fce-a4e0-b471e4b3fb7f] [fwm-00F3F517301110900] Changing status from Connected to Authenticated [2008-04-24 13:23:46,342] [funambol.ctp.server.session-manager] [TRACE] [CTPServer-processor-58] [/89.223.227.245:2989] [10fe07b1-7afe-4fce-a4e0-b471e4b3fb7f] [fwm-00F3F517301110900] Subscribed notification for device fwm-00F3F517301110900 [2008-04-24 13:23:46,343] [funambol.ctp.server.session-manager] [TRACE] [CTPServer-processor-58] [/89.223.227.245:2989] [10fe07b1-7afe-4fce-a4e0-b471e4b3fb7f] [fwm-00F3F517301110900] Device fwm-00F3F517301110900 connected and ready to receive notifications [2008-04-24 13:23:46,343] [funambol.ctp.server.session-manager] [TRACE] [CTPServer-processor-58] [/89.223.227.245:2989] [10fe07b1-7afe-4fce-a4e0-b471e4b3fb7f] [fwm-00F3F517301110900] Changing status from Authenticated to Ready (no change after I get a new mail) -------------------ds server log -------------------------- [2008-04-24 13:32:45,257] [funambol.admin] [TRACE] [] [] [] [] Authenticating admin action. [2008-04-24 13:32:45,284] [funambol.server.notification] [TRACE] [] [] [] [] Try to notify device 'fwm-00F3F517301110900' [2008-04-24 13:32:45,321] [funambol.server.notification.ctp-sender] [INFO] [] [] [] [] Creating JGroups channel with name 'ctp-notification-group' using '/opt/funambol/Funambol/ds-server/config/com/funambol/server/notificatio n/tcp/ctp/jgroups-ctp-notification-group.xml' [2008-04-24 13:32:47,560] [funambol.server.notification.ctp-sender] [INFO] [] [] [] [] Notification channel started [groupName: ctp-notification-group] [2008-04-24 13:32:47,566] [funambol.server.notification] [TRACE] [] [] [] [] Number of sync items:1 [2008-04-24 13:32:47,566] [funambol.server.notification] [TRACE] [] [] [] [] SourceURI: mail [2008-04-24 13:32:47,568] [funambol.server.notification] [INFO] [] [] [] [] Sending message '70F45FF17B5ABD16CE29FA27D2F870C2031800000000000866756E616D626F6C1060000 306046D61696C' to 'com.funambol.framework.server.Sync4jDevice@106433d[deviceId=fwm-00F3F51 7301110900,description=<null>,type=<null>,client_nonce=Zn3)eG:#_TkB65$8, server_nonce=,serverPassword=fnbl,timeZone=<null>,convertDatePolicy=UNSP ECIFIED,charset=UTF-8,address=,msisdn=<null>,notificationBuilder=com/fun ambol/server/notification/DSNotificationBuilder.xml,notificationSender=c om/funambol/server/notification/PushSender.xml]' [2008-04-24 13:32:47,568] [funambol.server.notification.tcp-sender] [INFO] [] [] [] [] Address not configured for device 'fwm-00F3F517301110900'. Unable to send notification via STP [2008-04-24 13:32:47,575] [funambol.server.notification.ctp-sender] [INFO] [] [] [] [] Dispatching notification message... [2008-04-24 13:32:47,577] [funambol.server.notification.ctp-sender] [TRACE] [] [] [] [] Notification message dispatched to all CTP Servers in 1 milliseconds [2008-04-24 13:32:47,577] [funambol.server.notification.ctp-sender] [INFO] [] [] [] [] No valid response received [2008-04-24 13:32:47,577] [funambol.server.notification.tcp-sender] [INFO] [] [] [] [] Notification message not sent ------------------------------ client log -------------------------- 14:27:32 GMT +1:00 [DEBUG] - Socket connection closed by Server, exiting 14:27:32 GMT +1:00 [DEBUG] - Exiting receiveWorker thread 14:27:32 GMT +1:00 [DEBUG] - receiveThread ended with code -1 14:27:32 GMT +1:00 [DEBUG] - heartbeatThread killed 14:27:32 GMT +1:00 [DEBUG] - Restoring CTP connection... 14:27:32 GMT +1:00 [INFO] - Socket connection closed 14:27:32 GMT +1:00 [DEBUG] - Total number of bytes sent = 69 14:27:32 GMT +1:00 [DEBUG] - Total number of bytes received = 30 14:27:32 GMT +1:00 [INFO] - CTP will be restored in 5 seconds... 14:27:37 GMT +1:00 [DEBUG] - Open CTP connection... 14:27:37 GMT +1:00 [INFO] - --- Starting a new SOCKET connection --- 14:27:37 GMT +1:00 [DEBUG] - Find the server address... 14:27:37 GMT +1:00 [INFO] - HOSTNAME = 'domain' PORT = '4745' 14:27:37 GMT +1:00 [DEBUG] - Create SOCKET connection... 14:27:37 GMT +1:00 [DEBUG] - Connecting to 'domain'... 14:27:38 GMT +1:00 [INFO] - Succesfully connected to domain! 14:27:38 GMT +1:00 [DEBUG] - Sending [AUTH] message... 14:27:38 GMT +1:00 [DEBUG] - Creating AUTH msg... 14:27:38 GMT +1:00 [DEBUG] - AUTH: devId='fwm-00F3F517301110900', user='vadito', cred='febUX8RDrCIlZPHB9jjgQA==' 14:27:38 GMT +1:00 [DEBUG] - Sending 61 bytes: 14:27:38 GMT +1:00 [DEBUG] - [00 3b 10 01 01 15 66 77 6d 2d 30 30 46 33 46 35 31 37 33 30 31 31 31 30 39 30 30 02 06 76 61 64 69 74 6f 03 18 66 65 62 55 58 38 52 44 72 43 49 6c 5a 50 48 42 39 6a 6a 67 51 41 3d 3d] 14:27:38 GMT +1:00 [DEBUG] - sendMsg - 61 bytes sent 14:27:38 GMT +1:00 [DEBUG] - Total bytes sent since beginning: 61 14:27:38 GMT +1:00 [INFO] - Waiting for Server message... 14:27:39 GMT +1:00 [DEBUG] - Starting cmdTimeoutWorker thread 14:27:56 GMT +1:00 [DEBUG] - Package received: 22 bytes read (total = 22, expected = 22) 14:27:56 GMT +1:00 [DEBUG] - Message complete 14:27:57 GMT +1:00 [DEBUG] - Received 22 bytes: 14:27:57 GMT +1:00 [DEBUG] - [00 14 10 20 06 10 5a 6e 33 29 65 47 3a 23 5f 54 6b 42 36 35 24 38] 14:27:57 GMT +1:00 [DEBUG] - Total bytes received since beginning: 22 14:27:57 GMT +1:00 [DEBUG] - Parsing msg... 14:27:57 GMT +1:00 [DEBUG] - param found 14:27:57 GMT +1:00 [DEBUG] - status = 0x20 14:27:57 GMT +1:00 [INFO] - Client authenticated successfully! 14:27:57 GMT +1:00 [DEBUG] - New nonce received: 'Wm4zKWVHOiNfVGtCNjUkOA==' 14:27:57 GMT +1:00 [DEBUG] - heartbeatThread killed 14:27:57 GMT +1:00 [DEBUG] - Waiting for the receive thread to finish (timeout = 0 sec)... 14:27:57 GMT +1:00 [DEBUG] - Starting heartbeatWorker threadStarting receiveWorker thread 14:27:57 GMT +1:00 [INFO] - Sending [READY] message...Waiting for Server message... 14:27:58 GMT +1:00 [DEBUG] - Sending 4 bytes: 14:27:58 GMT +1:00 [DEBUG] - [00 02 10 02] 14:27:58 GMT +1:00 [DEBUG] - sendMsg - 4 bytes sent 14:27:58 GMT +1:00 [DEBUG] - Total bytes sent since beginning: 65 14:27:58 GMT +1:00 [DEBUG] - Next ready msg will be sent in 300 seconds... 14:27:58 GMT +1:00 [DEBUG] - Starting cmdTimeoutWorker thread 14:27:58 GMT +1:00 [DEBUG] - Package received: 4 bytes read (total = 4, expected = 4) 14:27:58 GMT +1:00 [DEBUG] - Message complete 14:27:58 GMT +1:00 [DEBUG] - Received 4 bytes: 14:27:58 GMT +1:00 [DEBUG] - [00 02 10 20] 14:27:58 GMT +1:00 [DEBUG] - Total bytes received since beginning: 26 14:27:58 GMT +1:00 [DEBUG] - Parsing msg... 14:27:59 GMT +1:00 [DEBUG] - status = 0x20 14:27:59 GMT +1:00 [INFO] - [OK] received -> back to receive state 14:27:59 GMT +1:00 [INFO] - Waiting for Server message... 14:32:22 GMT +1:00 [DEBUG] - GPRS mutex created. 14:32:22 GMT +1:00 [DEBUG] - Checking internet connection... 14:32:22 GMT +1:00 [DEBUG] - Attempting to connect... 14:32:25 GMT +1:00 [DEBUG] - Internet connection succesfully completed! 14:32:25 GMT +1:00 [DEBUG] - GPRS mutex released. 14:34:54 GMT +1:00 [INFO] - Sending [READY] message... 14:34:54 GMT +1:00 [DEBUG] - Sending 4 bytes: 14:34:54 GMT +1:00 [DEBUG] - [00 02 10 02] 14:34:54 GMT +1:00 [DEBUG] - sendMsg - 4 bytes sent 14:34:54 GMT +1:00 [DEBUG] - Total bytes sent since beginning: 69 14:34:54 GMT +1:00 [DEBUG] - Next ready msg will be sent in 300 seconds...Starting cmdTimeoutWorker thread 14:34:55 GMT +1:00 [DEBUG] - Package received: 4 bytes read (total = 4, expected = 4) 14:34:55 GMT +1:00 [DEBUG] - Message complete 14:34:55 GMT +1:00 [DEBUG] - Received 4 bytes: 14:34:55 GMT +1:00 [DEBUG] - [00 02 10 20] 14:34:55 GMT +1:00 [DEBUG] - Total bytes received since beginning: 30 14:34:55 GMT +1:00 [DEBUG] - Parsing msg... 14:34:55 GMT +1:00 [DEBUG] - status = 0x20 14:34:55 GMT +1:00 [INFO] - [OK] received -> back to receive state 14:34:55 GMT +1:00 [INFO] - Waiting for Server message... 14:37:03 GMT +1:00 [DEBUG] - GPRS mutex created. 14:37:03 GMT +1:00 [DEBUG] - Checking internet connection... 14:37:03 GMT +1:00 [DEBUG] - Attempting to connect... 14:37:06 GMT +1:00 [DEBUG] - Internet connection succesfully completed! 14:37:06 GMT +1:00 [DEBUG] - GPRS mutex released. 14:39:54 GMT +1:00 [INFO] - Sending [READY] message... 14:39:54 GMT +1:00 [DEBUG] - Sending 4 bytes: 14:39:54 GMT +1:00 [DEBUG] - [00 02 10 02] 14:39:54 GMT +1:00 [DEBUG] - sendMsg - 4 bytes sent 14:39:55 GMT +1:00 [DEBUG] - Total bytes sent since beginning: 73 14:39:55 GMT +1:00 [DEBUG] - Next ready msg will be sent in 300 seconds... 14:39:55 GMT +1:00 [DEBUG] - Starting cmdTimeoutWorker thread 14:39:55 GMT +1:00 [DEBUG] - Package received: 4 bytes read (total = 4, expected = 4) 14:39:55 GMT +1:00 [DEBUG] - Message complete 14:39:55 GMT +1:00 [DEBUG] - Received 4 bytes: 14:39:55 GMT +1:00 [DEBUG] - [00 02 10 20] 14:39:55 GMT +1:00 [DEBUG] - Total bytes received since beginning: 34 14:39:55 GMT +1:00 [DEBUG] - Parsing msg... 14:39:56 GMT +1:00 [DEBUG] - status = 0x20 14:39:56 GMT +1:00 [INFO] - [OK] received -> back to receive state 14:39:56 GMT +1:00 [INFO] - Waiting for Server message... 14:40:02 GMT +1:00 [DEBUG] - addressChangeHandler begin 14:40:02 GMT +1:00 [DEBUG] - useSTP = 1, useCTP = 1 14:40:03 GMT +1:00 [DEBUG] - Connected to network, getting IP address... 14:40:03 GMT +1:00 [INFO] - Current IP: 89.223.227.245 14:40:03 GMT +1:00 [DEBUG] - Starting stpWorker thread 14:40:03 GMT +1:00 [DEBUG] - Exiting addressChangeHandler 14:40:03 GMT +1:00 [DEBUG] - Starting ctpWorker thread 14:40:03 GMT +1:00 [INFO] - Waiting for STP notification sync to finish (timeout = 120 sec)... 14:40:12 GMT +1:00 [DEBUG] - Exiting stpWorker thread: response = 421 14:40:12 GMT +1:00 [DEBUG] - STPThread ended with code 421 14:40:12 GMT +1:00 [INFO] - STP failed (code 421), go with CTP 14:40:12 GMT +1:00 [DEBUG] - Open CTP connection... 14:40:12 GMT +1:00 [INFO] - Socket connection closed 14:40:12 GMT +1:00 [ERROR] - SOCKET recv() error 10004: Unknown socket error. 14:40:12 GMT +1:00 [DEBUG] - Exiting receiveWorker thread 14:40:12 GMT +1:00 [DEBUG] - Total number of bytes sent = 0 14:40:12 GMT +1:00 [DEBUG] - Total number of bytes received = 0 14:40:12 GMT +1:00 [INFO] - --- Starting a new SOCKET connection --- 14:40:12 GMT +1:00 [DEBUG] - Find the server address... 14:40:12 GMT +1:00 [INFO] - HOSTNAME = 'domain' PORT = '4745' 14:40:12 GMT +1:00 [DEBUG] - Create SOCKET connection... 14:40:12 GMT +1:00 [DEBUG] - Connecting to 'domain'... 14:40:13 GMT +1:00 [INFO] - Succesfully connected to domain! 14:40:13 GMT +1:00 [DEBUG] - Sending [AUTH] message... 14:40:13 GMT +1:00 [DEBUG] - Creating AUTH msg... 14:40:13 GMT +1:00 [DEBUG] - AUTH: devId='fwm-00F3F517301110900', user='vadito', cred='Ou9gOH7UvAGuhJJUK5dcRw==' 14:40:13 GMT +1:00 [DEBUG] - Sending 61 bytes: 14:40:13 GMT +1:00 [DEBUG] - [00 3b 10 01 01 15 66 77 6d 2d 30 30 46 33 46 35 31 37 33 30 31 31 31 30 39 30 30 02 06 76 61 64 69 74 6f 03 18 4f 75 39 67 4f 48 37 55 76 41 47 75 68 4a 4a 55 4b 35 64 63 52 77 3d 3d] 14:40:13 GMT +1:00 [DEBUG] - sendMsg - 61 bytes sent 14:40:13 GMT +1:00 [DEBUG] - Total bytes sent since beginning: 61 14:40:13 GMT +1:00 [INFO] - Waiting for Server message... 14:40:13 GMT +1:00 [DEBUG] - Starting cmdTimeoutWorker thread 14:40:13 GMT +1:00 [DEBUG] - Package received: 22 bytes read (total = 22, expected = 22) 14:40:13 GMT +1:00 [DEBUG] - Message complete 14:40:13 GMT +1:00 [DEBUG] - Received 22 bytes: 14:40:13 GMT +1:00 [DEBUG] - [00 14 10 20 06 10 2c 29 3d 7c 4f 34 50 60 7e 31 2f 39 20 30 21 26] 14:40:13 GMT +1:00 [DEBUG] - Total bytes received since beginning: 22 14:40:13 GMT +1:00 [DEBUG] - Parsing msg... 14:40:14 GMT +1:00 [DEBUG] - param found 14:40:14 GMT +1:00 [DEBUG] - status = 0x20 14:40:14 GMT +1:00 [INFO] - Client authenticated successfully! 14:40:14 GMT +1:00 [DEBUG] - New nonce received: 'LCk9fE80UGB+MS85IDAhJg==' 14:40:14 GMT +1:00 [DEBUG] - receiveThread killed 14:40:14 GMT +1:00 [DEBUG] - heartbeatThread killed 14:40:14 GMT +1:00 [DEBUG] - Waiting for the receive thread to finish (timeout = 0 sec)... 14:40:14 GMT +1:00 [DEBUG] - Starting heartbeatWorker threadStarting receiveWorker thread 14:40:14 GMT +1:00 [INFO] - Sending [READY] message...Waiting for Server message... 14:40:14 GMT +1:00 [DEBUG] - Sending 4 bytes: 14:40:14 GMT +1:00 [DEBUG] - [00 02 10 02] 14:40:14 GMT +1:00 [DEBUG] - sendMsg - 4 bytes sent 14:40:14 GMT +1:00 [DEBUG] - Total bytes sent since beginning: 65 14:40:14 GMT +1:00 [DEBUG] - Next ready msg will be sent in 300 seconds... 14:40:14 GMT +1:00 [DEBUG] - Starting cmdTimeoutWorker thread 14:40:15 GMT +1:00 [DEBUG] - Package received: 4 bytes read (total = 4, expected = 4) 14:40:15 GMT +1:00 [DEBUG] - Message complete 14:40:15 GMT +1:00 [DEBUG] - Received 4 bytes: 14:40:15 GMT +1:00 [DEBUG] - [00 02 10 20] 14:40:15 GMT +1:00 [DEBUG] - Total bytes received since beginning: 26 14:40:15 GMT +1:00 [DEBUG] - Parsing msg... 14:40:15 GMT +1:00 [DEBUG] - status = 0x20 14:40:15 GMT +1:00 [INFO] - [OK] received -> back to receive state 14:40:15 GMT +1:00 [INFO] - Waiting for Server message... -------------- ports opened on server: ACCEPT tcp -- anywhere anywhere tcp dpt:4745 ACCEPT tcp -- anywhere anywhere tcp dpt:745 ACCEPT udp -- domain anywhere udp dpt:45601 ACCEPT udp -- domain anywhere udp dpt:45101 ACCEPT udp -- domain anywhere udp dpt:44101 ACCEPT udp -- domain anywhere udp dpt:43101 which ports should I open when not theas? The ctp and ds server are running on the same machine... ------------------------------------------------------------------------ - This SF.net email is sponsored by the 2008 JavaOne(SM) Conference Don't miss this year's exciting event. There's still time to save $100. Use priority code J8TL2D2. http://ad.doubleclick.net/clk;198757673;13503038;p?http://java.sun.com/javaone _______________________________________________ Sync4j-users mailing list Syn...@li... https://lists.sourceforge.net/lists/listinfo/sync4j-users -- This message was scanned by ESVA and is believed to be clean. Click here to report this message as spam. http://spamdefender3.lan1.com.au/cgi-bin/learn-msg.cgi?id=25D6F27F08.A5604 |