Discussion:
Issue 129413 in chromium: Login screen on test image did not detect USB to Ethernet dongle until disconnected and reconnected several times
(too old to reply)
c***@googlecode.com
2012-05-24 19:41:29 UTC
Permalink
Comment #6 on issue 129413 by ***@chromium.org: Login screen on test
image did not detect USB to Ethernet dongle until disconnected and
reconnected several times
http://code.google.com/p/chromium/issues/detail?id=129413

According to no_detect_ethernet.tar.bz2:

chrome_20120523-114715

[1296:1296:10210458:INFO:CONSOLE(4812)] "Gaia frame error = 106", source:
chrome://oobe/login (4812)
[1296:1296:10403355:INFO:CONSOLE(4832)] "ErrorMessageScreen.maybeRetry,
state=0, network=", source: chrome://oobe/login (4832)

First time network error screen is shown as expected, messages1.txt show
that Ethernet was only connected at 11:51:55

2012-05-23T11:51:55.002363-07:00 localhost dhcpcd[2906]: dhcpcd is running
with reduced privileges
2012-05-23T11:51:55.002492-07:00 localhost dhcpcd[2906]: version 5.1.4
starting
2012-05-23T11:51:55.005941-07:00 localhost dhcpcd[2906]: status changed to
Init
2012-05-23T11:51:55.007054-07:00 localhost dhcpcd[2906]: eth0: broadcasting
for a lease
2012-05-23T11:51:55.146285-07:00 localhost dhcpcd[2906]: eth0: offered
172.22.75.226 from 172.24.96.75
2012-05-23T11:51:55.163258-07:00 localhost dhcpcd[2906]: eth0: acknowledged
172.22.75.226 from 172.24.96.75
2012-05-23T11:51:55.184294-07:00 localhost dhcpcd[2906]: eth0: checking for
172.22.75.254
2012-05-23T11:51:55.185442-07:00 localhost dhcpcd[2906]: eth0: gateway
172.22.75.254 found at 00:00:0c:9f:f0:21
2012-05-23T11:51:55.201890-07:00 localhost dhcpcd[2906]: eth0: leased
172.22.75.226 for 1800 seconds
2012-05-23T11:51:55.201979-07:00 localhost dhcpcd[2906]: event BOUND on
interface eth0

chrome logs show at that time errors with DBus calls:

[1296:1296:227102699:ERROR:object_proxy.cc(443)] Failed to call method:
org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible
causes include: the remote application did not send a reply, the message
bus security policy blocked the reply, the reply timeout expired, or the
network connection was broken.
[1296:1296:227102845:ERROR:object_proxy.cc(443)] Failed to call method:
org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible
causes include: the remote application did not send a reply, the message
bus security policy blocked the reply, the reply timeout expired, or the
network connection was broken.
[1296:1296:252094528:ERROR:object_proxy.cc(443)] Failed to call method:
org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible
causes include: the remote application did not send a reply, the message
bus security policy blocked the reply, the reply timeout expired, or the
network connection was broken.
[1296:1296:252094926:ERROR:power_manager_client.cc(386)] Error calling
GetIdleTime
[1296:1296:282099413:ERROR:object_proxy.cc(443)] Failed to call method:
org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible
causes include: the remote application did not send a reply, the message
bus security policy blocked the reply, the reply timeout expired, or the
network connection was broken.
[1296:1296:282099548:ERROR:power_manager_client.cc(386)] Error calling
GetIdleTime
[1296:1296:312108272:ERROR:object_proxy.cc(443)] Failed to call method:
org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible
causes include: the remote application did not send a reply, the message
bus security policy blocked the reply, the reply timeout expired, or the
network connection was broken.
[1296:1296:312108428:ERROR:power_manager_client.cc(386)] Error calling
GetIdleTime
[1296:1296:342114560:ERROR:object_proxy.cc(443)] Failed to call method:
org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible
causes include: the remote application did not send a reply, the message
bus security policy blocked the reply, the reply timeout expired, or the
network connection was broken.
[1296:1296:342114756:ERROR:power_manager_client.cc(386)] Error calling
GetIdleTime
--
Automated mail from issue updates at http://crbug.com/
Subscription options: http://groups.google.com/a/chromium.org/group/chromium-bugs
c***@googlecode.com
2012-05-24 19:59:31 UTC
Permalink
Updates:
Owner: ***@chromium.org
Cc: ***@chromium.org

