Dec 13 23:00:52.196 INFO testing craigmayhew/id3-music-organiser against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 13 23:00:52.196 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +bd47d6825bf4090517549d33cfef10d3300b4a75-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 13 23:00:52.819 INFO [stdout] a58c076c82f5d24def42d906695e320e7a5c33a6c07d76e3af093121eaf59439 Dec 13 23:00:52.823 INFO running `"docker" "start" "-a" "a58c076c82f5d24def42d906695e320e7a5c33a6c07d76e3af093121eaf59439"` Dec 13 23:00:53.965 INFO [stderr] usermod: no changes Dec 13 23:00:54.311 INFO [stderr] Compiling crossbeam-epoch v0.6.1 Dec 13 23:00:54.319 INFO [stderr] Compiling parking_lot_core v0.3.1 Dec 13 23:00:54.319 INFO [stderr] Compiling regex v1.0.5 Dec 13 23:00:58.033 INFO [stderr] Compiling parking_lot v0.6.4 Dec 13 23:01:00.421 INFO [stderr] Compiling crossbeam-channel v0.2.6 Dec 13 23:01:07.779 INFO [stderr] Compiling globset v0.4.2 Dec 13 23:01:07.779 INFO [stderr] Compiling docopt v1.0.2 Dec 13 23:01:07.779 INFO [stderr] Compiling id3 v0.2.5 Dec 13 23:01:13.079 INFO [stderr] Compiling ignore v0.4.4 Dec 13 23:01:24.310 INFO [stderr] Compiling globwalk v0.5.0 Dec 13 23:01:25.852 INFO [stderr] Compiling cucumber_rust v0.5.0 Dec 13 23:01:29.591 INFO [stderr] Compiling id3org v0.1.1 (/source) Dec 13 23:01:33.662 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 39.67s Dec 13 23:01:33.674 INFO [stderr] su: No module specific data is present Dec 13 23:01:34.577 INFO running `"docker" "inspect" "a58c076c82f5d24def42d906695e320e7a5c33a6c07d76e3af093121eaf59439"` Dec 13 23:01:34.803 INFO running `"docker" "rm" "-f" "a58c076c82f5d24def42d906695e320e7a5c33a6c07d76e3af093121eaf59439"` Dec 13 23:01:35.023 INFO [stdout] a58c076c82f5d24def42d906695e320e7a5c33a6c07d76e3af093121eaf59439 Dec 13 23:01:35.023 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +bd47d6825bf4090517549d33cfef10d3300b4a75-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 13 23:01:35.288 INFO [stdout] 9e3c7424b17c2d7710c432743b2a96c5ea4595c8156e131749c3bf98eb99641d Dec 13 23:01:35.290 INFO running `"docker" "start" "-a" "9e3c7424b17c2d7710c432743b2a96c5ea4595c8156e131749c3bf98eb99641d"` Dec 13 23:01:36.409 INFO [stderr] usermod: no changes Dec 13 23:01:36.615 INFO [stderr] Compiling id3org v0.1.1 (/source) Dec 13 23:01:38.479 INFO [stderr] warning: unused import: `std::io::Write` Dec 13 23:01:38.479 INFO [stderr] --> src/main.rs:25:5 Dec 13 23:01:38.479 INFO [stderr] | Dec 13 23:01:38.479 INFO [stderr] 25 | use std::io::Write; Dec 13 23:01:38.479 INFO [stderr] | ^^^^^^^^^^^^^^ Dec 13 23:01:38.479 INFO [stderr] | Dec 13 23:01:38.479 INFO [stderr] = note: #[warn(unused_imports)] on by default Dec 13 23:01:38.479 INFO [stderr] Dec 13 23:01:40.103 INFO [stderr] warning: unused variable: `step` Dec 13 23:01:40.103 INFO [stderr] --> tests/cucumber.rs:22:50 Dec 13 23:01:40.103 INFO [stderr] | Dec 13 23:01:40.103 INFO [stderr] 22 | given "I am trying out Cucumber" |world, step| { Dec 13 23:01:40.103 INFO [stderr] | ^^^^ help: consider using `_step` instead Dec 13 23:01:40.103 INFO [stderr] | Dec 13 23:01:40.103 INFO [stderr] = note: #[warn(unused_variables)] on by default Dec 13 23:01:40.103 INFO [stderr] Dec 13 23:01:40.103 INFO [stderr] warning: unused variable: `step` Dec 13 23:01:40.103 INFO [stderr] --> tests/cucumber.rs:27:51 Dec 13 23:01:40.103 INFO [stderr] | Dec 13 23:01:40.103 INFO [stderr] 27 | when "I consider what I am doing" |world, step| { Dec 13 23:01:40.103 INFO [stderr] | ^^^^ help: consider using `_step` instead Dec 13 23:01:40.103 INFO [stderr] Dec 13 23:01:40.103 INFO [stderr] warning: unused variable: `step` Dec 13 23:01:40.103 INFO [stderr] --> tests/cucumber.rs:33:48 Dec 13 23:01:40.103 INFO [stderr] | Dec 13 23:01:40.103 INFO [stderr] 33 | then "I am interested in ATDD" |world, step| { Dec 13 23:01:40.103 INFO [stderr] | ^^^^ help: consider using `_step` instead Dec 13 23:01:40.103 INFO [stderr] Dec 13 23:01:40.103 INFO [stderr] warning: unused variable: `world` Dec 13 23:01:40.103 INFO [stderr] --> tests/cucumber.rs:38:55 Dec 13 23:01:40.103 INFO [stderr] | Dec 13 23:01:40.103 INFO [stderr] 38 | then regex r"^we can (.*) rules with regex$" |world, matches, step| { Dec 13 23:01:40.103 INFO [stderr] | ^^^^^ help: consider using `_world` instead Dec 13 23:01:40.103 INFO [stderr] Dec 13 23:01:40.103 INFO [stderr] warning: unused variable: `step` Dec 13 23:01:40.103 INFO [stderr] --> tests/cucumber.rs:38:71 Dec 13 23:01:40.103 INFO [stderr] | Dec 13 23:01:40.103 INFO [stderr] 38 | then regex r"^we can (.*) rules with regex$" |world, matches, step| { Dec 13 23:01:40.103 INFO [stderr] | ^^^^ help: consider using `_step` instead Dec 13 23:01:40.103 INFO [stderr] Dec 13 23:01:40.103 INFO [stderr] warning: unused variable: `world` Dec 13 23:01:40.103 INFO [stderr] --> tests/cucumber.rs:43:77 Dec 13 23:01:40.103 INFO [stderr] | Dec 13 23:01:40.103 INFO [stderr] 43 | then regex r"^we can also match (\d+) (.+) types$" (usize, String) |world, num, word, step| { Dec 13 23:01:40.103 INFO [stderr] | ^^^^^ help: consider using `_world` instead Dec 13 23:01:40.103 INFO [stderr] Dec 13 23:01:40.103 INFO [stderr] warning: unused variable: `step` Dec 13 23:01:40.104 INFO [stderr] --> tests/cucumber.rs:43:95 Dec 13 23:01:40.104 INFO [stderr] | Dec 13 23:01:40.104 INFO [stderr] 43 | then regex r"^we can also match (\d+) (.+) types$" (usize, String) |world, num, word, step| { Dec 13 23:01:40.104 INFO [stderr] | ^^^^ help: consider using `_step` instead Dec 13 23:01:40.104 INFO [stderr] Dec 13 23:01:40.104 INFO [stderr] warning: unused variable: `scenario` Dec 13 23:01:40.104 INFO [stderr] --> tests/cucumber.rs:52:25 Dec 13 23:01:40.104 INFO [stderr] | Dec 13 23:01:40.104 INFO [stderr] 52 | before!(a_before_fn => |scenario| { Dec 13 23:01:40.104 INFO [stderr] | ^^^^^^^^ help: consider using `_scenario` instead Dec 13 23:01:40.104 INFO [stderr] Dec 13 23:01:40.104 INFO [stderr] warning: unused variable: `scenario` Dec 13 23:01:40.104 INFO [stderr] --> tests/cucumber.rs:57:24 Dec 13 23:01:40.104 INFO [stderr] | Dec 13 23:01:40.104 INFO [stderr] 57 | after!(an_after_fn => |scenario| { Dec 13 23:01:40.104 INFO [stderr] | ^^^^^^^^ help: consider using `_scenario` instead Dec 13 23:01:40.104 INFO [stderr] Dec 13 23:01:44.535 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 8.06s Dec 13 23:01:44.535 INFO [stderr] su: No module specific data is present Dec 13 23:01:45.199 INFO running `"docker" "inspect" "9e3c7424b17c2d7710c432743b2a96c5ea4595c8156e131749c3bf98eb99641d"` Dec 13 23:01:45.413 INFO running `"docker" "rm" "-f" "9e3c7424b17c2d7710c432743b2a96c5ea4595c8156e131749c3bf98eb99641d"` Dec 13 23:01:45.716 INFO [stdout] 9e3c7424b17c2d7710c432743b2a96c5ea4595c8156e131749c3bf98eb99641d Dec 13 23:01:45.718 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +bd47d6825bf4090517549d33cfef10d3300b4a75-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 13 23:01:46.095 INFO [stdout] f5215abd8b3682ad35a5da18972d54b0fc5f6f39cad598b2fc94aebf86fc6cf8 Dec 13 23:01:46.103 INFO running `"docker" "start" "-a" "f5215abd8b3682ad35a5da18972d54b0fc5f6f39cad598b2fc94aebf86fc6cf8"` Dec 13 23:01:46.734 INFO [stderr] usermod: no changes Dec 13 23:01:46.999 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.24s Dec 13 23:01:47.007 INFO [stderr] Running /target/debug/deps/id3org-1c05dbad8f7ce680 Dec 13 23:01:47.009 INFO [stdout] Dec 13 23:01:47.009 INFO [stdout] running 15 tests Dec 13 23:01:47.022 INFO [stdout] test album::album_artist_basic ... ok Dec 13 23:01:47.023 INFO [stdout] test album::album_album_artist_feat_case_insensitive ... ok Dec 13 23:01:47.023 INFO [stdout] test album::album_album_artist_feat ... ok Dec 13 23:01:47.023 INFO [stdout] test album::album_basic ... ok Dec 13 23:01:47.023 INFO [stdout] test album::album_id3_tags_not_readable ... ok Dec 13 23:01:47.025 INFO [stdout] test album::album_remove_bad_chars ... ok Dec 13 23:01:47.025 INFO [stdout] test artist::artist_remove_bad_chars ... ok Dec 13 23:01:47.025 INFO [stdout] test album::album_album_featuring ... ok Dec 13 23:01:47.031 INFO [stdout] test artist::artist_basic ... ok Dec 13 23:01:47.043 INFO [stdout] test title::title_basic ... ok Dec 13 23:01:47.043 INFO [stdout] test title::title_remove_bad_chars ... ok Dec 13 23:01:47.043 INFO [stdout] test artist::artist_remove_ft ... ok Dec 13 23:01:47.059 INFO [stdout] test artist::artist_remove_feat_case_insensitive ... ok Dec 13 23:01:47.059 INFO [stdout] test artist::artist_id3_tags_not_readable ... ok Dec 13 23:01:47.119 INFO [stdout] test destination_path_with_file_name::check_creates_file ... FAILED Dec 13 23:01:47.119 INFO [stdout] Dec 13 23:01:47.119 INFO [stdout] failures: Dec 13 23:01:47.119 INFO [stdout] Dec 13 23:01:47.119 INFO [stdout] ---- destination_path_with_file_name::check_creates_file stdout ---- Dec 13 23:01:47.119 INFO [stdout] thread 'destination_path_with_file_name::check_creates_file' panicked at 'create_dir_all failed with an error', src/main.rs:178:16 Dec 13 23:01:47.119 INFO [stdout] stack backtrace: Dec 13 23:01:47.119 INFO [stdout] 0: 0x5638a4e26593 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 13 23:01:47.119 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 13 23:01:47.119 INFO [stdout] 1: 0x5638a4e20d28 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 13 23:01:47.119 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 13 23:01:47.119 INFO [stdout] 2: 0x5638a4e25502 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 13 23:01:47.119 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 13 23:01:47.119 INFO [stdout] at src/libstd/panicking.rs:210 Dec 13 23:01:47.119 INFO [stdout] 3: 0x5638a4e251f7 - std::panicking::default_hook::h1134a4d34aab4428 Dec 13 23:01:47.119 INFO [stdout] at src/libstd/panicking.rs:218 Dec 13 23:01:47.119 INFO [stdout] 4: 0x5638a4e25c10 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 13 23:01:47.119 INFO [stdout] at src/libstd/panicking.rs:487 Dec 13 23:01:47.119 INFO [stdout] 5: 0x5638a4db9457 - std::panicking::begin_panic::hbeacfa6e2928e8b4 Dec 13 23:01:47.119 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:421 Dec 13 23:01:47.119 INFO [stdout] 6: 0x5638a4c058af - id3org::destination_path_with_file_name::setup::h75057120305a4be6 Dec 13 23:01:47.119 INFO [stdout] at src/main.rs:178 Dec 13 23:01:47.119 INFO [stdout] 7: 0x5638a4c05cfb - id3org::destination_path_with_file_name::check_creates_file::h028a2d9d90b81a15 Dec 13 23:01:47.119 INFO [stdout] at src/main.rs:208 Dec 13 23:01:47.119 INFO [stdout] 8: 0x5638a4c05cd9 - id3org::destination_path_with_file_name::check_creates_file::{{closure}}::h11e3b0f1ca5bc58a Dec 13 23:01:47.119 INFO [stdout] at src/main.rs:207 Dec 13 23:01:47.119 INFO [stdout] 9: 0x5638a4c0019d - core::ops::function::FnOnce::call_once::hd4f37f291c7e0bdf Dec 13 23:01:47.119 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 13 23:01:47.119 INFO [stdout] 10: 0x5638a4c0ee2e - >::call_box::h3cf534772c089f15 Dec 13 23:01:47.119 INFO [stdout] at src/libtest/lib.rs:1483 Dec 13 23:01:47.119 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 13 23:01:47.119 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 13 23:01:47.119 INFO [stdout] 11: 0x5638a4e35c79 - __rust_maybe_catch_panic Dec 13 23:01:47.119 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 23:01:47.119 INFO [stdout] 12: 0x5638a4c2c7d7 - test::run_test::run_test_inner::{{closure}}::h5f14673685eef7c8 Dec 13 23:01:47.119 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 13 23:01:47.119 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 13 23:01:47.119 INFO [stdout] at src/libtest/lib.rs:1438 Dec 13 23:01:47.119 INFO [stdout] 13: 0x5638a4c076e4 - std::sys_common::backtrace::__rust_begin_short_backtrace::h96de92a7d062bb5f Dec 13 23:01:47.119 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 13 23:01:47.119 INFO [stdout] 14: 0x5638a4c07d54 - std::panicking::try::do_call::h6a71e68ea4ad0d8a Dec 13 23:01:47.119 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 13 23:01:47.119 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 13 23:01:47.119 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 13 23:01:47.120 INFO [stdout] 15: 0x5638a4e35c79 - __rust_maybe_catch_panic Dec 13 23:01:47.120 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 23:01:47.120 INFO [stdout] 16: 0x5638a4c0ed0c - >::call_box::h260fd1ad8e2854a6 Dec 13 23:01:47.120 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 13 23:01:47.120 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 13 23:01:47.120 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 13 23:01:47.120 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 13 23:01:47.120 INFO [stdout] 17: 0x5638a4e2993d - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 13 23:01:47.120 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 13 23:01:47.120 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 13 23:01:47.120 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 13 23:01:47.120 INFO [stdout] 18: 0x7f3f780af6b9 - start_thread Dec 13 23:01:47.120 INFO [stdout] 19: 0x7f3f77bcf41c - clone Dec 13 23:01:47.120 INFO [stdout] 20: 0x0 - Dec 13 23:01:47.120 INFO [stdout] Dec 13 23:01:47.120 INFO [stdout] Dec 13 23:01:47.120 INFO [stdout] failures: Dec 13 23:01:47.120 INFO [stdout] destination_path_with_file_name::check_creates_file Dec 13 23:01:47.120 INFO [stdout] Dec 13 23:01:47.120 INFO [stdout] test result: FAILED. 14 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 23:01:47.120 INFO [stdout] Dec 13 23:01:47.130 INFO [stderr] error: test failed, to rerun pass '--bin id3org' Dec 13 23:01:47.130 INFO [stderr] su: No module specific data is present Dec 13 23:01:47.545 INFO running `"docker" "inspect" "f5215abd8b3682ad35a5da18972d54b0fc5f6f39cad598b2fc94aebf86fc6cf8"` Dec 13 23:01:47.688 INFO running `"docker" "rm" "-f" "f5215abd8b3682ad35a5da18972d54b0fc5f6f39cad598b2fc94aebf86fc6cf8"` Dec 13 23:01:47.754 INFO [stdout] f5215abd8b3682ad35a5da18972d54b0fc5f6f39cad598b2fc94aebf86fc6cf8