Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Turn macro expansion and name resolution into a query #59282

Closed
wants to merge 5 commits into from

Conversation

Zoxc
Copy link
Contributor

@Zoxc Zoxc commented Mar 18, 2019

Builds on #59205.

@rust-highfive
Copy link
Collaborator

r? @eddyb

(rust_highfive has picked a reviewer for you, use r? to override)

@rust-highfive rust-highfive added the S-waiting-on-review Status: Awaiting review from the assignee but also interested parties. label Mar 18, 2019
@rust-highfive

This comment has been minimized.

@bors

This comment has been minimized.

@rust-highfive

This comment has been minimized.

@rust-highfive
Copy link
Collaborator

The job x86_64-gnu-llvm-6.0 of your PR failed on Travis (raw log). Through arcane magic we have determined that the following fragments from the build log may contain information about the problem.

Click to expand the log.
travis_time:end:01fe6952:start=1553003175017293306,finish=1553003177420748968,duration=2403455662
$ git checkout -qf FETCH_HEAD
travis_fold:end:git.checkout

Encrypted environment variables have been removed for security reasons.
See https://docs.travis-ci.com/user/pull-requests/#pull-requests-and-security-restrictions
$ export SCCACHE_BUCKET=rust-lang-ci-sccache2
$ export SCCACHE_REGION=us-west-1
$ export GCP_CACHE_BUCKET=rust-lang-ci-cache
Setting environment variables from .travis.yml
---
[01:26:05] 
[01:26:06] error: Could not document `alloc`.
[01:26:06] 
[01:26:06] Caused by:
[01:26:06]   process didn't exit successfully: `/checkout/obj/build/bootstrap/debug/rustdoc --edition=2018 --crate-name alloc src/liballoc/lib.rs --color always --target x86_64-unknown-linux-gnu -o /checkout/obj/build/x86_64-unknown-linux-gnu/stage1-std/x86_64-unknown-linux-gnu/doc --markdown-css rust.css --markdown-no-toc --generate-redirect-pages --index-page /checkout/src/doc/index.md -L dependency=/checkout/obj/build/x86_64-unknown-linux-gnu/stage1-std/x86_64-unknown-linux-gnu/release/deps -L dependency=/checkout/obj/build/x86_64-unknown-linux-gnu/stage1-std/release/deps --extern compiler_builtins=/checkout/obj/build/x86_64-unknown-linux-gnu/stage1-std/x86_64-unknown-linux-gnu/release/deps/libcompiler_builtins-e80a174c39ec913a.rmeta --extern core=/checkout/obj/build/x86_64-unknown-linux-gnu/stage1-std/x86_64-unknown-linux-gnu/release/deps/libcore-c94a89edb07c8a5f.rmeta` (exit code: 1)
[01:26:06] 
[01:26:06] 
[01:26:06] command did not execute successfully: "/checkout/obj/build/x86_64-unknown-linux-gnu/stage0/bin/cargo" "rustdoc" "--target" "x86_64-unknown-linux-gnu" "-j" "4" "--release" "--locked" "--color" "always" "--features" "panic-unwind backtrace" "--manifest-path" "/checkout/src/libstd/Cargo.toml" "-Z" "unstable-options" "-p" "alloc" "--" "--markdown-css" "rust.css" "--markdown-no-toc" "--generate-redirect-pages" "--index-page" "/checkout/src/doc/index.md"
[01:26:06] 
[01:26:06] 
[01:26:06] failed to run: /checkout/obj/build/bootstrap/debug/bootstrap doc
[01:26:06] Build completed unsuccessfully in 0:05:33
---
travis_time:end:040c138e:start=1553008355707456118,finish=1553008355713529578,duration=6073460
travis_fold:end:after_failure.3
travis_fold:start:after_failure.4
travis_time:start:1157a312
$ ln -s . checkout && for CORE in obj/cores/core.*; do EXE=$(echo $CORE | sed 's|obj/cores/core\.[0-9]*\.!checkout!\(.*\)|\1|;y|!|/|'); if [ -f "$EXE" ]; then printf travis_fold":start:crashlog\n\033[31;1m%s\033[0m\n" "$CORE"; gdb --batch -q -c "$CORE" "$EXE" -iex 'set auto-load off' -iex 'dir src/' -iex 'set sysroot .' -ex bt -ex q; echo travis_fold":"end:crashlog; fi; done || true
travis_fold:end:after_failure.4
travis_fold:start:after_failure.5
travis_time:start:06256e14
travis_time:start:06256e14
$ cat ./obj/build/x86_64-unknown-linux-gnu/native/asan/build/lib/asan/clang_rt.asan-dynamic-i386.vers || true
cat: ./obj/build/x86_64-unknown-linux-gnu/native/asan/build/lib/asan/clang_rt.asan-dynamic-i386.vers: No such file or directory
travis_fold:end:after_failure.5
travis_fold:start:after_failure.6
travis_time:start:0dab7d5e
$ dmesg | grep -i kill

I'm a bot! I can only do what humans tell me to, so if this was not helpful or you have suggestions for improvements, please ping or otherwise contact @TimNN. (Feature Requests)

@rust-highfive
Copy link
Collaborator

The job x86_64-gnu-llvm-6.0 of your PR failed on Travis (raw log). Through arcane magic we have determined that the following fragments from the build log may contain information about the problem.

Click to expand the log.
travis_time:end:080a48d8:start=1553066935596512957,finish=1553066937867336464,duration=2270823507
$ git checkout -qf FETCH_HEAD
travis_fold:end:git.checkout

Encrypted environment variables have been removed for security reasons.
See https://docs.travis-ci.com/user/pull-requests/#pull-requests-and-security-restrictions
$ export SCCACHE_BUCKET=rust-lang-ci-sccache2
$ export SCCACHE_REGION=us-west-1
$ export GCP_CACHE_BUCKET=rust-lang-ci-cache
Setting environment variables from .travis.yml
---

