[INFO] cloning repository https://github.com/rubenzuurman/python-script-analyser
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/rubenzuurman/python-script-analyser" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frubenzuurman%2Fpython-script-analyser", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frubenzuurman%2Fpython-script-analyser'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 5821f3e1358105656176310ad459ceaba398b105
[INFO] testing rubenzuurman/python-script-analyser against master#1871252fc8bb672d40787e67404e6eaae7059369 for pr-125151
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frubenzuurman%2Fpython-script-analyser" "/workspace/builds/worker-3-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc1/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/rubenzuurman/python-script-analyser on toolchain 1871252fc8bb672d40787e67404e6eaae7059369
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1871252fc8bb672d40787e67404e6eaae7059369" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/rubenzuurman/python-script-analyser
[INFO] finished tweaking git repo https://github.com/rubenzuurman/python-script-analyser
[INFO] tweaked toml for git repo https://github.com/rubenzuurman/python-script-analyser written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] crate git repo https://github.com/rubenzuurman/python-script-analyser already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1871252fc8bb672d40787e67404e6eaae7059369" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[ERROR] error running command: no output for 300 seconds
[ERROR] this task or one of its parent failed!
[ERROR] no output for 300 seconds
[ERROR] note: run with `RUST_BACKTRACE=1` to display a backtrace.
[INFO] testing rubenzuurman/python-script-analyser against try#b642703cf9526da1e72c0b6755753b939a9c6b6d for pr-125151
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frubenzuurman%2Fpython-script-analyser" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/rubenzuurman/python-script-analyser on toolchain b642703cf9526da1e72c0b6755753b939a9c6b6d
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b642703cf9526da1e72c0b6755753b939a9c6b6d" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/rubenzuurman/python-script-analyser
[INFO] finished tweaking git repo https://github.com/rubenzuurman/python-script-analyser
[INFO] tweaked toml for git repo https://github.com/rubenzuurman/python-script-analyser written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] crate git repo https://github.com/rubenzuurman/python-script-analyser already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b642703cf9526da1e72c0b6755753b939a9c6b6d" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:59a85a07ab18ca8720692f8e61effa1c651d9e2ca591e072c2b212bb91a6b8b5" "/opt/rustwide/cargo-home/bin/cargo" "+b642703cf9526da1e72c0b6755753b939a9c6b6d" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 9f09da187e72224f3f656ee9f3450294b51d3c34b9e6c436940435017feb85ae
[INFO] running `Command { std: "docker" "start" "-a" "9f09da187e72224f3f656ee9f3450294b51d3c34b9e6c436940435017feb85ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "9f09da187e72224f3f656ee9f3450294b51d3c34b9e6c436940435017feb85ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9f09da187e72224f3f656ee9f3450294b51d3c34b9e6c436940435017feb85ae", kill_on_drop: false }`
[INFO] [stdout] 9f09da187e72224f3f656ee9f3450294b51d3c34b9e6c436940435017feb85ae
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:59a85a07ab18ca8720692f8e61effa1c651d9e2ca591e072c2b212bb91a6b8b5" "/opt/rustwide/cargo-home/bin/cargo" "+b642703cf9526da1e72c0b6755753b939a9c6b6d" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 75eb5fc734fd32a90272a2b75e596f5ee8c9a139005e800131c5e09403e4cb0e
[INFO] running `Command { std: "docker" "start" "-a" "75eb5fc734fd32a90272a2b75e596f5ee8c9a139005e800131c5e09403e4cb0e", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.5.0
[INFO] [stderr]    Compiling regex-syntax v0.7.3
[INFO] [stderr]    Compiling aho-corasick v1.0.2
[INFO] [stderr]    Compiling regex-automata v0.3.2
[INFO] [stderr]    Compiling regex v1.9.1
[INFO] [stderr]    Compiling python-script-analyser v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 6.25s
[INFO] running `Command { std: "docker" "inspect" "75eb5fc734fd32a90272a2b75e596f5ee8c9a139005e800131c5e09403e4cb0e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "75eb5fc734fd32a90272a2b75e596f5ee8c9a139005e800131c5e09403e4cb0e", kill_on_drop: false }`
[INFO] [stdout] 75eb5fc734fd32a90272a2b75e596f5ee8c9a139005e800131c5e09403e4cb0e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:59a85a07ab18ca8720692f8e61effa1c651d9e2ca591e072c2b212bb91a6b8b5" "/opt/rustwide/cargo-home/bin/cargo" "+b642703cf9526da1e72c0b6755753b939a9c6b6d" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2474477e7120657b083220a802409cc8ca59e06ff0ba756d06bb843544180245
[INFO] running `Command { std: "docker" "start" "-a" "2474477e7120657b083220a802409cc8ca59e06ff0ba756d06bb843544180245", kill_on_drop: false }`
[INFO] [stderr]    Compiling python-script-analyser v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.79s
[INFO] running `Command { std: "docker" "inspect" "2474477e7120657b083220a802409cc8ca59e06ff0ba756d06bb843544180245", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2474477e7120657b083220a802409cc8ca59e06ff0ba756d06bb843544180245", kill_on_drop: false }`
[INFO] [stdout] 2474477e7120657b083220a802409cc8ca59e06ff0ba756d06bb843544180245
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:59a85a07ab18ca8720692f8e61effa1c651d9e2ca591e072c2b212bb91a6b8b5" "/opt/rustwide/cargo-home/bin/cargo" "+b642703cf9526da1e72c0b6755753b939a9c6b6d" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 58e22c03e79d845f98842829b52acc872b380f7a39ed5afd6da2bff11dc2324a
[INFO] running `Command { std: "docker" "start" "-a" "58e22c03e79d845f98842829b52acc872b380f7a39ed5afd6da2bff11dc2324a", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/python_script_analyser-ff1d56e91c4adcf2)
[INFO] [stdout] 
[INFO] [stdout] running 43 tests
[INFO] [stdout] test tests::test_contains_arithmetic_symbols_not_enclosed ... ok
[INFO] [stdout] test tests::test_create_assignment ... ok
[INFO] [stdout] test tests::test_create_line ... ok
[INFO] [stdout] test tests::test_get_file_lines ... ok
[INFO] [stdout] test tests::test_get_indentation_length ... ok
[INFO] [stdout] test tests::test_get_lines_for_test ... ok
[INFO] [stdout] test tests::test_function_at_end_of_file_no_newline ... ok
[INFO] [stdout] test tests::test_is_enclosed_in_brackets ... ok
[INFO] [stdout] WARNING: The input file might not be a python file (file has no extension).
[INFO] [stdout] test tests::test_create_file_empty_name ... ok
[INFO] [stdout] WARNING: The input file might not be a python file (extension='notpy', not 'py').
[INFO] [stdout] WARNING: The input file might not be a python file (file has no extension).
[INFO] [stdout] WARNING: Line 1: Import cannot contain spaces 'import b a n a a n' (specifically 'b a n a a n').
[INFO] [stdout] WARNING: Line 1: Import cannot contain spaces 'from os import list dir' (specifically 'list dir').
[INFO] [stdout] test tests::test_file_print_warnings ... ok
[INFO] [stdout] test tests::test_is_string_literal ... ok
[INFO] [stdout] test tests::test_is_array_access ... ok
[INFO] [stdout] test tests::test_line_is_assignment ... ok
[INFO] [stdout] test tests::test_regex_pattern_array_dict_access_expression ... ok
[INFO] [stdout] test tests::test_regex_pattern_class_start ... ok
[INFO] [stdout] test tests::test_is_function_call ... ok
[INFO] [stdout] test tests::test_regex_pattern_for_loop ... ok
[INFO] [stdout] test tests::test_regex_pattern_class_variable ... ok
[INFO] [stdout] test tests::test_regex_pattern_from_import ... ok
[INFO] [stdout] test tests::test_regex_pattern_function_call_expression ... ok
[INFO] [stdout] test tests::test_regex_pattern_import ... ok
[INFO] [stdout] test tests::test_regex_pattern_indentation ... ok
[INFO] [stdout] test tests::test_regex_pattern_function_start ... ok
[INFO] [stdout] test tests::test_regex_pattern_while_loop ... ok
[INFO] [stdout] test tests::test_regex_pattern_variable_name_expression ... ok
[INFO] [stdout] test tests::test_regex_pattern_with_statement ... ok
[INFO] [stdout] test tests::test_remove_empty_lines ... ok
[INFO] [stdout] test tests::test_vec_str_to_vec_line ... ok
[INFO] [stdout] test tests::test_split_by_char ... ok
[INFO] [stdout] [Line 5] WARNING: Variable name does not exist or is out of scope 'rnd'.
[INFO] [stdout] [Line 6] WARNING: Variable name does not exist or is out of scope 'math'.
[INFO] [stdout] test tests::test_class_get_source ... ok
[INFO] [stdout] WARNING: Line 4: Import cannot contain spaces 'import m ath, b a n aan' (specifically 'm ath').
[INFO] [stdout] WARNING: Line 4: Import cannot contain spaces 'import m ath, b a n aan' (specifically 'b a n aan').
[INFO] [stdout] WARNING: Line 6: Import cannot contain spaces 'from banaan import a pp le' (specifically 'a pp le').
[INFO] [stdout] test tests::test_line_functions ... ok
[INFO] [stdout] WARNING: Invalid class definition on the first line of the source ''.
[INFO] [stdout] test tests::test_create_class ... ok
[INFO] [stdout] test tests::test_handle_assignment_right_side_single ... ok
[INFO] [stdout] WARNING: Invalid function definition on the first line of the source 'df function(a=5, b=10):'.
[INFO] [stdout] test tests::test_create_function ... ok
[INFO] [stdout] test tests::test_handle_assignment_left_side ... ok
[INFO] [stdout] test tests::test_get_variables_from_assignment ... ok
[INFO] [stdout] test tests::test_handle_assignment_right_side ... ok
[INFO] [stdout] test tests::test_as_string ... ok
[INFO] [stdout] test tests::test_partialeq_implementations ... ok
[INFO] [stdout] test tests::test_handle_assignment_left_side_single ... ok
[INFO] [stdout] [Line 12] WARNING: Variable name does not exist or is out of scope 'j'.
[INFO] [stdout] [Line 17] WARNING: Variable name does not exist or is out of scope 'i'.
[INFO] [stdout] [Line 18] WARNING: Variable name does not exist or is out of scope 'i'.
[INFO] [stdout] [Line 19] WARNING: Variable name does not exist or is out of scope 'i'.
[INFO] [stdout] [Line 29] WARNING: Variable name does not exist or is out of scope 'j'.
[INFO] [stdout] test tests::test_handle_assignment_expression ... ok
[INFO] [stdout] [Line 6] WARNING: Variable name does not exist or is out of scope 'ID'.
[INFO] [stdout] [Line 25] WARNING: Variable name does not exist or is out of scope 'j'.
[INFO] [stdout] [Line 30] WARNING: Variable name does not exist or is out of scope 'i'.
[INFO] [stdout] [Line 30] WARNING: Variable name does not exist or is out of scope 'j'.
[INFO] [stdout] [Line 30] WARNING: Variable name does not exist or is out of scope 'k'.
[INFO] [stdout] [Line 2] WARNING: For loop on the last line of the function 'func'.
[INFO] [stdout] [Line 5] WARNING: While loop on the last line of the function 'func2'.
[INFO] [stdout] WARNING: Line 1: Import cannot contain spaces 'from __future__ import annotations, m a n g o' (specifically 'm a n g o').
[INFO] [stdout] WARNING: Line 3: Import cannot contain spaces 'import gc, b a n a a n' (specifically 'b a n a a n').
[INFO] [stdout] test tests::test_create_file ... ok
[INFO] [stdout] [Line 4] WARNING: Variable name does not exist or is out of scope 'a'.
[INFO] [stdout] [Line 5] WARNING: Variable name does not exist or is out of scope 'a'.
[INFO] [stdout] [Line 6] WARNING: Variable name does not exist or is out of scope 'b'.
[INFO] [stdout] [Line 7] WARNING: Variable name does not exist or is out of scope 'b'.
[INFO] [stdout] [Line 9] WARNING: Variable name does not exist or is out of scope 'c'.
[INFO] [stdout] [Line 14] WARNING: Variable name does not exist or is out of scope 'file'.
[INFO] [stdout] [Line 17] WARNING: Variable name does not exist or is out of scope 'socket'.
[INFO] [stdout] [Line 2] WARNING: Variable name does not exist or is out of scope 'var3'.
[INFO] [stdout] [Line 3] WARNING: Variable name does not exist or is out of scope 'math'.
[INFO] [stdout] [Line 4] WARNING: Variable name does not exist or is out of scope 'var4'.
[INFO] [stdout] test tests::test_file_scan ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 43 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 9.56s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/python_script_analyser-f03d8afab0d531a6)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests python_script_analyser
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "58e22c03e79d845f98842829b52acc872b380f7a39ed5afd6da2bff11dc2324a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "58e22c03e79d845f98842829b52acc872b380f7a39ed5afd6da2bff11dc2324a", kill_on_drop: false }`
[INFO] [stdout] 58e22c03e79d845f98842829b52acc872b380f7a39ed5afd6da2bff11dc2324a