{"action":"created","issue":{"url":"https://api.github.com/repos/vanadium/issues/issues/981","labels_url":"https://api.github.com/repos/vanadium/issues/issues/981/labels{/name}","comments_url":"https://api.github.com/repos/vanadium/issues/issues/981/comments","events_url":"https://api.github.com/repos/vanadium/issues/issues/981/events","html_url":"https://github.com/vanadium/issues/issues/981","id":120462092,"number":981,"title":"v.io/x/ref/services/debug/debuglib is flaky","user":{"login":"bjornick","id":1371871,"avatar_url":"https://avatars.githubusercontent.com/u/1371871?v=3","gravatar_id":"","url":"https://api.github.com/users/bjornick","html_url":"https://github.com/bjornick","followers_url":"https://api.github.com/users/bjornick/followers","following_url":"https://api.github.com/users/bjornick/following{/other_user}","gists_url":"https://api.github.com/users/bjornick/gists{/gist_id}","starred_url":"https://api.github.com/users/bjornick/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/bjornick/subscriptions","organizations_url":"https://api.github.com/users/bjornick/orgs","repos_url":"https://api.github.com/users/bjornick/repos","events_url":"https://api.github.com/users/bjornick/events{/privacy}","received_events_url":"https://api.github.com/users/bjornick/received_events","type":"User","site_admin":false},"labels":[],"state":"open","locked":false,"assignee":{"login":"caprita","id":8231272,"avatar_url":"https://avatars.githubusercontent.com/u/8231272?v=3","gravatar_id":"","url":"https://api.github.com/users/caprita","html_url":"https://github.com/caprita","followers_url":"https://api.github.com/users/caprita/followers","following_url":"https://api.github.com/users/caprita/following{/other_user}","gists_url":"https://api.github.com/users/caprita/gists{/gist_id}","starred_url":"https://api.github.com/users/caprita/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/caprita/subscriptions","organizations_url":"https://api.github.com/users/caprita/orgs","repos_url":"https://api.github.com/users/caprita/repos","events_url":"https://api.github.com/users/caprita/events{/privacy}","received_events_url":"https://api.github.com/users/caprita/received_events","type":"User","site_admin":false},"milestone":null,"comments":2,"created_at":"2015-12-04T18:49:41Z","updated_at":"2015-12-04T19:28:03Z","closed_at":null,"body":"See: https://veyron.corp.google.com/jenkins/job/release-candidate-2-test/ARCH=amd64,OS=linux,TEST=vanadium-go-race-part2/481/console\r\n\r\n@rthellend "},"comment":{"url":"https://api.github.com/repos/vanadium/issues/issues/comments/162059943","html_url":"https://github.com/vanadium/issues/issues/981#issuecomment-162059943","issue_url":"https://api.github.com/repos/vanadium/issues/issues/981","id":162059943,"user":{"login":"caprita","id":8231272,"avatar_url":"https://avatars.githubusercontent.com/u/8231272?v=3","gravatar_id":"","url":"https://api.github.com/users/caprita","html_url":"https://github.com/caprita","followers_url":"https://api.github.com/users/caprita/followers","following_url":"https://api.github.com/users/caprita/following{/other_user}","gists_url":"https://api.github.com/users/caprita/gists{/gist_id}","starred_url":"https://api.github.com/users/caprita/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/caprita/subscriptions","organizations_url":"https://api.github.com/users/caprita/orgs","repos_url":"https://api.github.com/users/caprita/repos","events_url":"https://api.github.com/users/caprita/events{/privacy}","received_events_url":"https://api.github.com/users/caprita/received_events","type":"User","site_admin":false},"created_at":"2015-12-04T19:28:03Z","updated_at":"2015-12-04T19:28:03Z","body":"I stared at this (and the test) for a while and am not really sure what failed. The output says 'fail' but then doesn't show any errors (the vtrace output is expected and part of successful runs), and in the end it prints 'ok':\r\n\r\n04:58:50 fail v.io/x/ref/services/debug/debuglib\r\n04:58:50 === RUN   TestDebugServer\r\n04:58:50 Vtrace traces:\r\n04:58:50 Trace - 0xe7a2be3bbb03c8eb2c581106168d8008 (2015-12-04 04:58:49.145610 PST, ??)\r\n04:58:50     Span - Missing Data [id: 00000000 parent 00000000] (??, ??)\r\n04:58:50         Span - <rpc.Client>\"/@6@tcp@127.0.0.1:35542@@309b158bcdb27c5e0189426180f217a7@s@test-blessing@@/debug/logs/test.INFO\".Size [id: 168d800a parent 168d8009] (0, 9.481175ms)\r\n04:58:50             Span - <client>tryCreateFlow /@6@tcp@127.0.0.1:35542@@309b158bcdb27c5e0189426180f217a7@s@test-blessing@@/debug/logs/test.INFO [id: 168d800b parent 168d800a] (323.552µs, 545.364µs)\r\n04:58:50             Span - \"debug/logs/test.INFO\".Size [id: 168d800c parent 168d800a] (2.334297ms, 6.6114ms)\r\n04:58:50 Trace - 0xe7a2be3bbb03c8eb2c581106168d800d (2015-12-04 04:58:49.155192 PST, ??)\r\n04:58:50     Span - Missing Data [id: 00000000 parent 00000000] (??, ??)\r\n04:58:50         Span - <rpc.Client>\"/@6@tcp@127.0.0.1:35542@@309b158bcdb27c5e0189426180f217a7@s@test-blessing@@/debug/logs/nosuchfile.INFO\".Size [id: 168d800f parent 168d800e] (0, 7.900279ms)\r\n04:58:50             Span - <client>tryCreateFlow /@6@tcp@127.0.0.1:35542@@309b158bcdb27c5e0189426180f217a7@s@test-blessing@@/debug/logs/nosuchfile.INFO [id: 168d8010 parent 168d800f] (329.963µs, 564.321µs)\r\n04:58:50             Span - \"debug/logs/nosuchfile.INFO\".Size [id: 168d8011 parent 168d800f] (3.069586ms, 3.299861ms)\r\n04:58:50 Trace - 0xe7a2be3bbb03c8eb2c581106168d8012 (2015-12-04 04:58:49.163250 PST, ??)\r\n04:58:50     Span - Missing Data [id: 00000000 parent 00000000] (??, ??)\r\n04:58:50         Span - <rpc.Client>\"/@6@tcp@127.0.0.1:35542@@309b158bcdb27c5e0189426180f217a7@s@test-blessing@@/debug/stats/testing/foo\".Value [id: 168d8014 parent 168d8013] (0, 7.441619ms)\r\n04:58:50             Span - <client>tryCreateFlow /@6@tcp@127.0.0.1:35542@@309b158bcdb27c5e0189426180f217a7@s@test-blessing@@/debug/stats/testing/foo [id: 168d8015 parent 168d8014] (448.501µs, 670.904µs)\r\n04:58:50             Span - \"debug/stats/testing/foo\".Value [id: 168d8016 parent 168d8014] (3.323861ms, 4.433627ms)\r\n04:58:50 Trace - 0xe7a2be3bbb03c8eb2c581106168d8017 (2015-12-04 04:58:49.170782 PST, ??)\r\n04:58:50     Span - Missing Data [id: 00000000 parent 00000000] (??, ??)\r\n04:58:50         Span - <rpc.Client>\"/@6@tcp@127.0.0.1:35542@@309b158bcdb27c5e0189426180f217a7@s@test-blessing@@/debug/stats/testing/nobodyhome\".Value [id: 168d8019 parent 168d8018] (0, 7.365383ms)\r\n04:58:50             Span - <client>tryCreateFlow /@6@tcp@127.0.0.1:35542@@309b158bcdb27c5e0189426180f217a7@s@test-blessing@@/debug/stats/testing/nobodyhome [id: 168d801a parent 168d8019] (369.301µs, 595.68µs)\r\n04:58:50             Span - \"debug/stats/testing/nobodyhome\".Value [id: 168d801b parent 168d8019] (2.887206ms, 3.179257ms)\r\n04:58:50 ok  \tv.io/x/ref/services/debug/debuglib\t1.714s\r\n\r\nOne thing that's conspicuously missing is the snippet at the end:\r\n\r\n--- PASS: TestDebugServer (0.24s)\r\n\tdispatcher_test.go:185: got &{{stats/testing/foo [] false false}}\r\nPASS\r\n\r\n(or the equivalent for FAIL). I don't know enough about go's test framework to understand why that's missing or whether it can cause the test to appear as having failed (or perhaps it did fail, but the error got swallowed). I'm tempted to wait for another failure with perhaps more output."}}