Comment #7 on issue 129413 by ***@chromium.org: Login screen on test
image did not detect USB to Ethernet dongle until disconnected and
reconnected several times
http://code.google.com/p/chromium/issues/detail?id=129413

This was the reason why Chrome crashed:
[1296:1296:397608473:FATAL:image_transport_factory.cc(377)] Failed to make
compositor shared context current.

Chrome log before that restart doesn't show these lines in logs which are
generated when new network service is reported to Chrome:

----
[1296:1296:9537782:WARNING:network_library_impl_cros.cc(701)] Manager:
Unhandled key: Country
[1296:1296:9537975:WARNING:network_library_impl_cros.cc(701)] Manager:
Unhandled key: HostName
[1296:1296:9538138:WARNING:network_library_impl_cros.cc(701)] Manager:
Unhandled key: PortalCheckInterval
----

Only Chrome log after restart (*115350) show that network was parsed and
Chrome proceeds with GAIA frame load.

I don't see this issue on a same test image I've downloaded but I see
similar error messages:
[1075:1075:532555513:ERROR:object_proxy.cc(443)] Failed to call method:
org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with
signature "" on interface "org.chromium.flimflam.Service" doesn't exist

Summary: I suspect that for some reason Chrome stopped receiving DBus
notifations from flimflam/shill so that when Ethernet is finally connected
there're no notifications.

Also compare this output from logs that Kris provided and my logs with that
same image:

[1296:1296:10210458:INFO:CONSOLE(4812)] "Gaia frame error = 106", source:
chrome://oobe/login (4812)
[1296:1296:10403355:INFO:CONSOLE(4832)] "ErrorMessageScreen.maybeRetry,
state=0, network=", source: chrome://oobe/login (4832)

My logs, when network is connected:
[1075:1075:1398886408:INFO:CONSOLE(4760)] "Hide offline message. state=1,
network=Ethernet, reason=network changed", source: chrome://oobe/login
(4760)

// The Internet connection has been lost.
NET_ERROR(INTERNET_DISCONNECTED, -106)
--
Automated mail from issue updates at http://crbug.com/
Subscription options: http://groups.google.com/a/chromium.org/group/chromium-bugs
c***@googlecode.com
2012-05-24 20:16:36 UTC
Permalink
Comment #8 on issue 129413 by ***@chromium.org: Login screen on test
image did not detect USB to Ethernet dongle until disconnected and
reconnected several times
http://code.google.com/p/chromium/issues/detail?id=129413
doesn't show these lines in logs which are generated when new network
That actually is not correct. But here's how the log looks for me when I
connect / disconnect Ethernet. Notice messages from NetworkLibrary which
are missing in
chrome_20120523-114715 after network state was initially marked as offline
(no network was active at that time). That makes me suspect that network
state is not passed/parsed.

[1084:1084:18600165:INFO:CONSOLE(4286)] "Opening extension:
chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.html, opt_email=",
source: chrome://oobe/login (4286)
[1084:1084:18772324:INFO:CONSOLE(4697)] "Show offline message: state=0,
network=, reason=update, isUnderCaptivePortal=false", source:
chrome://oobe/login (4697)
[1084:1084:18803353:WARNING:network_library_impl_cros.cc(701)] Manager:
Unhandled key: Country
[1084:1084:18803694:WARNING:network_library_impl_cros.cc(701)] Manager:
Unhandled key: HostName
[1084:1084:18803822:WARNING:network_library_impl_cros.cc(701)] Manager:
Unhandled key: PortalCheckInterval
[1084:1084:18811598:INFO:CONSOLE(115)] "#### main.html start", source:
chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.js (115)
[1084:1084:18812578:INFO:CONSOLE(29)] "### Authenticator.initialize",
source: chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.js (29)
[1084:1084:18841103:WARNING:network_library_impl_cros.cc(1059)] Ignoring
remembered network: ethernet_0080c83c640d Type: ethernet
[1084:1084:18880737:INFO:CONSOLE(69)] "Authenticator loading GAIA frame",
source: chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.js (69)
[1084:1084:19970544:INFO:CONSOLE(4812)] "Gaia frame error = 106", source:
chrome://oobe/login (4812)
[1084:1084:19972446:INFO:CONSOLE(4832)] "ErrorMessageScreen.maybeRetry,
state=0, network=", source: chrome://oobe/login (4832)
[1084:1109:21869861:WARNING:backend_impl.cc(1609)] Messed up entry found.
[1084:1109:21870105:WARNING:backend_impl.cc(1895)] Destroying invalid entry.
[1116:1119:32638913:ERROR:gpu_watchdog_thread.cc(198)] The GPU process
hung. Terminating after 10000 ms.
[1084:1084:32685888:ERROR:image_transport_factory.cc(281)] Lost UI
compositor context.
[1084:1084:49345278:WARNING:native_network_parser.cc(360)] Carrier ID not
defined, using code instead:
[1084:1084:49346162:WARNING:native_network_parser.cc(360)] Carrier ID not
defined, using code instead:
[1084:1084:49447871:INFO:CONSOLE(4760)] "Hide offline message. state=1,
network=Ethernet, reason=network changed", source: chrome://oobe/login
(4760)
[1084:1084:49448528:INFO:CONSOLE(4414)] "Reload auth extension frame.",
source: chrome://oobe/login (4414)
[1084:1084:49626230:INFO:CONSOLE(115)] "#### main.html start", source:
chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.js (115)
[1084:1084:49626468:INFO:CONSOLE(29)] "### Authenticator.initialize",
source: chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.js (29)
[1084:1084:49627226:INFO:CONSOLE(69)] "Authenticator loading GAIA frame",
source: chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.js (69)
[1084:1084:51103828:INFO:CONSOLE(88)] "#### Authenticator.onMessage:
method=clearOldAttempts", source:
chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.js (88)
[1084:1084:51104170:INFO:CONSOLE(83)] "### Authenticator.onLoginUILoaded.",
source: chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.js (83)
[1084:1084:51104371:INFO:CONSOLE(4349)] "GaiaSigninScreen.onMessage_:
method=loginUILoaded", source: chrome://oobe/login (4349)
[2381:2520:60540131:ERROR:gpu_watchdog_thread.cc(198)] The GPU process
hung. Terminating after 10000 ms.
[1084:1084:60575334:ERROR:image_transport_factory.cc(281)] Lost UI
compositor context.
[1084:1084:85079140:WARNING:network_library_impl_base.cc(477)] Device path
not found: /device/eth0
[1084:1084:85079773:WARNING:network_library_impl_base.cc(477)] Device path
not found: /device/eth0
[1084:1084:85686328:WARNING:native_network_parser.cc(360)] Carrier ID not
defined, using code instead:
[1084:1084:85687587:WARNING:native_network_parser.cc(360)] Carrier ID not
defined, using code instead:
[1084:1084:93144053:WARNING:network_library_impl_base.cc(477)] Device path
not found: /device/eth0
[1084:1084:93163469:ERROR:object_proxy.cc(443)] Failed to call method:
org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with
signature "" on interface "org.chromium.flimflam.Device" doesn't exist

[1084:1084:93163632:WARNING:network_library_impl_base.cc(1586)] Attempt to
delete non-existent device: /device/eth0
[1084:1084:93166080:WARNING:native_network_parser.cc(360)] Carrier ID not
defined, using code instead:
[1084:1084:93226569:INFO:CONSOLE(4697)] "Show offline message: state=0,
network=, reason=network changed, isUnderCaptivePortal=false", source:
chrome://oobe/login (4697)
[1084:1084:93250467:WARNING:network_library_impl_cros.cc(701)] Manager:
Unhandled key: Country
[1084:1084:93251053:WARNING:network_library_impl_cros.cc(701)] Manager:
Unhandled key: HostName
[1084:1084:93251311:WARNING:network_library_impl_cros.cc(701)] Manager:
Unhandled key: PortalCheckInterval
[1084:1084:93264176:WARNING:native_network_parser.cc(360)] Carrier ID not
defined, using code instead:
[1084:1084:93298380:WARNING:network_library_impl_cros.cc(1059)] Ignoring
remembered network: ethernet_0080c83c640d Type: ethernet
[1084:1084:100779407:WARNING:network_library_impl_base.cc(477)] Device path
not found: /device/eth0
[1084:1084:100788398:WARNING:native_network_parser.cc(360)] Carrier ID not
defined, using code instead:
[1084:1084:102691601:INFO:CONSOLE(4697)] "Show offline message: state=0,
network=Ethernet, reason=network changed, isUnderCaptivePortal=false",
source: chrome://oobe/login (4697)
[1084:1084:102725156:WARNING:network_library_impl_cros.cc(1059)] Ignoring
remembered network: ethernet_0080c83c640d Type: ethernet
[1084:1084:102854750:INFO:CONSOLE(4760)] "Hide offline message. state=1,
network=Ethernet, reason=network changed", source: chrome://oobe/login
(4760)
[1084:1084:102856056:INFO:CONSOLE(4414)] "Reload auth extension frame.",
source: chrome://oobe/login (4414)
[1084:1084:102895744:INFO:CONSOLE(115)] "#### main.html start", source:
chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.js (115)
[1084:1084:102896065:INFO:CONSOLE(29)] "### Authenticator.initialize",
source: chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.js (29)
[1084:1084:102968293:INFO:CONSOLE(69)] "Authenticator loading GAIA frame",
source: chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.js (69)
[1084:1084:103247125:INFO:CONSOLE(88)] "#### Authenticator.onMessage:
method=clearOldAttempts", source:
chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.js (88)
[1084:1084:103261370:INFO:CONSOLE(83)] "###
Authenticator.onLoginUILoaded.", source:
chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/main.js (83)
[1084:1084:103261812:INFO:CONSOLE(4349)] "GaiaSigninScreen.onMessage_:
method=loginUILoaded", source: chrome://oobe/login (4349)
[2544:3001:106514812:ERROR:gpu_watchdog_thread.cc(198)] The GPU process
hung. Terminating after 10000 ms.
[1084:1084:106546764:ERROR:image_transport_factory.cc(281)] Lost UI
compositor context.
[1084:1084:132387991:WARNING:network_library_impl_base.cc(477)] Device path
not found: /device/eth0
[1084:1084:132410672:WARNING:network_library_impl_base.cc(477)] Device path
not found: /device/eth0
[1084:1084:132437821:WARNING:native_network_parser.cc(360)] Carrier ID not
defined, using code instead:
[1084:1084:132438970:WARNING:native_network_parser.cc(360)] Carrier ID not
defined, using code instead:
--
Automated mail from issue updates at http://crbug.com/
Subscription options: http://groups.google.com/a/chromium.org/group/chromium-bugs
c***@googlecode.com
2012-05-24 21:53:50 UTC
Permalink
Comment #9 on issue 129413 by ***@chromium.org: Login screen on test
image did not detect USB to Ethernet dongle until disconnected and
reconnected several times
http://code.google.com/p/chromium/issues/detail?id=129413

The crash in comment #7 I believe is a separate bug:
[1296:1296:397608473:FATAL:image_transport_factory.cc(377)] Failed to make
compositor shared context current.

The unhandled key messages can be ignored.

The relevant NetworkLibrary WARNING here is:
WARNING:network_library_impl_base.cc(477)] Device path not found:
/device/eth0

This is in FindNetworkDeviceByPath(), probably getting called from
UpdateNetworkDeviceStatus.
That would indicate that shill is sending us an update for /device/eth0
without sending (or before sending) a Manager update with /device/eth0 in
the list of devices.

Can we run the test with shill disabled (crosh> shill_manager off) so we
can establish whether this is a change in behavior with shill, or a problem
with NetworkLibrary and/or the new dbus code?
--
Automated mail from issue updates at http://crbug.com/
Subscription options: http://groups.google.com/a/chromium.org/group/chromium-bugs
c***@googlecode.com
2012-05-24 22:38:52 UTC
Permalink
Updates:
Cc: ***@chromium.org

Comment #10 on issue 129413 by ***@chromium.org: Login screen on test
image did not detect USB to Ethernet dongle until disconnected and
reconnected several times
http://code.google.com/p/chromium/issues/detail?id=129413

(No comment was entered for this change.)
--
Automated mail from issue updates at http://crbug.com/
Subscription options: http://groups.google.com/a/chromium.org/group/chromium-bugs
c***@googlecode.com
2012-05-25 11:38:35 UTC
Permalink
Comment #11 on issue 129413 by ***@chromium.org: Login screen on test
image did not detect USB to Ethernet dongle until disconnected and
reconnected several times
http://code.google.com/p/chromium/issues/detail?id=129413

Does not reproduce on my developer build (Chromium 21.0.1152.0, Platform
2350.0.2012_05_25_2021)
It shows "Ethernet" about 3 seconds after plugging the device.

