Hey Anthony,
back to the problem with switching between waves. I just gave it a try
with the latest WebSocket fix. The problem is still there though. I
found the log when the problem occurs (upon reopening a wave):
Mon Aug 09 08:52:21 GMT+200 2010[SEVERE] LB: Channel Exception:
org.waveprotocol.wave.concurrencycontrol.common.ChannelException:
Exception in view channel, state [ViewChannel id: ch4
waveId: [WaveId ahorstwave.de!w
+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA]
state: CONNECTED],
waveId: [WaveId ahorstwave.de!w
+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA] waveletId: [WaveletId
ahorstwave.de!conv+root], isRecoverable: NOT_RECOVERABLE,
responseCode: INTERNAL_ERROR<br/> Unknown:146:
$fillInStackTrace<br/> Unknown:386:
fillInStackTrace<br/> Unknown:46:
ChannelException_0<br/> Unknown:358:
onUpdate_3<br/> Unknown:305:
$publishDeltaList<br/> Unknown:338:
$receiveWaveletUpdate<br/> Unknown:122:
$handleWaveletUpdate<br/> Unknown:94:
$onMessage<br/> Unknown:84: onMessage<br/
> Unknown:30: anonymous<br/
> Unknown:214: entry0<br/
> Unknown:57: anonymous<br/>
Followed by:
on Aug 09 08:52:21 GMT+200 2010[INFO] LB: Delta channel reset
Mon Aug 09 08:52:22 GMT+200 2010[INFO] closing viewserver channel
org.waveprotocol.wave.examples.fedone.waveclient.common.waveviewservicei...@b2
Mon Aug 09 08:52:22 GMT+200 2010[INFO] LB: [ViewChannel id: ch4
waveId: [WaveId ahorstwave.de!w
+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA]
state: CLOSED] terminated: null
Mon Aug 09 08:52:22 GMT+200 2010[INFO] LB: Mux dropping onClosed from
defunct view
Mon Aug 09 08:52:22 GMT+200 2010[INFO] received JSON message
{"version":1,"sequenceNumber":
13,"messageType":"ProtocolSubmitResponse","messageJson":"{\"1\":
2,\"3\":{\"1\":10,\"2\":\"oXhuYYT/tNiJ05Dfqke3BVpdLW8\u003d\"}}"}
Mon Aug 09 08:52:22 GMT+200 2010[INFO] LB: Ignoring orphaned ack on
disconnected channel
Mon Aug 09 08:52:22 GMT+200 2010[INFO] received JSON message
{"version":1,"sequenceNumber":
1,"messageType":"ProtocolWaveletUpdate","messageJson":"{\"1\":\"wave://
ahorstwave.de/w+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA/conv+root\",
\"2\":[{\"1\":{\"1\":8,\"2\":\"yFnBTBiQDyf76GPENu79C7Slbgk\u003d\"},
\"2\":\"[email protected]\",\"3\":[{\"3\":{\"1\":\"b
+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAE\",\"2\":{\"1\":[{\"3\":{\"1\":
\"body\",\"2\":[]}},{\"3\":{\"1\":\"line\",\"2\":[]}},{\"4\":true},
{\"4\":true}]}}},{\"3\":{\"1\":\"conversation\",\"2\":{\"1\":[{\"5\":
3},{\"3\":{\"1\":\"blip\",\"2\":[{\"1\":\"id\",\"2\":\"b
+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAE\"}]}},{\"4\":true},{\"5\":
1}]}}}],\"4\":[],\"5\":{\"1\":10,\"2\":\"oXhuYYT/
tNiJ05Dfqke3BVpdLW8\u003d\"}}],\"4\":{\"1\":10,\"2\":\"oXhuYYT/
tNiJ05Dfqke3BVpdLW8\u003d\"},\"6\":false}"}
Mon Aug 09 08:52:22 GMT+200 2010[INFO] Wavelet update for
wave://ahorstwave.de/w+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA/conv+root
Mon Aug 09 08:52:22 GMT+200 2010[INFO] handling wavelet update
Mon Aug 09 08:52:22 GMT+200 2010[INFO] Received update for
wave://ahorstwave.de/w+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA/conv+root
Mon Aug 09 08:52:22 GMT+200 2010[INFO] wavelet name decoding
wave://ahorstwave.de/w+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA/conv+root
-> [WaveletName ahorstwave.de!w
+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA ahorstwave.de!conv+root]
Mon Aug 09 08:52:22 GMT+200 2010[INFO] applied wavelet update for
ahorstwave.de!conv+root
Mon Aug 09 08:52:22 GMT+200 2010[INFO] Have a WaveViewServiceImpl for
[WaveId ahorstwave.de!w+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA]
Mon Aug 09 08:52:22 GMT+200 2010[INFO] Publishing deltas: [Delta =
[operations:document op: << body {}; << line {}; >>; >>; on blip b
+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAE by [email protected] at Thu Jan
01 00:59:59 GMT+100 1970 version null isWorthy true, document op: __3;
<< blip { id="b+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAE" }; >>; __1;
on blip conversation by [email protected] at Thu Jan 01 00:59:59 GMT+100
1970 version null isWorthy true]
[version:8]
[signature:distinct:0...@10]
[timestamp: -1]]
Mon Aug 09 08:52:22 GMT+200 2010[INFO] received JSON message
{"version":1,"sequenceNumber":
0,"messageType":"ProtocolWaveletUpdate","messageJson":"{\"1\":\"wave://
ahorstwave.de/indexwave!indexwave/w
+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA\",\"2\":[{\"1\":{\"1\":
5,\"2\":\"\"},\"2\":\"digest-author\",\"3\":[{\"3\":{\"1\":\"digest\",
\"2\":{\"1\":[{\"5\":4},{\"2\":\" \"}]}}}],\"4\":[],\"5\":{\"1\":
6,\"2\":\"\"}}],\"4\":{\"1\":6,\"2\":\"\"},\"6\":false}"}
Mon Aug 09 08:52:22 GMT+200 2010[INFO] Wavelet update for
wave://ahorstwave.de/indexwave!indexwave/w+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA
Mon Aug 09 08:52:22 GMT+200 2010[INFO] handling wavelet update
Mon Aug 09 08:52:22 GMT+200 2010[INFO] Received update for
wave://ahorstwave.de/indexwave!indexwave/w+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA
Mon Aug 09 08:52:22 GMT+200 2010[INFO] wavelet name decoding
wave://ahorstwave.de/indexwave!indexwave/w+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA
-> [WaveletName indexwave!indexwave ahorstwave.de!w
+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA]
Mon Aug 09 08:52:22 GMT+200 2010[INFO] applied wavelet update for
ahorstwave.de!w+YUBhaG9yc3R3YXZlLmRlMTI4MTMzNjcyNzM3NAA
Mon Aug 09 08:52:22 GMT+200 2010[INFO] Have a WaveViewServiceImpl for
[WaveId indexwave!indexwave]
Mon Aug 09 08:52:22 GMT+200 2010[INFO] Publishing deltas: [Delta =
[operations:document op: __4; ++" "; on blip digest by digest-author
at Thu Jan 01 00:59:59 GMT+100 1970 version null isWorthy true]
[version:5]
[signature:distinct:-...@-1]
[timestamp: -1]]
Mon Aug 09 08:52:22 GMT+200 2010[INFO] IndexWave update received
hasDeltas=true hasWaveletSnapshot=false
Mon Aug 09 08:52:22 GMT+200 2010[INFO] WaveList refreshing due to
index update
Mon Aug 09 08:55:50 GMT+200 2010[INFO] YYY saw blip timestamp changed
on conversation ahorstwave.de!conv+root
And finally the error upon editing the reopened wave:
Mon Aug 09 08:55:50 GMT+200 2010[SEVERE] LB: Cannot send to closed
operation channel: Operation Channel State = [state: CLOSED]
Hope this helps.
Regards,
Andreas
On 1 Jul., 15:37, andreas <[email protected]> wrote:
> There actually isn't since it comes direct on module loading. The only
> output is "operation channel state closed".
>
> I use FF3.7 only for HostedMode, the same error in Chrome.
>
> On 1 Jul., 14:39, Anthony Baxter <[email protected]> wrote:
>
>
>
> > I haven't tried FF3.7. Try chrome.
>
> > There's lots of logging, you'll have to look through it (in the
> > client) to figure out why it's closing the channel.
>
> > On Wed, Jun 30, 2010 at 23:35,andreas<[email protected]> wrote:
> > > Does exactly the same when I start the web client with hosted_gwt
> > > target in FF3.7.
>
> > > On 30 Jun., 15:13,andreas<[email protected]> wrote:
> > >> OK, it seems like I messed it up. I'm actually still new to hg and
> > >> hence not familiar with it. I made some changes and sort of was no
> > >> longer able to pull and update so I created a new clone with hopefully
> > >> head revision.
>
> > >> hg sum prints:
> > >> 226:db8332248da5 tip
> > >> branch merge
>
> > >> Now I just setup everything to check the log again for above mentioned
> > >> problem with connection state and I just run into a different one:
>
> > >> Web client is not able to connect. It periodically tries to reconnect
> > >> but fails. Server log for the reconnect attempts is:
>
> > >> 2010-06-30 15:05:12.479:DBUG::REQUEST /socket on
> > >> org.eclipse.jetty.server.nio.selectchannelconnecto...@4bb369
> > >> 2010-06-30
> > >> 15:05:12.479:DBUG::servlet=org.waveprotocol.wave.examples.fedone.rpc.Server
> > >> RpcProvider
> > >> $WaveWebSocketServlet-29469766
> > >> 2010-06-30 15:05:12.479:DBUG::servlet holder=
> > >> org.waveprotocol.wave.examples.fedone.rpc.ServerRpcProvider
> > >> $WaveWebSocketServlet-29469766
> > >> 2010-06-30 15:05:12.479:DBUG::chain= null
> > >> 30.06.2010 15:05:12
> > >> org.waveprotocol.wave.examples.fedone.rpc.ServerRpcProvider
> > >> $WebSocketConnection <init>
> > >> INFO: New websocket connection set up.
> > >> 2010-06-30 15:05:12.488:DBUG::RESPONSE /socket 101
> > >> 2010-06-30 15:05:17.481:DBUG::REQUEST /socket on
> > >> org.eclipse.jetty.server.nio.selectchannelconnecto...@1c79dfc
> > >> 2010-06-30
> > >> 15:05:17.481:DBUG::servlet=org.waveprotocol.wave.examples.fedone.rpc.Server
> > >> RpcProvider
> > >> $WaveWebSocketServlet-29469766
> > >> 2010-06-30 15:05:17.481:DBUG::servlet holder=
> > >> org.waveprotocol.wave.examples.fedone.rpc.ServerRpcProvider
> > >> $WaveWebSocketServlet-29469766
> > >> 2010-06-30 15:05:17.481:DBUG::chain= null
> > >> 30.06.2010 15:05:17
> > >> org.waveprotocol.wave.examples.fedone.rpc.ServerRpcProvider
> > >> $WebSocketConnection <init>
> > >> INFO: New websocket connection set up.
> > >> 2010-06-30 15:05:17.491:DBUG::RESPONSE /socket 101
>
> > >> Before I try messing around with hg again and destroy my clone again,
> > >> do I have to apply the websocket patch?
>
> > >> On 30 Jun., 10:29,andreas<[email protected]> wrote:
>
> > >> > Where exactly? In the web-client log or server?
>
> > >> > I'll have a look...
>
> > >> > On 30 Jun., 03:08, Anthony Baxter <[email protected]> wrote:
>
> > >> > > There should have been an earlier log message showing why the channel
> > >> > > was closed.
>
> > >> > > On Tue, Jun 29, 2010 at
> > >> > > 21:39,andreas<[email protected]> wrote:
> > >> > > > Hey everyone,
>
> > >> > > > I'm experiencing a problem running FedOne (io2010).
>
> > >> > > > In simple web client when I open another Wave besides the first
> > >> > > > opened
> > >> > > > one and edit the reopened first Wave I get the following error
> > >> > > > message:
>
> > >> > > > [SEVERE] LB: Cannot send to closed operation channel: Operation
> > >> > > > Channel State = [state: CLOSED]
>
> > >> > > > The server itself does not log any errors. Sometimes I can reopen
> > >> > > > the
> > >> > > > first Wave and type one letter which is successfully transmitted
> > >> > > > but
> > >> > > > all following edits generate the above error.
>
> > >> > > > After restarting the web client (F5) it works again. At least
> > >> > > > until I
> > >> > > > start switching between Waves again. Then the same behavior: switch
> > >> > > > back to the first open Wave and only first operation successfully
> > >> > > > transmitted...
>
> > >> > > > Has anybody experienced a similar problem?
>
> > >> > > > Running on:
> > >> > > > Ubuntu 10.04
> > >> > > > Java(TM) SE Runtime Environment (build 1.6.0_20-b02)
> > >> > > > Java HotSpot(TM) Server VM (build 16.3-b01, mixed mode)
> > >> > > > Federation is not active
>
> > >> > > > Thanks,
> > >> > > >Andreas
>
> > >> > > > --
> > >> > > > You received this message because you are subscribed to the Google
> > >> > > > Groups "Wave Protocol" group.
> > >> > > > To post to this group, send email to
> > >> > > > [email protected].
> > >> > > > To unsubscribe from this group, send email to
> > >> > > > [email protected].
> > >> > > > For more options, visit this group
> > >> > > > athttp://groups.google.com/group/wave-protocol?hl=en.
>
> > >> > > --
> > >> > > Anthony Baxter, [email protected]
>
> > > --
> > > You received this message because you are subscribed to the Google Groups
> > > "Wave Protocol" group.
> > > To post to this group, send email to [email protected].
> > > To unsubscribe from this group, send email to
> > > [email protected].
> > > For more options, visit this group
> > > athttp://groups.google.com/group/wave-protocol?hl=en.
>
> > --
> > Anthony Baxter, [email protected]
--
You received this message because you are subscribed to the Google Groups "Wave
Protocol" group.
To post to this group, send email to [email protected].
To unsubscribe from this group, send email to
[email protected].
For more options, visit this group at
http://groups.google.com/group/wave-protocol?hl=en.