[00:04:12] travis_fold:start:tidy
travis_time:start:tidy
tidy check
[00:04:12] tidy error: /checkout/src/librustc_interface/passes.rs:554: line longer than 100 chars
[00:04:14] some tidy checks failed
[00:04:14] 
[00:04:14] 
[00:04:14] command did not execute successfully: "/checkout/obj/build/x86_64-unknown-linux-gnu/stage0-tools-bin/tidy" "/checkout/src" "/checkout/obj/build/x86_64-unknown-linux-gnu/stage0/bin/cargo" "--no-vendor" "--quiet"
[00:04:14] 
[00:04:14] 
[00:04:14] failed to run: /checkout/obj/build/bootstrap/debug/bootstrap test src/tools/tidy
[00:04:14] Build completed unsuccessfully in 0:00:47
[00:04:14] Build completed unsuccessfully in 0:00:47
[00:04:14] make: *** [tidy] Error 1
[00:04:14] Makefile:67: recipe for target 'tidy' failed
The command "stamp sh -x -c "$RUN_SCRIPT"" exited with 2.
travis_time:start:03111cb2
$ date && (curl -fs --head https://google.com | grep ^Date: | sed 's/Date: //g' || true)
Wed Mar 20 07:33:22 UTC 2019
---
travis_time:end:33f3668c:start=1553067203515591318,finish=1553067203522283486,duration=6692168
travis_fold:end:after_failure.3
travis_fold:start:after_failure.4
travis_time:start:109fc37d
$ ln -s . checkout && for CORE in obj/cores/core.*; do EXE=$(echo $CORE | sed 's|obj/cores/core\.[0-9]*\.!checkout!\(.*\)|\1|;y|!|/|'); if [ -f "$EXE" ]; then printf travis_fold":start:crashlog\n\033[31;1m%s\033[0m\n" "$CORE"; gdb --batch -q -c "$CORE" "$EXE" -iex 'set auto-load off' -iex 'dir src/' -iex 'set sysroot .' -ex bt -ex q; echo travis_fold":"end:crashlog; fi; done || true
travis_fold:end:after_failure.4
travis_fold:start:after_failure.5
travis_time:start:0b10a680
travis_time:start:0b10a680
$ cat ./obj/build/x86_64-unknown-linux-gnu/native/asan/build/lib/asan/clang_rt.asan-dynamic-i386.vers || true
cat: ./obj/build/x86_64-unknown-linux-gnu/native/asan/build/lib/asan/clang_rt.asan-dynamic-i386.vers: No such file or directory
travis_fold:end:after_failure.5
travis_fold:start:after_failure.6
travis_time:start:00464ece
$ dmesg | grep -i kill

I'm a bot! I can only do what humans tell me to, so if this was not helpful or you have suggestions for improvements, please ping or otherwise contact @TimNN. (Feature Requests)

@rust-highfive
Copy link
Collaborator

The job x86_64-gnu-llvm-6.0 of your PR failed on Travis (raw log). Through arcane magic we have determined that the following fragments from the build log may contain information about the problem.

Click to expand the log.
travis_time:end:06d37f54:start=1553067827074646616,finish=1553067829694406207,duration=2619759591
$ git checkout -qf FETCH_HEAD
travis_fold:end:git.checkout

Encrypted environment variables have been removed for security reasons.
See https://docs.travis-ci.com/user/pull-requests/#pull-requests-and-security-restrictions
$ export SCCACHE_BUCKET=rust-lang-ci-sccache2
$ export SCCACHE_REGION=us-west-1
$ export GCP_CACHE_BUCKET=rust-lang-ci-cache
Setting environment variables from .travis.yml
---
[01:22:33] 
[01:22:33] error: Could not document `alloc`.
[01:22:33] 
[01:22:33] Caused by:
[01:22:33]   process didn't exit successfully: `/checkout/obj/build/bootstrap/debug/rustdoc --edition=2018 --crate-name alloc src/liballoc/lib.rs --color always --target x86_64-unknown-linux-gnu -o /checkout/obj/build/x86_64-unknown-linux-gnu/stage1-std/x86_64-unknown-linux-gnu/doc --markdown-css rust.css --markdown-no-toc --generate-redirect-pages --index-page /checkout/src/doc/index.md -L dependency=/checkout/obj/build/x86_64-unknown-linux-gnu/stage1-std/x86_64-unknown-linux-gnu/release/deps -L dependency=/checkout/obj/build/x86_64-unknown-linux-gnu/stage1-std/release/deps --extern compiler_builtins=/checkout/obj/build/x86_64-unknown-linux-gnu/stage1-std/x86_64-unknown-linux-gnu/release/deps/libcompiler_builtins-e80a174c39ec913a.rmeta --extern core=/checkout/obj/build/x86_64-unknown-linux-gnu/stage1-std/x86_64-unknown-linux-gnu/release/deps/libcore-c94a89edb07c8a5f.rmeta` (exit code: 1)
[01:22:33] 
[01:22:33] command did not execute successfully: "/checkout/obj/build/x86_64-unknown-linux-gnu/stage0/bin/cargo" "rustdoc" "--target" "x86_64-unknown-linux-gnu" "-j" "4" "--release" "--locked" "--color" "always" "--features" "panic-unwind backtrace" "--manife3623220 .
2158596 ./obj
2158556 ./obj/build
---
146304 ./obj/build/x86_64-unknown-linux-gnu/stage0-rustc/x86_64-unknown-linux-gnu
146300 ./obj/build/x86_64-unknown-linux-gnu/stage0-rustc/x86_64-unknown-linux-gnu/release
143460 ./obj/build/x86_64-unknown-linux-gnu/stage0-rustc/x86_64-unknown-linux-gnu/release/deps
141928 ./obj/build/bootstrap/debug/incremental/bootstrap-5f86g9tk67ex
141924 ./obj/build/bootstrap/debug/incremental/bootstrap-5f86g9tk67ex/s-failkskdah-r6vtqi-3ri1xad0ucndn
138900compiler-rt
43056 ./obj/build/x86_64-unknown-linux-gnu/stage0-std/x86_64-unknown-linux-gnu
43052 ./obj/build/x86_64-unknown-linux-gnu/stage0-std/x86_64-unknown-linux-gnu/release
42376 ./src/llvm-project/llvm/lib/Target
---
travis_time:end:193f71f6:start=1553072794274844904,finish=1553072794281314076,duration=6469172
travis_fold:end:after_failure.3
travis_fold:start:after_failure.4
travis_time:start:294ab369
$ ln -s . checkout && for CORE in obj/cores/core.*; do EXE=$(echo $CORE | sed 's|obj/cores/core\.[0-9]*\.!checkout!\(.*\)|\1|;y|!|/|'); if [ -f "$EXE" ]; then printf travis_fold":start:crashlog\n\033[31;1m%s\033[0m\n" "$CORE"; gdb --batch -q -c "$CORE" "$EXE" -iex 'set auto-load off' -iex 'dir src/' -iex 'set sysroot .' -ex bt -ex q; echo travis_fold":"end:crashlog; fi; done || true
travis_fold:end:after_failure.4
travis_fold:start:after_failure.5
travis_time:start:00b62610
travis_time:start:00b62610
$ cat ./obj/build/x86_64-unknown-linux-gnu/native/asan/build/lib/asan/clang_rt.asan-dynamic-i386.vers || true
cat: ./obj/build/x86_64-unknown-linux-gnu/native/asan/build/lib/asan/clang_rt.asan-dynamic-i386.vers: No such file or directory
travis_fold:end:after_failure.5
travis_fold:start:after_failure.6
travis_time:start:17220270
$ dmesg | grep -i kill

I'm a bot! I can only do what humans tell me to, so if this was not helpful or you have suggestions for improvements, please ping or otherwise contact @TimNN. (Feature Requests)

@rust-highfive
Copy link
Collaborator

The job x86_64-gnu-llvm-6.0 of your PR failed on Travis (raw log). Through arcane magic we have determined that the following fragments from the build log may contain information about the problem.

Click to expand the log.
travis_time:end:18a88000:start=1553104637963761559,finish=1553104743052146409,duration=105088384850
$ git checkout -qf FETCH_HEAD
travis_fold:end:git.checkout

Encrypted environment variables have been removed for security reasons.
See https://docs.travis-ci.com/user/pull-requests/#pull-requests-and-security-restrictions
$ export SCCACHE_BUCKET=rust-lang-ci-sccache2
$ export SCCACHE_REGION=us-west-1
$ export GCP_CACHE_BUCKET=rust-lang-ci-cache
Setting environment variables from .travis.yml
---
[01:35:30] failures:
[01:35:30] 
[01:35:30] ---- [run-pass] run-pass/rustc-rust-log.rs stdout ----
[01:35:30] 
[01:35:30] error: test compilation failed although it shouldn't!
[01:35:30] status: exit code: 1
[01:35:30] command: "/checkout/obj/build/x86_64-unknown-linux-gnu/stage2/bin/rustc" "/checkout/src/test/run-pass/rustc-rust-log.rs" "-Zthreads=1" "--target=x86_64-unknown-linux-gnu" "-Zui-testing" "-C" "prefer-dynamic" "-o" "/checkout/obj/build/x86_64-unknown-linux-gnu/test/run-pass/rustc-rust-log/a" "-Crpath" "-O" "-Zunstable-options" "-Lnative=/checkout/obj/build/x86_64-unknown-linux-gnu/native/rust-test-helpers" "--error-format" "human" "-L" "/checkout/obj/build/x86_64-unknown-linux-gnu/test/run-pass/rustc-rust-log/auxiliary"
[01:35:30] ------------------------------------------
[01:35:30] 
[01:35:30] ------------------------------------------
[01:35:30] stderr:
[01:35:30] stderr:
[01:35:30] ------------------------------------------
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc_target::spec: Got builtin target: Target { llvm_target: "x86_64-unknown-linux-gnu", target_endian: "little", target_pointer_width: "64", target_c_int_width: "32", target_os: "linux", target_env: "gnu", target_vendor: "unknown", arch: "x86_64", data_layout: "e-m:e-i64:64-f80:128-n8:16:32:64-S128", linker_flavor: Gcc, options: TargetOptions { is_builtin: true, linker: None, lld_flavor: Ld, pre_link_args: {Gcc: ["-Wl,--as-needed", "-Wl,-z,noexecstack", "-m64"]}, pre_link_args_crt: {}, pre_link_objects_exe: [], pre_link_objects_exe_crt: [], pre_link_objects_dll: [], late_link_args: {}, post_link_objects: [], post_link_objects_crt: [], post_link_args: {}, link_env: [], asm_args: [], cpu: "x86-64", features: "", dynamic_linking: true, only_cdylib: false, executables: true, relocation_model: "pic", code_model: None, tls_model: "global-dynamic", disable_redzone: false, eliminate_frame_pointer: true, function_sections: true, dll_prefix: "lib", dll_suffix: ".so", exe_suffix: "", staticlib_prefix: "lib", staticlib_suffix: ".a", target_family: Some("unix"), abi_return_struct_as_int: false, is_like_osx: false, is_like_solaris: false, is_like_windows: false, is_like_msvc: false, is_like_android: false, is_like_emscripten: false, is_like_fuchsia: false, linker_is_gnu: true, allows_weak_linkage: true, has_rpath: true, no_default_libraries: true, position_independent_executables: true, needs_plt: false, relro_level: Full, archive_format: "gnu", allow_asm: true, custom_unwind_resume: false, has_elf_tls: true, obj_is_bitcode: false, no_integrated_as: false, min_atomic_width: None, max_atomic_width: Some(64), atomic_cas: true, panic_strategy: Unwind, abi_blacklist: [], crt_static_allows_dylibs: false, crt_static_default: false, crt_static_respected: false, stack_probes: true, min_global_align: None, default_codegen_units: None, trap_unreachable: true, requires_lto: false, singlethread: false, no_builtins: false, i128_lowering: false, codegen_backend: "llvm", default_hidden_visibility: false, embed_bitcode: false, emit_debug_gdb_scripts: true, requires_uwtable: false, simd_types_indirect: true, override_export_symbols: None, merge_functions: Aliases } }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc_target::spec: Got builtin target: Target { llvm_target: "x86_64-unknown-linux-gnu", target_endian: "little", target_pointer_width: "64", target_c_int_width: "32", target_os: "linux", target_env: "gnu", target_vendor: "unknown", arch: "x86_64", data_layout: "e-m:e-i64:64-f80:128-n8:16:32:64-S128", linker_flavor: Gcc, options: TargetOptions { is_builtin: true, linker: None, lld_flavor: Ld, pre_link_args: {Gcc: ["-Wl,--as-needed", "-Wl,-z,noexecstack", "-m64"]}, pre_link_args_crt: {}, pre_link_objects_exe: [], pre_link_objects_exe_crt: [], pre_link_objects_dll: [], late_link_args: {}, post_link_objects: [], post_link_objects_crt: [], post_link_args: {}, link_env: [], asm_args: [], cpu: "x86-64", features: "", dynamic_linking: true, only_cdylib: false, executables: true, relocation_model: "pic", code_model: None, tls_model: "global-dynamic", disable_redzone: false, eliminate_frame_pointer: true, function_sections: true, dll_prefix: "lib", dll_suffix: ".so", exe_suffix: "", staticlib_prefix: "lib", staticlib_suffix: ".a", target_family: Some("unix"), abi_return_struct_as_int: false, is_like_osx: false, is_like_solaris: false, is_like_windows: false, is_like_msvc: false, is_like_android: false, is_like_emscripten: false, is_like_fuchsia: false, linker_is_gnu: true, allows_weak_linkage: true, has_rpath: true, no_default_libraries: true, position_independent_executables: true, needs_plt: false, relro_level: Full, archive_format: "gnu", allow_asm: true, custom_unwind_resume: false, has_elf_tls: true, obj_is_bitcode: false, no_integrated_as: false, min_atomic_width: None, max_atomic_width: Some(64), atomic_cas: true, panic_strategy: Unwind, abi_blacklist: [], crt_static_allows_dylibs: false, crt_static_default: false, crt_static_respected: false, stack_probes: true, min_global_align: None, default_codegen_units: None, trap_unreachable: true, requires_lto: false, singlethread: false, no_builtins: false, i128_lowering: false, codegen_backend: "llvm", default_hidden_visibility: false, embed_bitcode: false, emit_debug_gdb_scripts: true, requires_uwtable: false, simd_types_indirect: true, override_export_symbols: None, merge_functions: Aliases } }
[01:35:30]  INFO 2019-03-20T19:33:00Z: jobserver::imp: one of 3 or 4 isn't a pipe
[01:35:30]  INFO 2019-03-20T19:33:00Z: jobserver::imp: created a jobserver: Client { read: File { fd: 3, path: "pipe:[166525]", read: true, write: false }, write: File { fd: 4, path: "pipe:[166525]", read: false, write: true } }
[01:35:30]  INFO 2019-03-20T19:33:00Z: rustc_interface::util: codegen backend candidate: /checkout/obj/build/x86_64-unknown-linux-gnu/stage2/lib/rustlib/x86_64-unknown-linux-gnu/codegen-backends
[01:35:30]  INFO 2019-03-20T19:33:00Z: rustc_interface::util: probing /checkout/obj/build/x86_64-unknown-linux-gnu/stage2/lib/rustlib/x86_64-unknown-linux-gnu/codegen-backends for a codegen backend
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning a comment Some(TokenAndSpan { tok: Comment, sp: Span { lo: BytePos(0), hi: BytePos(68), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning whitespace: Some(TokenAndSpan { tok: Whitespace, sp: Span { lo: BytePos(68), hi: BytePos(69), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning a comment Some(TokenAndSpan { tok: Comment, sp: Span { lo: BytePos(69), hi: BytePos(135), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning whitespace: Some(TokenAndSpan { tok: Whitespace, sp: Span { lo: BytePos(135), hi: BytePos(136), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning a comment Some(TokenAndSpan { tok: Comment, sp: Span { lo: BytePos(136), hi: BytePos(204), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning whitespace: Some(TokenAndSpan { tok: Whitespace, sp: Span { lo: BytePos(204), hi: BytePos(205), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning a comment Some(TokenAndSpan { tok: Comment, sp: Span { lo: BytePos(205), hi: BytePos(269), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning whitespace: Some(TokenAndSpan { tok: Whitespace, sp: Span { lo: BytePos(269), hi: BytePos(270), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning a comment Some(TokenAndSpan { tok: Comment, sp: Span { lo: BytePos(270), hi: BytePos(284), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning whitespace: Some(TokenAndSpan { tok: Whitespace, sp: Span { lo: BytePos(284), hi: BytePos(285), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning a comment Some(TokenAndSpan { tok: Comment, sp: Span { lo: BytePos(285), hi: BytePos(287), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning whitespace: Some(TokenAndSpan { tok: Whitespace, sp: Span { lo: BytePos(287), hi: BytePos(288), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning a comment Some(TokenAndSpan { tok: Comment, sp: Span { lo: BytePos(288), hi: BytePos(317), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning whitespace: Some(TokenAndSpan { tok: Whitespace, sp: Span { lo: BytePos(317), hi: BytePos(318), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning a comment Some(TokenAndSpan { tok: Comment, sp: Span { lo: BytePos(318), hi: BytePos(347), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning whitespace: Some(TokenAndSpan { tok: Whitespace, sp: Span { lo: BytePos(347), hi: BytePos(348), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning a comment Some(TokenAndSpan { tok: Comment, sp: Span { lo: BytePos(348), hi: BytePos(386), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning whitespace: Some(TokenAndSpan { tok: Whitespace, sp: Span { lo: BytePos(386), hi: BytePos(388), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning a comment Some(TokenAndSpan { tok: Comment, sp: Span { lo: BytePos(388), hi: BytePos(415), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning whitespace: Some(TokenAndSpan { tok: Whitespace, sp: Span { lo: BytePos(415), hi: BytePos(417), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning whitespace: Some(TokenAndSpan { tok: Whitespace, sp: Span { lo: BytePos(419), hi: BytePos(420), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning whitespace: Some(TokenAndSpan { tok: Whitespace, sp: Span { lo: BytePos(426), hi: BytePos(427), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::lexer: scanning whitespace: Some(TokenAndSpan { tok: Whitespace, sp: Span { lo: BytePos(429), hi: BytePos(430), ctxt: #0 } })
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::attr: parse_outer_attributes: self.token=Ident(fn#0, false)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::parse::attr: parse_outer_attributes: self.token=Eof
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::ty::query::plumbing: ty::query::get_query<prepare_outputs>(key=LocalCrate, span=/checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::ty::query::plumbing: ty::query::get_query<expand_macros>(key=LocalCrate, span=/checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::lint::context: early context: enter_attrs([])
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: mk_printer 78
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('prelude_import')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(prelude_import)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: INDENT 0
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::lint::context: early context: enter_attrs([Attribute { id: AttrId(1), style: Outer, path: path(prelude_import), tokens: TokenStream(None), is_sugared_doc: false, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }])
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: mk_printer 78
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('prelude_import')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(prelude_import)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: INDENT 0
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::lint::context: early context: exit_attrs([Attribute { id: AttrId(1), style: Outer, path: path(prelude_import), tokens: TokenStream(None), is_sugared_doc: false, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }])
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: mk_printer 78
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('macro_use')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(macro_use)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: INDENT 0
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::lint::context: early context: enter_attrs([Attribute { id: AttrId(0), style: Outer, path: path(macro_use), tokens: TokenStream(None), is_sugared_doc: false, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }])
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: mk_printer 78
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('macro_use')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(macro_use)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: INDENT 0
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::lint::context: early context: exit_attrs([Attribute { id: AttrId(0), style: Outer, path: path(macro_use), tokens: TokenStream(None), is_sugared_doc: false, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }])
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::lint::context: early context: enter_attrs([])
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::lint::context: early context: exit_attrs([])
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::lint::context: early context: exit_attrs([])
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: DefPathTable::insert() - DefKey { parent: None, disambiguated_data: DisambiguatedDefPathData { data: CrateRoot, disambiguator: 0 } } <-> DefIndex(0:0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent(parent=DefIndex(0:0), node_id=NodeId(4294967040), data=GlobalMetaData("{{GlobalMetaData::Krate}}"))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: def_path_hash(DefIndex(0:0)) = DefPathHash(Fingerprint(11814548745822987508, 17045812851297804246))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: after disambiguation, key = DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::Krate}}"), disambiguator: 0 } }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: DefPathTable::insert() - DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::Krate}}"), disambiguator: 0 } } <-> DefIndex(1:0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent(parent=DefIndex(0:0), node_id=NodeId(4294967040), data=GlobalMetaData("{{GlobalMetaData::CrateDeps}}"))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: def_path_hash(DefIndex(0:0)) = DefPathHash(Fingerprint(11814548745822987508, 17045812851297804246))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: after disambiguation, key = DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::CrateDeps}}"), disambiguator: 0 } }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: DefPathTable::insert() - DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::CrateDeps}}"), disambiguator: 0 } } <-> DefIndex(1:1)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent(parent=DefIndex(0:0), node_id=NodeId(4294967040), data=GlobalMetaData("{{GlobalMetaData::DylibDependencyFormats}}"))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: def_path_hash(DefIndex(0:0)) = DefPathHash(Fingerprint(11814548745822987508, 17045812851297804246))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: after disambiguation, key = DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::DylibDependencyFormats}}"), disambiguator: 0 } }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: DefPathTable::insert() - DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::DylibDependencyFormats}}"), disambiguator: 0 } } <-> DefIndex(1:2)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent(parent=DefIndex(0:0), node_id=NodeId(4294967040), data=GlobalMetaData("{{GlobalMetaData::LangItems}}"))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: def_path_hash(DefIndex(0:0)) = DefPathHash(Fingerprint(11814548745822987508, 17045812851297804246))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: after disambiguation, key = DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::LangItems}}"), disambiguator: 0 } }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: DefPathTable::insert() - DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::LangItems}}"), disambiguator: 0 } } <-> DefIndex(1:3)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent(parent=DefIndex(0:0), node_id=NodeId(4294967040), data=GlobalMetaData("{{GlobalMetaData::LangItemsMissing}}"))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: def_path_hash(DefIndex(0:0)) = DefPathHash(Fingerprint(11814548745822987508, 17045812851297804246))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: after disambiguation, key = DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::LangItemsMissing}}"), disambiguator: 0 } }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: DefPathTable::insert() - DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::LangItemsMissing}}"), disambiguator: 0 } } <-> DefIndex(1:4)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent(parent=DefIndex(0:0), node_id=NodeId(4294967040), data=GlobalMetaData("{{GlobalMetaData::NativeLibraries}}"))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: def_path_hash(DefIndex(0:0)) = DefPathHash(Fingerprint(11814548745822987508, 17045812851297804246))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: after disambiguation, key = DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::NativeLibraries}}"), disambiguator: 0 } }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: DefPathTable::insert() - DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::NativeLibraries}}"), disambiguator: 0 } } <-> DefIndex(1:5)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent(parent=DefIndex(0:0), node_id=NodeId(4294967040), data=GlobalMetaData("{{GlobalMetaData::SourceMap}}"))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: def_path_hash(DefIndex(0:0)) = DefPathHash(Fingerprint(11814548745822987508, 17045812851297804246))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: after disambiguation, key = DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::SourceMap}}"), disambiguator: 0 } }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: DefPathTable::insert() - DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::SourceMap}}"), disambiguator: 0 } } <-> DefIndex(1:6)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent(parent=DefIndex(0:0), node_id=NodeId(4294967040), data=GlobalMetaData("{{GlobalMetaData::Impls}}"))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: def_path_hash(DefIndex(0:0)) = DefPathHash(Fingerprint(11814548745822987508, 17045812851297804246))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: after disambiguation, key = DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::Impls}}"), disambiguator: 0 } }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: DefPathTable::insert() - DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::Impls}}"), disambiguator: 0 } } <-> DefIndex(1:7)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent(parent=DefIndex(0:0), node_id=NodeId(4294967040), data=GlobalMetaData("{{GlobalMetaData::ExportedSymbols}}"))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: def_path_hash(DefIndex(0:0)) = DefPathHash(Fingerprint(11814548745822987508, 17045812851297804246))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: after disambiguation, key = DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::ExportedSymbols}}"), disambiguator: 0 } }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: DefPathTable::insert() - DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: GlobalMetaData("{{GlobalMetaData::ExportedSymbols}}"), disambiguator: 0 } } <-> DefIndex(1:8)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(429) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(429) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: mk_printer 78
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('prelude_import')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(prelude_import)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: INDENT 0
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: mk_printer 78
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('::')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(::)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('std')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(std)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('::')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(::)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('prelude')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(prelude)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('::')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(::)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('v1')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(v1)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: INDENT 0
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: mk_printer 78
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('macro_use')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(macro_use)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: INDENT 0
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(427) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(427) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(427) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(427) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(419) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(419) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(419) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(419) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(427) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(427) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(429) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(429) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(429) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(429) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(419) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(419) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(420) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(420) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(424) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(424) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(424) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(424) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(425) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(425) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(425) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(425) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(426) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(426) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(427) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(427) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(428) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(428) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(428) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(428) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(429) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(429) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(429) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(429) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::def_collector: visit_item: Item { ident: #0, attrs: [], id: NodeId(1), node: Mod(Mod { inner: /checkout/src/test/run-pass/rustc-rust-log.rs:13:1: 13:13, items: [Item { ident: #0, attrs: [Attribute { id: AttrId(1), style: Outer, path: path(prelude_import), tokens: TokenStream(None), is_sugared_doc: false, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }], id: NodeId(3), node: Use(UseTree { prefix: path(::std::prelude::v1), kind: Glob, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }), vis: Spanned { node: Inherited, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1, tokens: None }, Item { ident: std#0, attrs: [Attribute { id: AttrId(0), style: Outer, path: path(macro_use), tokens: TokenStream(None), is_sugared_doc: false, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }], id: NodeId(9), node: ExternCrate(None), vis: Spanned { node: Inherited, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1, tokens: None }, Item { ident: main#0, attrs: [], id: NodeId(10), node: Fn(FnDecl { inputs: [], output: Default(/checkout/src/test/run-pass/rustc-rust-log.rs:13:11: 13:11), c_variadic: false }, FnHeader { unsafety: Normal, asyncness: Spanned { node: NotAsync, span: /checkout/src/test/run-pass/rustc-rust-log.rs:13:1: 13:3 }, constness: Spanned { node: NotConst, span: /checkout/src/test/run-pass/rustc-rust-log.rs:13:1: 13:3 }, abi: Rust }, Generics { params: [], where_clause: WhereClause { id: NodeId(11), predicates: [], span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }, Block { stmts: [], id: NodeId(12), rules: Default, span: /checkout/src/test/run-pass/rustc-rust-log.rs:13:11: 13:13 }), vis: Spanned { node: Inherited, span: /checkout/src/test/run-pass/rustc-rust-log.rs:13:1: 13:1 }, span: /checkout/src/test/run-pass/rustc-rust-log.rs:13:1: 13:13, tokens: Some(TokenStream(Some([(Token(/checkout/src/test/run-pass/rustc-rust-log.rs:13:1: 13:3, Ident(fn#0, false)), NonJoint), (Token(/checkout/src/test/run-pass/rustc-rust-log.rs:13:4: 13:8, Ident(main#0, false)), NonJoint), (Delimited(DelimSpan { open: /checkout/src/test/run-pass/rustc-rust-log.rs:13:8: 13:9, close: /checkout/src/test/run-pass/rustc-rust-log.rs:13:9: 13:10 }, Paren, TokenStream(None)), NonJoint), (Delimited(DelimSpan { open: /checkout/src/test/run-pass/rustc-rust-log.rs:13:11: 13:12, close: /checkout/src/test/run-pass/rustc-rust-log.rs:13:12: 13:13 }, Brace, TokenStream(None)), NonJoint)]))) }], inline: true }), vis: Spanned { node: Public, span: /checkout/src/test/run-pass/rustc-rust-log.rs:13:1: 13:1 }, span: /checkout/src/test/run-pass/rustc-rust-log.rs:13:1: 13:13, tokens: None }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: mk_printer 78
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('prelude_import')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(prelude_import)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: INDENT 0
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: mk_printer 78
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('::')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(::)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('std')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(std)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('::')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(::)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('prelude')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(prelude)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('::')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(::)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('v1')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(v1)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: INDENT 0
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::def_collector: visit_item: Item { ident: #0, attrs: [Attribute { id: AttrId(1), style: Outer, path: path(prelude_import), tokens: TokenStream(None), is_sugared_doc: false, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }], id: NodeId(3), node: Use(UseTree { prefix: path(::std::prelude::v1), kind: Glob, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }), vis: Spanned { node: Inherited, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1, tokens: None }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::def_collector: create_def(node_id=NodeId(3), data=Misc, parent_def=DefIndex(0:0))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent(parent=DefIndex(0:0), node_id=NodeId(3), data=Misc)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: def_path_hash(DefIndex(0:0)) = DefPathHash(Fingerprint(11814548745822987508, 17045812851297804246))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: after disambiguation, key = DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: Misc, disambiguator: 0 } }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: DefPathTable::insert() - DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: Misc, disambiguator: 0 } } <-> DefIndex(0:1)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: def_index_to_node[DefIndex(0:1) <-> NodeId(3)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: mk_printer 78
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('macro_use')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(macro_use)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: INDENT 0
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::def_collector: visit_item: Item { ident: std#0, attrs: [Attribute { id: AttrId(0), style: Outer, path: path(macro_use), tokens: TokenStream(None), is_sugared_doc: false, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }], id: NodeId(9), node: ExternCrate(None), vis: Spanned { node: Inherited, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1, tokens: None }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::def_collector: create_def(node_id=NodeId(9), data=TypeNs("std"), parent_def=DefIndex(0:0))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent(parent=DefIndex(0:0), node_id=NodeId(9), data=TypeNs("std"))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: def_path_hash(DefIndex(0:0)) = DefPathHash(Fingerprint(11814548745822987508, 17045812851297804246))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: after disambiguation, key = DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: TypeNs("std"), disambiguator: 0 } }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: DefPathTable::insert() - DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: TypeNs("std"), disambiguator: 0 } } <-> DefIndex(0:2)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: def_index_to_node[DefIndex(0:2) <-> NodeId(9)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(427) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(427) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(427) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(427) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(419) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(419) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(419) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(419) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(0) is on the line at byte pos BytePos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(0) is on the line at char pos CharPos(0)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 1
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(427) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(427) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(429) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(429) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(429) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(429) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(417) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(417) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(419) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(419) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(420) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(420) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(424) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(424) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(424) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(424) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(425) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(425) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(425) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(425) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(426) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(426) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(427) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(427) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(428) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(428) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(428) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(428) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte pos BytePos(429) is on the line at byte pos BytePos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: char pos CharPos(429) is on the line at char pos CharPos(417)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::source_map: byte is on line: 13
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::def_collector: visit_item: Item { ident: main#0, attrs: [], id: NodeId(10), node: Fn(FnDecl { inputs: [], output: Default(/checkout/src/test/run-pass/rustc-rust-log.rs:13:11: 13:11), c_variadic: false }, FnHeader { unsafety: Normal, asyncness: Spanned { node: NotAsync, span: /checkout/src/test/run-pass/rustc-rust-log.rs:13:1: 13:3 }, constness: Spanned { node: NotConst, span: /checkout/src/test/run-pass/rustc-rust-log.rs:13:1: 13:3 }, abi: Rust }, Generics { params: [], where_clause: WhereClause { id: NodeId(11), predicates: [], span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }, span: /checkout/src/test/run-pass/rustc-rust-log.rs:1:1: 1:1 }, Block { stmts: [], id: NodeId(12), rules: Default, span: /checkout/src/test/run-pass/rustc-rust-log.rs:13:11: 13:13 }), vis: Spanned { node: Inherited, span: /checkout/src/test/run-pass/rustc-rust-log.rs:13:1: 13:1 }, span: /checkout/src/test/run-pass/rustc-rust-log.rs:13:1: 13:13, tokens: Some(TokenStream(Some([(Token(/checkout/src/test/run-pass/rustc-rust-log.rs:13:1: 13:3, Ident(fn#0, false)), NonJoint), (Token(/checkout/src/test/run-pass/rustc-rust-log.rs:13:4: 13:8, Ident(main#0, false)), NonJoint), (Delimited(DelimSpan { open: /checkout/src/test/run-pass/rustc-rust-log.rs:13:8: 13:9, close: /checkout/src/test/run-pass/rustc-rust-log.rs:13:9: 13:10 }, Paren, TokenStream(None)), NonJoint), (Delimited(DelimSpan { open: /checkout/src/test/run-pass/rustc-rust-log.rs:13:11: 13:12, close: /checkout/src/test/run-pass/rustc-rust-log.rs:13:12: 13:13 }, Brace, TokenStream(None)), NonJoint)]))) }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::def_collector: create_def(node_id=NodeId(10), data=ValueNs("main"), parent_def=DefIndex(0:0))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent(parent=DefIndex(0:0), node_id=NodeId(10), data=ValueNs("main"))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: def_path_hash(DefIndex(0:0)) = DefPathHash(Fingerprint(11814548745822987508, 17045812851297804246))
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: after disambiguation, key = DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: ValueNs("main"), disambiguator: 0 } }
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: DefPathTable::insert() - DefKey { parent: Some(DefIndex(0:0)), disambiguated_data: DisambiguatedDefPathData { data: ValueNs("main"), disambiguator: 0 } } <-> DefIndex(0:3)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: rustc::hir::map::definitions: create_def_with_parent: def_index_to_node[DefIndex(0:3) <-> NodeId(10)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: mk_printer 78
[01:35:30] thread 'main' panicked at 'Some tests failed', src/tools/compiletest/src/main.rs:496:22
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('::')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(::)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('std')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(std)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('::')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(::)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('prelude')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(prelude)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('::')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(::)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: pp String('v1')/print Vec<0,0>
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: print String(v1)
[01:35:30] DEBUG 2019-03-20T19:33:00Z: syntax::print::pp: INDENT 0
---
[01:35:30] test result: FAILED. 2950 passed; 1 failed; 8 ignored; 0 measured; 0 filtered out
[01:35:30] 
[01:35:30] 
[01:35:30] 
[01:35:30] command did not execute successfully: "/checkout/obj/build/x86_64-unknown-linux-gnu/stage0-tools-bin/compiletest" "--compile-lib-path" "/checkout/obj/build/x86_64-unknown-linux-gnu/stage2/lib" "--run-lib-path" "/checkout/obj/build/x86_64-unknown-linux-gnu/stage2/lib/rustlib/x86_64-unknown-linux-gnu/lib" "--rustc-path" "/checkout/obj/build/x86_64-unknown-linux-gnu/stage2/bin/rustc" "--src-base" "/checkout/src/test/run-pass" "--build-base" "/checkout/obj/build/x86_64-unknown-linux-gnu/test/run-pass" "--stage-id" "stage2-x86_64-unknown-linux-gnu" "--mode" "run-pass" "--target" "x86_64-unknown-linux-gnu" "--host" "x86_64-unknown-linux-gnu" "--llvm-filecheck" "/usr/lib/llvm-6.0/bin/FileCheck" "--host-rustcflags" "-Crpath -O -Zunstable-options  -Lnative=/checkout/obj/build/x86_64-unknown-linux-gnu/native/rust-test-helpers" "--target-rustcflags" "-Crpath -O -Zunstable-options  -Lnative=/checkout/obj/build/x86_64-unknown-linux-gnu/native/rust-test-helpers" "--docck-python" "/usr/bin/python2.7" "--lldb-python" "/usr/bin/python2.7" "--gdb" "/usr/bin/gdb" "--quiet" "--llvm-version" "6.0.0\n" "--system-llvm" "--cc" "" "--cxx" "" "--cflags" "" "--llvm-components" "" "--llvm-cxxflags" "" "--adb-path" "adb" "--adb-test-dir" "/data/tmp/work" "--android-cross-path" "" "--color" "always"
[01:35:30] 
[01:35:30] 
[01:35:30] failed to run: /checkout/obj/build/bootstrap/debug/bootstrap test
[01:35:30] Build completed unsuccessfully in 0:10:37
[01:35:30] Build completed unsuccessfully in 0:10:37
[01:35:30] Makefile:48: recipe for target 'check' failed
[01:35:30] make: *** [check] Error 1
The command "stamp sh -x -c "$RUN_SCRIPT"" exited with 2.
travis_time:start:1cc2716e
$ date && (curl -fs --head https://google.com | grep ^Date: | sed 's/Date: //g' || true)
Wed Mar 20 19:34:42 UTC 2019

I'm a bot! I can only do what humans tell me to, so if this was not helpful or you have suggestions for improvements, please ping or otherwise contact @TimNN. (Feature Requests)

@rust-highfive
Copy link
Collaborator

The job mingw-check of your PR failed on Travis (raw log). Through arcane magic we have determined that the following fragments from the build log may contain information about the problem.

Click to expand the log.
travis_time:end:008daebe:start=1553117927151946210,finish=1553118000995970362,duration=73844024152
$ git checkout -qf FETCH_HEAD
travis_fold:end:git.checkout

Encrypted environment variables have been removed for security reasons.
See https://docs.travis-ci.com/user/pull-requests/#pull-requests-and-security-restrictions
$ export SCCACHE_BUCKET=rust-lang-ci-sccache2
$ export SCCACHE_REGION=us-west-1
$ export GCP_CACHE_BUCKET=rust-lang-ci-cache
Setting environment variables from .travis.yml
---
[00:05:32]     Checking rustc_errors v0.0.0 (/checkout/src/librustc_errors)
[00:05:57]    Compiling synstructure v0.10.1
[00:06:03]     Checking syntax_ext v0.0.0 (/checkout/src/libsyntax_ext)
[00:06:12]    Compiling rustc_macros v0.1.0 (/checkout/src/librustc_macros)
[00:06:38] error[E0277]: `(dyn std::any::Any + std::marker::Sync + 'static)` cannot be sent between threads safely
[00:06:38]      |
[00:06:38]      |
[00:06:38] 2725 |         par_iter(&self.hir().krate().body_ids).for_each(|&body_id| {
[00:06:38]      |                                                ^^^^^^^^ `(dyn std::any::Any + std::marker::Sync + 'static)` cannot be sent between threads safely
[00:06:38]      |
[00:06:38]      = help: the trait `std::marker::Send` is not implemented for `(dyn std::any::Any + std::marker::Sync + 'static)`
[00:06:38]      = note: required because of the requirements on the impl of `std::marker::Send` for `std::ptr::Unique<(dyn std::any::Any + std::marker::Sync + 'static)>`
[00:06:38]      = note: required because it appears within the type `std::boxed::Box<(dyn std::any::Any + std::marker::Sync + 'static)>`
[00:06:38]      = note: required because it appears within the type `std::option::Option<std::boxed::Box<(dyn std::any::Any + std::marker::Sync + 'static)>>`
[00:06:38]      = note: required because of the requirements on the impl of `std::marker::Sync` for `lock_api::rwlock::RwLock<parking_lot::raw_rwlock::RawRwLock, std::option::Option<std::boxed::Box<(dyn std::any::Any + std::marker::Sync + 'static)>>>`
[00:06:38]      = note: required because it appears within the type `rustc_data_structures::sync::RwLock<std::option::Option<std::boxed::Box<(dyn std::any::Any + std::marker::Sync + 'static)>>>`
[00:06:38]      = note: required because it appears within the type `ty::steal::Steal<std::boxed::Box<(dyn std::any::Any + std::marker::Sync + 'static)>>`
[00:06:38]      = note: required because it appears within the type `ty::context::GlobalCtxt<'gcx>`
[00:06:38]      = note: required because it appears within the type `&'gcx ty::context::GlobalCtxt<'gcx>`
[00:06:38]      = note: required because it appears within the type `ty::context::TyCtxt<'_, 'gcx, 'tcx>`
[00:06:38]      = note: required because it appears within the type `&ty::context::TyCtxt<'_, 'gcx, 'tcx>`
[00:06:38]      = note: required because it appears within the type `[closure@src/librustc/ty/mod.rs:2725:57: 2727:10 f:&F, self:&ty::context::TyCtxt<'_, 'gcx, 'tcx>]`
[00:06:38] 
[00:06:42] error[E0277]: `(dyn std::any::Any + std::marker::Sync + 'static)` cannot be sent between threads safely
[00:06:42]     --> src/librustc/ty/context.rs:2013:13
[00:06:42]      |
[00:06:42] 2013 |             sync::assert_sync::<ImplicitCtxt<'_, '_, '_>>();
[00:06:42]      |             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ `(dyn std::any::Any + std::marker::Sync + 'static)` cannot be sent between threads safely
[00:06:42]      |
[00:06:42]      = help: the trait `std::marker::Send` is not implemented for `(dyn std::any::Any + std::marker::Sync + 'static)`
[00:06:42]      = note: required because of the requirements on the impl of `std::marker::Send` for `std::ptr::Unique<(dyn std::any::Any + std::marker::Sync + 'static)>`
[00:06:42]      = note: required because it appears within the type `std::boxed::Box<(dyn std::any::Any + std::marker::Sync + 'static)>`
[00:06:42]      = note: required because it appears within the type `std::option::Option<std::boxed::Box<(dyn std::any::Any + std::marker::Sync + 'static)>>`
[00:06:42]      = note: required because of the requirements on the impl of `std::marker::Sync` for `lock_api::rwlock::RwLock<parking_lot::raw_rwlock::RawRwLock, std::option::Option<std::boxed::Box<(dyn std::any::Any + std::marker::Sync + 'static)>>>`
[00:06:42]      = note: required because it appears within the type `rustc_data_structures::sync::RwLock<std::option::Option<std::boxed::Box<(dyn std::any::Any + std::marker::Sync + 'static)>>>`
[00:06:42]      = note: required because it appears within the type `ty::steal::Steal<std::boxed::Box<(dyn std::any::Any + std::marker::Sync + 'static)>>`
[00:06:42]      = note: required because it appears within the type `ty::context::GlobalCtxt<'_>`
[00:06:42]      = note: required because it appears within the type `&ty::context::GlobalCtxt<'_>`
[00:06:42]      = note: required because it appears within the type `ty::context::TyCtxt<'_, '_, '_>`
[00:06:42]      = note: required because it appears within the type `ty::context::tls::ImplicitCtxt<'_, '_, '_>`
[00:06:42]      = note: required by `rustc_data_structures::sync::assert_sync`
[00:06:43] error: aborting due to 2 previous errors
[00:06:43] 
[00:06:43] For more information about this error, try `rustc --explain E0277`.
[00:06:44] error: Could not compile `rustc`.
---
travis_time:end:051844c4:start=1553118416024686537,finish=1553118416031127365,duration=6440828
travis_fold:end:after_failure.3
travis_fold:start:after_failure.4
travis_time:start:0f82baf8
$ ln -s . checkout && for CORE in obj/cores/core.*; do EXE=$(echo $CORE | sed 's|obj/cores/core\.[0-9]*\.!checkout!\(.*\)|\1|;y|!|/|'); if [ -f "$EXE" ]; then printf travis_fold":start:crashlog\n\033[31;1m%s\033[0m\n" "$CORE"; gdb --batch -q -c "$CORE" "$EXE" -iex 'set auto-load off' -iex 'dir src/' -iex 'set sysroot .' -ex bt -ex q; echo travis_fold":"end:crashlog; fi; done || true
travis_fold:end:after_failure.4
travis_fold:start:after_failure.5
travis_time:start:29e5c454
travis_time:start:29e5c454
$ cat ./obj/build/x86_64-unknown-linux-gnu/native/asan/build/lib/asan/clang_rt.asan-dynamic-i386.vers || true
cat: ./obj/build/x86_64-unknown-linux-gnu/native/asan/build/lib/asan/clang_rt.asan-dynamic-i386.vers: No such file or directory
travis_fold:end:after_failure.5
travis_fold:start:after_failure.6
travis_time:start:01f93712
$ dmesg | grep -i kill

I'm a bot! I can only do what humans tell me to, so if this was not helpful or you have suggestions for improvements, please ping or otherwise contact @TimNN. (Feature Requests)

@Zoxc Zoxc mentioned this pull request Mar 21, 2019
@Zoxc Zoxc changed the title [WIP] Turn macro expansion and name resolution into a query Turn macro expansion and name resolution into a query Mar 21, 2019
@bors
Copy link
Contributor

bors commented Mar 26, 2019

☔ The latest upstream changes (presumably #59433) made this pull request unmergeable. Please resolve the merge conflicts.

@bors
Copy link
Contributor

bors commented Mar 27, 2019

☔ The latest upstream changes (presumably #59415) made this pull request unmergeable. Please resolve the merge conflicts.

@Zoxc Zoxc force-pushed the query-expansion branch 2 times, most recently from b7a4e6a to 1b953ee Compare April 2, 2019 02:26
@bors
Copy link
Contributor

bors commented Apr 4, 2019

☔ The latest upstream changes (presumably #59517) made this pull request unmergeable. Please resolve the merge conflicts.

@bors
Copy link
Contributor

bors commented Apr 12, 2019

☔ The latest upstream changes (presumably #59536) made this pull request unmergeable. Please resolve the merge conflicts.

@bors
Copy link
Contributor

bors commented Apr 14, 2019

☔ The latest upstream changes (presumably #59967) made this pull request unmergeable. Please resolve the merge conflicts.

@Dylan-DPC-zz
Copy link

ping from triage r? @michaelwoerister

@Mark-Simulacrum
Copy link
Member

Visiting from triage, I'm closing the series of PRs here other than the first one since they will likely only make progress sequentially and it doesn't help to have all of them open. For reference, this is blocked on #59205 which is then blocked on #59064.

Feel free to reopen as those PRs merge and/or make progress.

@Mark-Simulacrum Mark-Simulacrum added S-blocked-closed and removed S-waiting-on-review Status: Awaiting review from the assignee but also interested parties. labels May 18, 2019
@Zoxc Zoxc reopened this Jun 18, 2019
@jonas-schievink jonas-schievink added S-waiting-on-review Status: Awaiting review from the assignee but also interested parties. and removed S-blocked-closed labels Jun 22, 2019
@bors
Copy link
Contributor

bors commented Jun 25, 2019

☔ The latest upstream changes (presumably #62119) made this pull request unmergeable. Please resolve the merge conflicts.

@totsteps
Copy link
Member

totsteps commented Jul 23, 2019

ping from triage, @michaelwoerister any updates on this?

@Alexendoo Alexendoo added S-blocked Status: Marked as blocked ❌ on something else such as an RFC or other implementation work. and removed S-waiting-on-review Status: Awaiting review from the assignee but also interested parties. labels Aug 7, 2019
@Alexendoo
Copy link
Member

Triage: Labelled as blocked, see #59282 (comment)

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
S-blocked Status: Marked as blocked ❌ on something else such as an RFC or other implementation work.
Projects
None yet
Development

Successfully merging this pull request may close these issues.

10 participants