Is this still happening?
--
Automated mail from issue updates at http://crbug.com/
Subscription options: http://groups.google.com/a/chromium.org/group/chromium-bugs
c***@googlecode.com
2012-05-25 12:28:11 UTC
Permalink
Comment #12 on issue 129413 by ***@chromium.org: Login screen on test
image did not detect USB to Ethernet dongle until disconnected and
reconnected several times
http://code.google.com/p/chromium/issues/detail?id=129413

Steven, these lines:

The relevant NetworkLibrary WARNING here is:
WARNING:network_library_impl_base.cc(477)] Device path not found:
/device/eth0

are actually from the log I've obtained with the same build but where issue
is NOT reproducing.
Post by c***@googlecode.com
Is this still happening?
I couldn't reproduce that as well.
--
Automated mail from issue updates at http://crbug.com/
Subscription options: http://groups.google.com/a/chromium.org/group/chromium-bugs
c***@googlecode.com
2012-05-28 05:39:10 UTC
Permalink
Updates:
Owner: ***@chromium.org
Cc: ***@chromium.org

Comment #13 on issue 129413 by ***@chromium.org: Login screen on test
image did not detect USB to Ethernet dongle until disconnected and
reconnected several times
http://code.google.com/p/chromium/issues/detail?id=129413

krisr,
Could you confirm that you can still reproduce this?
--
Automated mail from issue updates at http://crbug.com/
Subscription options: http://groups.google.com/a/chromium.org/group/chromium-bugs
c***@googlecode.com
2012-06-07 09:52:20 UTC
Permalink
Updates:
Owner: ***@chromium.org

Comment #18 on issue 129413 by ***@chromium.org: Login screen on test
image did not detect USB to Ethernet dongle until disconnected and
reconnected several times
http://code.google.com/p/chromium/issues/detail?id=129413

(No comment was entered for this change.)
--
Automated mail from issue updates at http://crbug.com/
Subscription options: http://groups.google.com/a/chromium.org/group/chromium-bugs
c***@googlecode.com
2012-06-07 15:36:05 UTC
Permalink
Updates:
Labels: -ReleaseBlock-Dev

Comment #19 on issue 129413 by ***@chromium.org: Login screen on test
image did not detect USB to Ethernet dongle until disconnected and
reconnected several times
http://code.google.com/p/chromium/issues/detail?id=129413

According to http://code.google.com/p/chromium/issues/detail?id=129413#c14
the behavior has changed and shouldn't be a blocker.

I don't see this behavior on TOT image on Alex. Not a test image though but
shouldn't matter as I've created /root/.forget_usernames.

It boots with sign in screen.
--
Automated mail from issue updates at http://crbug.com/
Subscription options: http://groups.google.com/a/chromium.org/group/chromium-bugs
c***@googlecode.com
2012-06-09 11:19:50 UTC
Permalink
Updates:
Owner: ***@chromium.org

Comment #20 on issue 129413 by ***@chromium.org: Login screen on test
image did not detect USB to Ethernet dongle until disconnected and
reconnected several times
http://code.google.com/p/chromium/issues/detail?id=129413

(No comment was entered for this change.)
--
Automated mail from issue updates at http://crbug.com/
Subscription options: http://groups.google.com/a/chromium.org/group/chromium-bugs
c***@googlecode.com
2012-06-09 16:20:03 UTC
Permalink
Updates:
Owner: ***@chromium.org

Comment #21 on issue 129413 by ***@chromium.org: Login screen on
test image did not detect USB to Ethernet dongle until disconnected and
reconnected several times
http://code.google.com/p/chromium/issues/detail?id=129413

I could not reproduce this issue on the x86-alex 2417.0.0 (09 Jun 2012
08:09:57) test image. Kris, could you please re-verify that issue?
--
Automated mail from issue updates at http://crbug.com/
Subscription options: http://groups.google.com/a/chromium.org/group/chromium-bugs
c***@googlecode.com
2012-06-14 13:12:07 UTC
Permalink
Updates:
Cc: ***@chromium.org

Comment #22 on issue 129413 by ***@chromium.org: Login screen on
test image did not detect USB to Ethernet dongle until disconnected and
reconnected several times
http://code.google.com/p/chromium/issues/detail?id=129413

Issue 130350 has been merged into this issue.
--
Automated mail from issue updates at http://crbug.com/
Subscription options: http://groups.google.com/a/chromium.org/group/chromium-bugs
Loading...