Skip to content

Instantly share code, notes, and snippets.

@silberzwiebel

silberzwiebel/spectrum2.log

Created May 28, 2016 13:20
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save silberzwiebel/cb574809f5f3cc229df17ef76b5eb887 to your computer and use it in GitHub Desktop.
Save silberzwiebel/cb574809f5f3cc229df17ef76b5eb887 to your computer and use it in GitHub Desktop.
Download ZIP
spectrum2: telegram transport not working
Raw
spectrum2.log
2016-05-28 15:07:57,430 INFO XMPPFrontend: Creating component in gateway mode
2016-05-28 15:07:57,475 INFO MySQLBackend: Connecting MySQL server localhost, user spectrum, database telegram_transport, port 0
2016-05-28 15:07:57,525 INFO MySQLBackend: New client character set: utf8
2016-05-28 15:07:57,577 ERROR MySQLBackend: SELECT type, value FROM buddies_settings WHERE user_id=? AND buddy_id=? AND var=?
2016-05-28 15:07:57,629 INFO NetworkPluginServer: Listening on host 127.0.0.1 port 36105
2016-05-28 15:07:57,630 INFO NetworkPluginServer: Starting new backend /usr/bin/spectrum2_libpurple_backend --host 127.0.0.1 --port 36105 --service.backend_id=1 "-j" "telegram.myserver.tld" "/etc/spectrum2/transports/telegram.cfg"
2016-05-28 15:07:57,639 INFO NetworkPluginServer: Tried to spawn first backend with pid 18361
2016-05-28 15:07:57,648 INFO NetworkPluginServer: Backend should now connect to Spectrum2 instance. Spectrum2 won't accept any connection before backend connects
2016-05-28 15:08:00,069 INFO NetworkPluginServer: New backend 0x1548780 connected. Current backend count=1
2016-05-28 15:08:00,075 INFO NetworkPluginServer: PING to 0x1548780 (ID=)
2016-05-28 15:08:00,087 INFO AdminInterface: Message from admin received: 'status'
2016-05-28 15:08:00,101 INFO NetworkPluginServer: Backend 0x1548780 (ID=) disconnected. Current backend count=0
2016-05-28 15:08:00,778 INFO NetworkPluginServer: New backend 0x1548468 connected. Current backend count=1
2016-05-28 15:08:00,779 INFO NetworkPluginServer: PING to 0x1548468 (ID=)
2016-05-28 15:08:00,792 INFO XMPPFrontend: Connecting XMPP server 127.0.0.1 port 5347
2016-05-28 15:08:00,810 INFO Component.RAW: RAW DATA OUT <?xml version="1.0"?><stream:stream xmlns="jabber:component:accept" xmlns:stream="http://etherx.jabber.org/streams" to="telegram.myserver.tld" version="1.0">
2016-05-28 15:08:00,815 INFO Component.RAW: RAW DATA IN <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' xml:lang='en' from='telegram.myserver.tld' id='63fb1737-9f36-4c9a-bb39-91aaa35e7955' xmlns='jabber:component:accept'>
2016-05-28 15:08:00,818 INFO Component.RAW: RAW DATA OUT <handshake>07db9354517f8b4c90c9f970700eff7a9d5b47ff</handshake>
2016-05-28 15:08:00,823 INFO Component.RAW: RAW DATA IN <handshake/>
2016-05-28 15:08:00,824 INFO UserReconnecter: Starting UserReconnecter.
2016-05-28 15:08:00,827 INFO UserReconnecter: All users reconnected, stopping UserReconnecter.
2016-05-28 15:08:00,828 INFO Component: Connected to Frontend server.
2016-05-28 15:08:04,668 INFO NetworkPluginServer: New backend 0x1548d80 connected. Current backend count=2
2016-05-28 15:08:04,670 INFO NetworkPluginServer: PING to 0x1548d80 (ID=)
2016-05-28 15:08:04,674 INFO AdminInterface: Message from admin received: 'registration_fields'
2016-05-28 15:08:04,678 INFO NetworkPluginServer: Backend 0x1548d80 (ID=) disconnected. Current backend count=1
2016-05-28 15:08:17,620 INFO NetworkPluginServer: Sending PING to backends
2016-05-28 15:08:17,621 INFO NetworkPluginServer: PING to 0x1548468 (ID=18361)
2016-05-28 15:08:17,625 INFO NetworkPluginServer: Disconnecting backend 0x1548468 (ID=18361). There are no users.
2016-05-28 15:08:17,630 INFO NetworkPluginServer: Backend 0x1548468 (ID=18361) disconnected. Current backend count=0
2016-05-28 15:08:37,637 INFO NetworkPluginServer: Sending PING to backends
2016-05-28 15:08:43,659 INFO NetworkPluginServer: New backend 0x1548408 connected. Current backend count=1
2016-05-28 15:08:43,660 INFO NetworkPluginServer: PING to 0x1548408 (ID=)
2016-05-28 15:08:43,666 INFO AdminInterface: Message from admin received: 'get_oauth2_url me@myserver.tld +123456789 bla'
2016-05-28 15:08:43,673 INFO NetworkPluginServer: Backend 0x1548408 (ID=) disconnected. Current backend count=0
2016-05-28 15:08:43,777 INFO NetworkPluginServer: New backend 0x1548408 connected. Current backend count=1
2016-05-28 15:08:43,778 INFO NetworkPluginServer: PING to 0x1548408 (ID=)
2016-05-28 15:08:43,783 INFO AdminInterface: Message from admin received: 'register me@myserver.tld +123456789 bla'
2016-05-28 15:08:43,866 INFO Component.RAW: RAW DATA OUT <iq from="telegram.myserver.tld" id="37accd8d-4b22-4fe5-bc96-72e7b53b7ab6" to="me@myserver.tld" type="get"><query xmlns="jabber:iq:roster"/></iq>
2016-05-28 15:08:43,879 INFO NetworkPluginServer: Backend 0x1548408 (ID=) disconnected. Current backend count=0
2016-05-28 15:08:43,892 INFO Component.RAW: RAW DATA IN <iq id='37accd8d-4b22-4fe5-bc96-72e7b53b7ab6' type='error' to='telegram.myserver.tld' from='me@myserver.tld'><error type='cancel'><service-unavailable xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/></error></iq>
2016-05-28 15:08:43,899 INFO Component.RAW: RAW DATA OUT <presence from="telegram.myserver.tld" to="me@myserver.tld" type="subscribe"/>
2016-05-28 15:08:43,947 INFO Component.RAW: RAW DATA IN <presence type='unavailable' to='telegram.myserver.tld' from='me@myserver.tld'/>
2016-05-28 15:08:43,961 INFO Component.RAW: RAW DATA OUT <presence from="telegram.myserver.tld" to="me@myserver.tld" type="unavailable"/>
2016-05-28 15:08:43,988 INFO UserManager: PRESENCE2 telegram.myserver.tld
2016-05-28 15:08:44,606 INFO Component.RAW: RAW DATA IN <presence type='subscribed' to='telegram.myserver.tld' from='me@myserver.tld'/><presence to='telegram.myserver.tld' from='me@myserver.tld/mobile'><c hash='sha-1' ver='jnMLwsvSQnIB/UYSQ4SeSiTmd/s=' node='http://conversations.im' xmlns='http://jabber.org/protocol/caps'/><delay from='myserver.tld' stamp='2016-05-28T08:56:34Z' xmlns='urn:xmpp:delay'/></presence><presence type='probe' to='telegram.myserver.tld' from='me@myserver.tld'/>
2016-05-28 15:08:44,609 INFO UserManager: PRESENCE2 telegram.myserver.tld
2016-05-28 15:08:44,632 INFO Component.RAW: RAW DATA OUT <iq from="telegram.myserver.tld" id="d9c78ac6-26f3-46f3-9aae-45299ef8cdf4" to="me@myserver.tld/mobile" type="get"><query node="http://conversations.im#jnMLwsvSQnIB/UYSQ4SeSiTmd/s=" xmlns="http://jabber.org/protocol/disco#info"/></iq>
2016-05-28 15:08:44,642 INFO Component.RAW: RAW DATA OUT <iq from="telegram.myserver.tld" id="a1c8a74a-8201-471a-aa21-d37b25d0d90c" to="me@myserver.tld/mobile" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>
2016-05-28 15:08:45,335 INFO Component.RAW: RAW DATA OUT <iq from="telegram.myserver.tld" id="c57b60df-09f4-44f9-9c99-0df68725e083" to="me@myserver.tld" type="get"><query xmlns="jabber:iq:roster"/></iq>
2016-05-28 15:08:45,340 INFO User: me@myserver.tld: Created
2016-05-28 15:08:45,421 INFO NetworkPluginServer: Starting new backend /usr/bin/spectrum2_libpurple_backend --host 127.0.0.1 --port 36105 --service.backend_id=1 "-j" "telegram.myserver.tld" "/etc/spectrum2/transports/telegram.cfg"
2016-05-28 15:08:45,426 INFO NetworkPluginServer: There is no backend to handle user me@myserver.tld. Adding him to queue.
2016-05-28 15:08:45,432 INFO User: PRESENCE me@myserver.tld/mobile telegram.myserver.tld
2016-05-28 15:08:45,435 INFO Component.RAW: RAW DATA OUT <iq from="telegram.myserver.tld" id="9d659612-e84f-4445-baca-41999a5fecad" to="me@myserver.tld/mobile" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>
2016-05-28 15:08:45,453 INFO Component.RAW: RAW DATA OUT <presence from="telegram.myserver.tld" to="me@myserver.tld/mobile" type="unavailable"><status>Connecting</status></presence>
2016-05-28 15:08:45,460 INFO UserManager: PRESENCE2 telegram.myserver.tld
2016-05-28 15:08:45,468 INFO Component.RAW: RAW DATA OUT <iq from="telegram.myserver.tld" id="2e37e18f-0866-47e8-95d9-0813bc55732c" to="me@myserver.tld" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>
2016-05-28 15:08:45,469 INFO User: PRESENCE me@myserver.tld telegram.myserver.tld
2016-05-28 15:08:45,472 INFO Component.RAW: RAW DATA OUT <presence from="telegram.myserver.tld" to="me@myserver.tld" type="unavailable"><status>Connecting</status></presence>
2016-05-28 15:08:45,481 INFO Component.RAW: RAW DATA OUT <presence from="telegram.myserver.tld" to="me@myserver.tld/mobile" type="unavailable"><status>Connecting</status></presence>
2016-05-28 15:08:45,492 INFO UserManager: PRESENCE2 telegram.myserver.tld
2016-05-28 15:08:45,493 INFO Component.RAW: RAW DATA IN <iq type='result' to='telegram.myserver.tld' from='me@myserver.tld/mobile' id='d9c78ac6-26f3-46f3-9aae-45299ef8cdf4'><query node='http://conversations.im#jnMLwsvSQnIB/UYSQ4SeSiTmd/s=' xmlns='http://jabber.org/protocol/disco#info'><identity type='phone' name='Conversations 1.12.4' category='client'/><feature var='eu.siacs.conversations.axolotl.devicelist+notify'/><feature var='http://jabber.org/protocol/caps'/><feature var='http://jabber.org/protocol/chatstates'/><feature var='http://jabber.org/protocol/disco#info'/><feature var='http://jabber.org/protocol/muc'/><feature var='http://jabber.org/protocol/nick+notify'/><feature var='jabber:iq:version'/><feature var='jabber:x:conference'/><feature var='urn:xmpp:avatar:metadata+notify'/><feature var='urn:xmpp:chat-markers:0'/><feature var='urn:xmpp:jingle:1'/><feature var='urn:xmpp:jingle:apps:file-transfer:3'/><feature var='urn:xmpp:jingle:transports:ibb:1'/><feature var='urn:xmpp:jingle:transports:s5b:1'/><feature var='urn:xmpp:message-correct:0'/><feature var='urn:xmpp:ping'/><feature var='urn:xmpp:receipts'/></query></iq>
2016-05-28 15:08:45,502 INFO User: me@myserver.tld/mobile: got disco#info
2016-05-28 15:08:45,510 INFO User: me@myserver.tld: Changing legacy network presence to 0
2016-05-28 15:08:45,520 INFO Component.RAW: RAW DATA IN <iq type='result' to='telegram.myserver.tld' from='me@myserver.tld/mobile' id='a1c8a74a-8201-471a-aa21-d37b25d0d90c'><query xmlns='http://jabber.org/protocol/disco#info'><identity type='phone' name='Conversations 1.12.4' category='client'/><feature var='eu.siacs.conversations.axolotl.devicelist+notify'/><feature var='http://jabber.org/protocol/caps'/><feature var='http://jabber.org/protocol/chatstates'/><feature var='http://jabber.org/protocol/disco#info'/><feature var='http://jabber.org/protocol/muc'/><feature var='http://jabber.org/protocol/nick+notify'/><feature var='jabber:iq:version'/><feature var='jabber:x:conference'/><feature var='urn:xmpp:avatar:metadata+notify'/><feature var='urn:xmpp:chat-markers:0'/><feature var='urn:xmpp:jingle:1'/><feature var='urn:xmpp:jingle:apps:file-transfer:3'/><feature var='urn:xmpp:jingle:transports:ibb:1'/><feature var='urn:xmpp:jingle:transports:s5b:1'/><feature var='urn:xmpp:message-correct:0'/><feature var='urn:xmpp:ping'/><feature var='urn:xmpp:receipts'/></query></iq>
2016-05-28 15:08:45,523 INFO User: me@myserver.tld/mobile: got disco#info
2016-05-28 15:08:45,532 INFO Component.RAW: RAW DATA IN <iq id='c57b60df-09f4-44f9-9c99-0df68725e083' type='error' to='telegram.myserver.tld' from='me@myserver.tld'><error type='cancel'><service-unavailable xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/></error></iq>
2016-05-28 15:08:45,534 INFO XMPPRosterManager: me@myserver.tld: This server does not support remote roster protoXEP
2016-05-28 15:08:45,542 INFO Component.RAW: RAW DATA IN <iq id='2e37e18f-0866-47e8-95d9-0813bc55732c' type='result' to='telegram.myserver.tld' from='me@myserver.tld'><query xmlns='http://jabber.org/protocol/disco#info'><feature var='urn:xmpp:mam:0'/><identity type='pep' category='pubsub'/><feature var='http://jabber.org/protocol/pubsub#publish'/></query></iq>
2016-05-28 15:08:45,548 INFO User: me@myserver.tld: got disco#info
2016-05-28 15:08:45,550 INFO Component.RAW: RAW DATA IN <iq type='result' to='telegram.myserver.tld' from='me@myserver.tld/mobile' id='9d659612-e84f-4445-baca-41999a5fecad'><query xmlns='http://jabber.org/protocol/disco#info'><identity type='phone' name='Conversations 1.12.4' category='client'/><feature var='eu.siacs.conversations.axolotl.devicelist+notify'/><feature var='http://jabber.org/protocol/caps'/><feature var='http://jabber.org/protocol/chatstates'/><feature var='http://jabber.org/protocol/disco#info'/><feature var='http://jabber.org/protocol/muc'/><feature var='http://jabber.org/protocol/nick+notify'/><feature var='jabber:iq:version'/><feature var='jabber:x:conference'/><feature var='urn:xmpp:avatar:metadata+notify'/><feature var='urn:xmpp:chat-markers:0'/><feature var='urn:xmpp:jingle:1'/><feature var='urn:xmpp:jingle:apps:file-transfer:3'/><feature var='urn:xmpp:jingle:transports:ibb:1'/><feature var='urn:xmpp:jingle:transports:s5b:1'/><feature var='urn:xmpp:message-correct:0'/><feature var='urn:xmpp:ping'/><feature var='urn:xmpp:receipts'/></query></iq>
2016-05-28 15:08:45,561 INFO User: me@myserver.tld/mobile: got disco#info
2016-05-28 15:08:47,223 INFO NetworkPluginServer: New backend 0x1554a00 connected. Current backend count=1
2016-05-28 15:08:47,224 INFO NetworkPluginServer: PING to 0x1554a00 (ID=)
2016-05-28 15:08:47,235 INFO NetworkPluginServer: Associating me@myserver.tld with this backend
2016-05-28 15:08:57,638 INFO NetworkPluginServer: Sending PING to backends
2016-05-28 15:08:57,639 INFO NetworkPluginServer: PING to 0x1554a00 (ID=18418)
2016-05-28 15:09:17,641 INFO NetworkPluginServer: Sending PING to backends
2016-05-28 15:09:17,642 INFO NetworkPluginServer: Disconnecting backend 0x1554a00 (ID=18418). PING response not received.
2016-05-28 15:09:17,643 INFO NetworkPluginServer: Backend 0x1554a00 (ID=18418) disconnected. Current backend count=0
2016-05-28 15:09:17,644 ERROR NetworkPluginServer: Backend 0x1554a00 (ID=18418) disconnected (probably crashed) with active user me@myserver.tld
2016-05-28 15:09:17,644 INFO User: me@myserver.tld: Disconnecting from legacy network Internal Server Error, please reconnect., trying to reconnect automatically.
2016-05-28 15:09:17,659 INFO NetworkPluginServer: Starting new backend /usr/bin/spectrum2_libpurple_backend --host 127.0.0.1 --port 36105 --service.backend_id=2 "-j" "telegram.myserver.tld" "/etc/spectrum2/transports/telegram.cfg"
2016-05-28 15:09:17,672 INFO NetworkPluginServer: There is no backend to handle user me@myserver.tld. Adding him to queue.
2016-05-28 15:09:19,322 INFO NetworkPluginServer: New backend 0x1554a00 connected. Current backend count=1
2016-05-28 15:09:19,323 INFO NetworkPluginServer: PING to 0x1554a00 (ID=)
2016-05-28 15:09:19,335 INFO NetworkPluginServer: Associating me@myserver.tld with this backend
2016-05-28 15:09:37,686 INFO NetworkPluginServer: Sending PING to backends
2016-05-28 15:09:37,687 INFO NetworkPluginServer: PING to 0x1554a00 (ID=18452)
2016-05-28 15:11:17,764 INFO NetworkPluginServer: Sending PING to backends
2016-05-28 15:11:17,764 INFO NetworkPluginServer: Disconnecting backend 0x1554a00 (ID=18525). PING response not received.
2016-05-28 15:11:17,765 INFO NetworkPluginServer: Backend 0x1554a00 (ID=18525) disconnected. Current backend count=0
2016-05-28 15:11:17,766 ERROR NetworkPluginServer: Backend 0x1554a00 (ID=18525) disconnected (probably crashed) with active user me@myserver.tld
2016-05-28 15:11:17,766 INFO User: me@myserver.tld: Disconnected from legacy network with error Internal Server Error, please reconnect.
2016-05-28 15:11:17,780 INFO Component.RAW: RAW DATA OUT <message from="telegram.myserver.tld" to="me@myserver.tld" type="chat"><body>Internal Server Error, please reconnect.</body><spectrumerror error="16" xmlns="http://spectrum.im/error">CONNECTION_ERROR_NETWORK_ERROR</spectrumerror></message>
2016-05-28 15:11:17,799 INFO UserManager: me@myserver.tld: Disconnecting user
2016-05-28 15:11:17,801 INFO User: me@myserver.tld: Destroying
2016-05-28 15:11:17,802 INFO Component.RAW: RAW DATA OUT <presence from="telegram.myserver.tld" to="me@myserver.tld" type="unavailable"/>
2016-05-28 15:11:37,817 INFO NetworkPluginServer: Sending PING to backends
2016-05-28 15:11:57,818 INFO NetworkPluginServer: Sending PING to backends
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment