2026-01-23 11:51.11: New job: test ocsigen/lwt https://github.com/ocsigen/lwt.git#refs/pull/967/head (107e18c20f3186b7a6f13f1d528757f9078eacc2) (linux-arm64:debian-13-4.14_arm32_opam-2.5) Base: ocaml/opam:debian-13-ocaml-4.14@sha256:e93c896d8da84c997afd2cc78eb54a19c207f4947651d2263a1fae66ae581f7e Opam project build To reproduce locally: git clone --recursive "https://github.com/ocsigen/lwt.git" && cd "lwt" && git fetch origin "refs/pull/967/head" && git reset --hard 107e18c2 cat > Dockerfile <<'END-OF-DOCKERFILE' FROM ocaml/opam:debian-13-ocaml-4.14@sha256:e93c896d8da84c997afd2cc78eb54a19c207f4947651d2263a1fae66ae581f7e # debian-13-4.14_arm32_opam-2.5 USER 1000:1000 SHELL [ "/usr/bin/linux32", "/bin/sh", "-c" ] ENV CLICOLOR_FORCE="1" ENV OPAMCOLOR="always" WORKDIR /src RUN sudo ln -f /usr/bin/opam-2.5 /usr/bin/opam RUN opam init --reinit -ni RUN uname -rs && opam exec -- ocaml -version && opam --version WORKDIR /src RUN sudo chown opam /src RUN cd ~/opam-repository && (git cat-file -e 873cb18c37b308713d11ad3894c4bb78d73fb3e7 || git fetch origin master) && git reset -q --hard 873cb18c37b308713d11ad3894c4bb78d73fb3e7 && git log --no-decorate -n1 --oneline && opam update -u COPY --chown=1000:1000 lwt_runtime_events.opam lwt_retry.opam lwt_react.opam lwt_ppx__ppx_let_tests.opam lwt_ppx.opam lwt_direct.opam lwt.opam ./ RUN opam pin add -yn lwt_runtime_events.dev './' && \ opam pin add -yn lwt_retry.dev './' && \ opam pin add -yn lwt_react.dev './' && \ opam pin add -yn lwt_ppx__ppx_let_tests.dev './' && \ opam pin add -yn lwt_ppx.dev './' && \ opam pin add -yn lwt_direct.dev './' && \ opam pin add -yn lwt.dev './' RUN echo '(lang dune 3.0)' > './dune-project' ENV DEPS="base-bigarray.base base-bytes.base base-threads.base base-unix.base cppo.1.8.0 csexp.1.5.2 dune.3.21.0 dune-configurator.3.21.0 ocaml.4.14.2 ocaml-base-compiler.4.14.2 ocaml-compiler-libs.v0.12.4 ocaml-config.2 ocaml-options-vanilla.1 ocamlbuild.0.16.1 ocamlfind.1.9.8 ocplib-endian.1.2 ppx_derivers.1.2.1 ppxlib.0.37.0 react.1.2.2 sexplib0.v0.17.0 stdlib-shims.0.3.0 topkg.1.1.1" ENV CI="true" ENV OCAMLCI="true" RUN opam update --depexts && opam install --cli=2.5 --depext-only -y lwt_retry.dev lwt_react.dev lwt_ppx.dev lwt.dev $DEPS RUN opam install $DEPS COPY --chown=1000:1000 . /src RUN opam exec -- dune build --only-packages=lwt_retry,lwt_react,lwt_ppx,lwt @install @check @runtest && rm -rf _build END-OF-DOCKERFILE docker build . END-REPRO-BLOCK 2026-01-23 11:51.11: Using cache hint "ocsigen/lwt-ocaml/opam:debian-13-ocaml-4.14@sha256:e93c896d8da84c997afd2cc78eb54a19c207f4947651d2263a1fae66ae581f7e-debian-13-4.14_arm32_opam-2.5-f80664a0fc43791440912ae83095cdb3" 2026-01-23 11:51.11: Using OBuilder spec: ((from ocaml/opam:debian-13-ocaml-4.14@sha256:e93c896d8da84c997afd2cc78eb54a19c207f4947651d2263a1fae66ae581f7e) (comment debian-13-4.14_arm32_opam-2.5) (user (uid 1000) (gid 1000)) (shell /usr/bin/linux32 /bin/sh -c) (env CLICOLOR_FORCE 1) (env OPAMCOLOR always) (workdir /src) (run (shell "sudo ln -f /usr/bin/opam-2.5 /usr/bin/opam")) (run (shell "opam init --reinit -ni")) (run (shell "uname -rs && opam exec -- ocaml -version && opam --version")) (workdir /src) (run (shell "sudo chown opam /src")) (run (cache (opam-archives (target /home/opam/.opam/download-cache))) (network host) (shell "cd ~/opam-repository && (git cat-file -e 873cb18c37b308713d11ad3894c4bb78d73fb3e7 || git fetch origin master) && git reset -q --hard 873cb18c37b308713d11ad3894c4bb78d73fb3e7 && git log --no-decorate -n1 --oneline && opam update -u")) (copy (src lwt_runtime_events.opam lwt_retry.opam lwt_react.opam lwt_ppx__ppx_let_tests.opam lwt_ppx.opam lwt_direct.opam lwt.opam) (dst ./)) (run (network host) (shell "opam pin add -yn lwt_runtime_events.dev './' && \ \nopam pin add -yn lwt_retry.dev './' && \ \nopam pin add -yn lwt_react.dev './' && \ \nopam pin add -yn lwt_ppx__ppx_let_tests.dev './' && \ \nopam pin add -yn lwt_ppx.dev './' && \ \nopam pin add -yn lwt_direct.dev './' && \ \nopam pin add -yn lwt.dev './'")) (run (network host) (shell "echo '(lang dune 3.0)' > './dune-project'")) (env DEPS "base-bigarray.base base-bytes.base base-threads.base base-unix.base cppo.1.8.0 csexp.1.5.2 dune.3.21.0 dune-configurator.3.21.0 ocaml.4.14.2 ocaml-base-compiler.4.14.2 ocaml-compiler-libs.v0.12.4 ocaml-config.2 ocaml-options-vanilla.1 ocamlbuild.0.16.1 ocamlfind.1.9.8 ocplib-endian.1.2 ppx_derivers.1.2.1 ppxlib.0.37.0 react.1.2.2 sexplib0.v0.17.0 stdlib-shims.0.3.0 topkg.1.1.1") (env CI true) (env OCAMLCI true) (run (cache (opam-archives (target /home/opam/.opam/download-cache))) (network host) (shell "opam update --depexts && opam install --cli=2.5 --depext-only -y lwt_retry.dev lwt_react.dev lwt_ppx.dev lwt.dev $DEPS")) (run (cache (opam-archives (target /home/opam/.opam/download-cache))) (network host) (shell "opam install $DEPS")) (copy (src .) (dst /src)) (run (shell "opam exec -- dune build --only-packages=lwt_retry,lwt_react,lwt_ppx,lwt @install @check @runtest && rm -rf _build")) ) 2026-01-23 11:51.11: Waiting for resource in pool OCluster 2026-01-23 11:51.11: Waiting for worker… 2026-01-23 11:51.23: Got resource from pool OCluster Building on ainia.caelum.ci.dev All commits already cached HEAD is now at 107e18c20 in CI: print test log (impossible to investigate otherwise) (from ocaml/opam:debian-13-ocaml-4.14@sha256:e93c896d8da84c997afd2cc78eb54a19c207f4947651d2263a1fae66ae581f7e) Unable to find image 'ocaml/opam:debian-13-ocaml-4.14@sha256:e93c896d8da84c997afd2cc78eb54a19c207f4947651d2263a1fae66ae581f7e' locally docker.io/ocaml/opam@sha256:e93c896d8da84c997afd2cc78eb54a19c207f4947651d2263a1fae66ae581f7e: Pulling from ocaml/opam 2d0218a867da: Pulling fs layer 2d0218a867da: Verifying Checksum 2d0218a867da: Download complete 2d0218a867da: Pull complete Digest: sha256:e93c896d8da84c997afd2cc78eb54a19c207f4947651d2263a1fae66ae581f7e Status: Downloaded newer image for ocaml/opam@sha256:e93c896d8da84c997afd2cc78eb54a19c207f4947651d2263a1fae66ae581f7e 2026-01-23 11:51.24 ---> using "782564cf786d233d949e53ada0d8a1d9c96bad68e0873e0b899509bc524a640c" from cache /: (comment debian-13-4.14_arm32_opam-2.5) /: (user (uid 1000) (gid 1000)) /: (shell /usr/bin/linux32 /bin/sh -c) /: (env CLICOLOR_FORCE 1) /: (env OPAMCOLOR always) /: (workdir /src) /src: (run (shell "sudo ln -f /usr/bin/opam-2.5 /usr/bin/opam")) 2026-01-23 11:51.24 ---> using "dafb89c43ab6514d8ce691bee1d9591281f10fe2a2411eef33a23506e9b7fe7d" from cache /src: (run (shell "opam init --reinit -ni")) Configuring from /home/opam/.opamrc and then from built-in defaults. Checking for available remotes: rsync and local, git. - you won't be able to use mercurial repositories unless you install the hg command on your system. - you won't be able to use darcs repositories unless you install the darcs command on your system. This version of opam requires an update to the layout of /home/opam/.opam from version 2.0 to version 2.2, which can't be reverted. You may want to back it up before going further. Continue? [Y/n] y Format upgrade done. <><> Updating repositories ><><><><><><><><><><><><><><><><><><><><><><><><><><> [default] Initialised 2026-01-23 11:51.24 ---> using "b062e0aafb067fe153dac51f72376663282a25b24c8599e9d4839a985ec6f70e" from cache /src: (run (shell "uname -rs && opam exec -- ocaml -version && opam --version")) Linux 6.8.0-85-generic The OCaml toplevel, version 4.14.2 2.5.0 2026-01-23 11:51.24 ---> using "101a749500f504a4831e82315b65a2fec23e1535d7260318f9d30b49e24a69e0" from cache /src: (workdir /src) /src: (run (shell "sudo chown opam /src")) 2026-01-23 11:51.24 ---> using "775076da1c1868fb45638a0563612e1387156c7dac6ab4173fadd11d6bba84d3" from cache /src: (run (cache (opam-archives (target /home/opam/.opam/download-cache))) (network host) (shell "cd ~/opam-repository && (git cat-file -e 873cb18c37b308713d11ad3894c4bb78d73fb3e7 || git fetch origin master) && git reset -q --hard 873cb18c37b308713d11ad3894c4bb78d73fb3e7 && git log --no-decorate -n1 --oneline && opam update -u")) From https://github.com/ocaml/opam-repository * branch master -> FETCH_HEAD 16d9c0eb87..3259a77d95 master -> origin/master 873cb18c37 Merge pull request #29216 from shonfeder/release-dune-3.21.0 <><> Updating package repositories ><><><><><><><><><><><><><><><><><><><><><><> [default] synchronised from git+file:///home/opam/opam-repository Everything as up-to-date as possible (run with --verbose to show unavailable upgrades). However, you may "opam upgrade" these packages explicitly at these versions (e.g. "opam upgrade ocaml.5.4.0"), which will ask permission to downgrade or uninstall the conflicting packages. Nothing to do. # To update the current shell environment, run: eval $(opam env) 2026-01-23 11:51.24 ---> using "d361b5df1500caf736a3a446f0731f22783bb5272f5ea3f011ede6caefb282b0" from cache /src: (copy (src lwt_runtime_events.opam lwt_retry.opam lwt_react.opam lwt_ppx__ppx_let_tests.opam lwt_ppx.opam lwt_direct.opam lwt.opam) (dst ./)) 2026-01-23 11:51.24 ---> using "f5d274b90f48925f13b848e08f96f08c034b080138abc64c2cc89971efda41ef" from cache /src: (run (network host) (shell "opam pin add -yn lwt_runtime_events.dev './' && \ \nopam pin add -yn lwt_retry.dev './' && \ \nopam pin add -yn lwt_react.dev './' && \ \nopam pin add -yn lwt_ppx__ppx_let_tests.dev './' && \ \nopam pin add -yn lwt_ppx.dev './' && \ \nopam pin add -yn lwt_direct.dev './' && \ \nopam pin add -yn lwt.dev './'")) [lwt_runtime_events.dev] synchronised (file:///src) lwt_runtime_events is now pinned to file:///src (version dev) [lwt_retry.dev] synchronised (file:///src) lwt_retry is now pinned to file:///src (version dev) [lwt_react.dev] synchronised (file:///src) lwt_react is now pinned to file:///src (version dev) [NOTE] Package lwt_ppx__ppx_let_tests does not exist in opam repositories registered in the current switch. [lwt_ppx__ppx_let_tests.dev] synchronised (file:///src) [WARNING] Failed checks on lwt_ppx__ppx_let_tests package definition from source at file:///src: warning 62: License doesn't adhere to the SPDX standard, see https://spdx.org/licenses/ : "NOTFORRELEASE" lwt_ppx__ppx_let_tests is now pinned to file:///src (version dev) [lwt_ppx.dev] synchronised (file:///src) lwt_ppx is now pinned to file:///src (version dev) [lwt_direct.dev] synchronised (file:///src) lwt_direct is now pinned to file:///src (version dev) [lwt.dev] synchronised (file:///src) lwt is now pinned to file:///src (version dev) 2026-01-23 11:51.24 ---> using "af6c110ebb8eef6c49ad28c9c8d6cd05682378e800afcd0f1d2c46e66c18d160" from cache /src: (run (network host) (shell "echo '(lang dune 3.0)' > './dune-project'")) 2026-01-23 11:51.24 ---> using "157f9325d3ad469ebe0ef4868bbb29e7209016d4f6f1ba0c79cadb8cde70eaba" from cache /src: (env DEPS "base-bigarray.base base-bytes.base base-threads.base base-unix.base cppo.1.8.0 csexp.1.5.2 dune.3.21.0 dune-configurator.3.21.0 ocaml.4.14.2 ocaml-base-compiler.4.14.2 ocaml-compiler-libs.v0.12.4 ocaml-config.2 ocaml-options-vanilla.1 ocamlbuild.0.16.1 ocamlfind.1.9.8 ocplib-endian.1.2 ppx_derivers.1.2.1 ppxlib.0.37.0 react.1.2.2 sexplib0.v0.17.0 stdlib-shims.0.3.0 topkg.1.1.1") /src: (env CI true) /src: (env OCAMLCI true) /src: (run (cache (opam-archives (target /home/opam/.opam/download-cache))) (network host) (shell "opam update --depexts && opam install --cli=2.5 --depext-only -y lwt_retry.dev lwt_react.dev lwt_ppx.dev lwt.dev $DEPS")) + /usr/bin/sudo "apt-get" "update" - Get:1 http://deb.debian.org/debian trixie InRelease [140 kB] - Get:2 http://deb.debian.org/debian trixie-updates InRelease [47.3 kB] - Get:3 http://deb.debian.org/debian-security trixie-security InRelease [43.4 kB] - Get:4 http://deb.debian.org/debian trixie/main armhf Packages [9238 kB] - Get:5 http://deb.debian.org/debian-security trixie-security/main armhf Packages [90.2 kB] - Fetched 9559 kB in 1s (7395 kB/s) - Reading package lists... - <><> Synchronising pinned packages ><><><><><><><><><><><><><><><><><><><><><><> [lwt.dev] synchronised (file:///src) [lwt_ppx.dev] synchronised (file:///src) [lwt_react.dev] synchronised (file:///src) [lwt_retry.dev] synchronised (file:///src) [NOTE] Package ocaml-options-vanilla is already installed (current version is 1). [NOTE] Package ocaml-config is already installed (current version is 2). [NOTE] Package ocaml-base-compiler is already installed (current version is 4.14.2). [NOTE] Package ocaml is already installed (current version is 4.14.2). [NOTE] Package base-unix is already installed (current version is base). [NOTE] Package base-threads is already installed (current version is base). [NOTE] Package base-bigarray is already installed (current version is base). # To update the current shell environment, run: eval $(opam env) 2026-01-23 11:51.24 ---> using "2890638845ae593734da66ff5b36d42a37b272e462faa50679c846cb8dea4a77" from cache /src: (run (cache (opam-archives (target /home/opam/.opam/download-cache))) (network host) (shell "opam install $DEPS")) [NOTE] Package ocaml-options-vanilla is already installed (current version is 1). [NOTE] Package ocaml-config is already installed (current version is 2). [NOTE] Package ocaml-base-compiler is already installed (current version is 4.14.2). [NOTE] Package ocaml is already installed (current version is 4.14.2). [NOTE] Package base-unix is already installed (current version is base). [NOTE] Package base-threads is already installed (current version is base). [NOTE] Package base-bigarray is already installed (current version is base). The following actions will be performed: === install 15 packages - install base-bytes base - install cppo 1.8.0 - install csexp 1.5.2 - install dune 3.21.0 - install dune-configurator 3.21.0 - install ocaml-compiler-libs v0.12.4 - install ocamlbuild 0.16.1 - install ocamlfind 1.9.8 - install ocplib-endian 1.2 - install ppx_derivers 1.2.1 - install ppxlib 0.37.0 - install react 1.2.2 - install sexplib0 v0.17.0 - install stdlib-shims 0.3.0 - install topkg 1.1.1 <><> Processing actions <><><><><><><><><><><><><><><><><><><><><><><><><><><><> -> retrieved cppo.1.8.0 (cached) -> retrieved csexp.1.5.2 (cached) -> retrieved dune.3.21.0, dune-configurator.3.21.0 (cached) -> retrieved ocaml-compiler-libs.v0.12.4 (cached) -> retrieved ocamlbuild.0.16.1 (cached) -> retrieved ocamlfind.1.9.8 (cached) -> retrieved ocplib-endian.1.2 (cached) -> retrieved ppx_derivers.1.2.1 (cached) -> retrieved sexplib0.v0.17.0 (cached) -> retrieved react.1.2.2 (cached) -> retrieved stdlib-shims.0.3.0 (cached) -> retrieved topkg.1.1.1 (cached) -> retrieved ppxlib.0.37.0 (cached) -> installed ocamlfind.1.9.8 -> installed base-bytes.base -> installed ocamlbuild.0.16.1 -> installed topkg.1.1.1 -> installed react.1.2.2 -> installed dune.3.21.0 -> installed stdlib-shims.0.3.0 -> installed ppx_derivers.1.2.1 -> installed csexp.1.5.2 -> installed cppo.1.8.0 -> installed ocaml-compiler-libs.v0.12.4 -> installed sexplib0.v0.17.0 -> installed ocplib-endian.1.2 -> installed dune-configurator.3.21.0 -> installed ppxlib.0.37.0 Done. # To update the current shell environment, run: eval $(opam env) 2026-01-23 11:51.24 ---> using "e52b2952c0b51181a67d22eeee551a1715191d29e081be21f5c7a1f30589dc4d" from cache /src: (copy (src .) (dst /src)) 2026-01-23 11:51.24 ---> saved as "dc04965ea8281e5f2c51d55b5a27f64c45b7a3f9108165c09df5cf2620ca3083" /src: (run (shell "opam exec -- dune build --only-packages=lwt_retry,lwt_react,lwt_ppx,lwt @install @check @runtest && rm -rf _build")) (cd _build/default/test/retry && ./main.exe) Testing library 'retry'... .............. Ok. 14 tests ran, 0 tests skipped in 0.01 seconds can format retries outcomes [000.000]: starting can format retries outcomes [000.000]: finished in 0.000 s can format fatal outcomes [000.000]: starting can format fatal outcomes [000.000]: finished in 0.000 s can format with default printer [000.000]: starting can format with default printer [000.000]: finished in 0.000 s success without retry [000.000]: starting success without retry [000.000]: finished in 0.000 s does not run extra attempts [000.000]: starting does not run extra attempts [000.000]: finished in 0.000 s just retries [000.000]: starting just retries [000.000]: finished in 0.000 s retries before fatal error [000.000]: starting retries before fatal error [000.000]: finished in 0.000 s retries before success [000.000]: starting retries before success [000.000]: finished in 0.000 s [n_times 0] runs one attempt [000.000]: starting [n_times 0] runs one attempt [000.000]: finished in 0.000 s n_times gives up on a fatal error [000.000]: starting n_times gives up on a fatal error [000.000]: finished in 0.000 s n_times gives a retry error when exhausted [000.000]: starting n_times gives a retry error when exhausted [000.000]: finished in 0.000 s n_times is ok on success [000.000]: starting n_times is ok on success [000.000]: finished in 0.000 s n_times on negative raises Invalid_argument [000.000]: starting n_times on negative raises Invalid_argument [000.000]: finished in 0.000 s with_sleep really does sleep [000.000]: starting with_sleep really does sleep [000.010]: finished in 0.010 s (cd _build/default/test/core && ./main.exe) Testing library 'core'... ...............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................SSSSSSSSSSSSSSSSSSSSSSS.................................................................................................................................................. Ok. 705 tests ran, 23 tests skipped in 0.21 seconds return [000.000]: starting return [000.000]: finished in 0.000 s reject [000.000]: starting reject [000.000]: finished in 0.000 s of_result: fulfilled [000.000]: starting of_result: fulfilled [000.000]: finished in 0.000 s of_result: rejected [000.000]: starting of_result: rejected [000.000]: finished in 0.000 s return_unit [000.000]: starting return_unit [000.000]: finished in 0.000 s return_true [000.000]: starting return_true [000.000]: finished in 0.000 s return_false [000.000]: starting return_false [000.000]: finished in 0.000 s return_none [000.000]: starting return_none [000.000]: finished in 0.000 s return_some [000.000]: starting return_some [000.000]: finished in 0.000 s return_ok [000.000]: starting return_ok [000.000]: finished in 0.000 s return_error [000.000]: starting return_error [000.000]: finished in 0.000 s fail_with [000.000]: starting fail_with [000.000]: finished in 0.000 s fail_invalid_arg [000.000]: starting fail_invalid_arg [000.000]: finished in 0.000 s wait: pending [000.000]: starting wait: pending [000.000]: finished in 0.000 s task: pending [000.000]: starting task: pending [000.000]: finished in 0.000 s wait: fulfill [000.000]: starting wait: fulfill [000.000]: finished in 0.000 s task: fulfill [000.000]: starting task: fulfill [000.000]: finished in 0.000 s wait: reject [000.000]: starting wait: reject [000.000]: finished in 0.000 s task: reject [000.000]: starting task: reject [000.000]: finished in 0.000 s wait: resolve [000.000]: starting wait: resolve [000.000]: finished in 0.000 s task: resolve [000.000]: starting task: resolve [000.000]: finished in 0.000 s wakeup: double use on wait [000.000]: starting wakeup: double use on wait [000.001]: finished in 0.000 s wakeup: double use on task [000.001]: starting wakeup: double use on task [000.001]: finished in 0.000 s wakeup_exn: double use on wait [000.001]: starting wakeup_exn: double use on wait [000.001]: finished in 0.000 s wakeup_exn: double use on task [000.001]: starting wakeup_exn: double use on task [000.001]: finished in 0.000 s wakeup_result: double use on wait [000.001]: starting wakeup_result: double use on wait [000.001]: finished in 0.000 s wakeup_result: double use on task [000.001]: starting wakeup_result: double use on task [000.001]: finished in 0.000 s already fulfilled [000.001]: starting already fulfilled [000.001]: finished in 0.000 s already fulfilled, f raises [000.001]: starting already fulfilled, f raises [000.001]: finished in 0.000 s already rejected [000.001]: starting already rejected [000.001]: finished in 0.000 s pending [000.001]: starting pending [000.001]: finished in 0.000 s pending, fulfilled [000.001]: starting pending, fulfilled [000.001]: finished in 0.000 s pending, fulfilled, f raises [000.001]: starting pending, fulfilled, f raises [000.001]: finished in 0.000 s pending, rejected [000.001]: starting pending, rejected [000.001]: finished in 0.000 s chain [000.001]: starting chain [000.001]: finished in 0.000 s suspended chain [000.001]: starting suspended chain [000.001]: finished in 0.000 s fanout [000.001]: starting fanout [000.001]: finished in 0.000 s double pending [000.001]: starting double pending [000.001]: finished in 0.000 s same pending [000.001]: starting same pending [000.001]: finished in 0.000 s nested [000.001]: starting nested [000.001]: finished in 0.000 s proxy chain [000.001]: starting proxy chain [000.001]: finished in 0.000 s cycle [000.001]: starting cycle [000.001]: finished in 0.000 s proxy during callback [000.001]: starting proxy during callback [000.001]: finished in 0.000 s fulfilled [000.001]: starting fulfilled [000.001]: finished in 0.000 s rejected [000.001]: starting rejected [000.001]: finished in 0.000 s pending, fulfilled [000.001]: starting pending, fulfilled [000.001]: finished in 0.000 s pending, fulfilled, f raises [000.001]: starting pending, fulfilled, f raises [000.001]: finished in 0.000 s pending, rejected [000.001]: starting pending, rejected [000.001]: finished in 0.000 s proxy during callback [000.001]: starting proxy during callback [000.001]: finished in 0.000 s fulfilled [000.001]: starting fulfilled [000.001]: finished in 0.000 s fulfilled, f raises [000.001]: starting fulfilled, f raises [000.001]: finished in 0.000 s rejected [000.001]: starting rejected [000.001]: finished in 0.000 s pending [000.001]: starting pending [000.001]: finished in 0.000 s pending, fulfilled [000.001]: starting pending, fulfilled [000.001]: finished in 0.000 s pending, fulfilled, f raises [000.001]: starting pending, fulfilled, f raises [000.001]: finished in 0.000 s pending, rejected [000.001]: starting pending, rejected [000.001]: finished in 0.000 s proxy during callback [000.001]: starting proxy during callback [000.001]: finished in 0.000 s fulfilled [000.001]: starting fulfilled [000.001]: finished in 0.000 s f raises [000.001]: starting f raises [000.001]: finished in 0.000 s rejected [000.001]: starting rejected [000.001]: finished in 0.000 s rejected (raise) [000.001]: starting rejected (raise) [000.001]: finished in 0.000 s rejected, h raises [000.001]: starting rejected, h raises [000.001]: finished in 0.000 s pending [000.001]: starting pending [000.001]: finished in 0.000 s pending, fulfilled [000.001]: starting pending, fulfilled [000.001]: finished in 0.000 s pending, rejected [000.001]: starting pending, rejected [000.001]: finished in 0.000 s pending, rejected, h raises [000.001]: starting pending, rejected, h raises [000.001]: finished in 0.000 s pending, rejected, h pending [000.001]: starting pending, rejected, h pending [000.001]: finished in 0.000 s proxy during callback [000.001]: starting proxy during callback [000.001]: finished in 0.000 s catch with ocaml-runtime exception [000.001]: starting catch with ocaml-runtime exception [000.001]: finished in 0.000 s try_bind with ocaml-runtime exception [000.001]: starting try_bind with ocaml-runtime exception [000.001]: finished in 0.000 s try_bind(2) with ocaml-runtime exception [000.001]: starting try_bind(2) with ocaml-runtime exception [000.001]: finished in 0.000 s fulfilled [000.001]: starting fulfilled [000.001]: finished in 0.000 s f raises [000.002]: starting f raises [000.002]: finished in 0.000 s rejected [000.002]: starting rejected [000.002]: finished in 0.000 s pending [000.002]: starting pending [000.002]: finished in 0.000 s pending, fulfilled [000.002]: starting pending, fulfilled [000.002]: finished in 0.000 s pending, rejected [000.002]: starting pending, rejected [000.002]: finished in 0.000 s pending, rejected, h raises [000.002]: starting pending, rejected, h raises [000.002]: finished in 0.000 s proxy during callback [000.002]: starting proxy during callback [000.002]: finished in 0.000 s fulfilled [000.002]: starting fulfilled [000.002]: finished in 0.000 s fulfilled, f' raises [000.002]: starting fulfilled, f' raises [000.002]: finished in 0.000 s rejected [000.002]: starting rejected [000.002]: finished in 0.000 s f raises [000.002]: starting f raises [000.002]: finished in 0.000 s rejected, h raises [000.002]: starting rejected, h raises [000.002]: finished in 0.000 s pending [000.002]: starting pending [000.002]: finished in 0.000 s pending, fulfilled [000.002]: starting pending, fulfilled [000.002]: finished in 0.000 s pending, fulfilled, f' raises [000.002]: starting pending, fulfilled, f' raises [000.002]: finished in 0.000 s pending, fulfilled, f' pending [000.002]: starting pending, fulfilled, f' pending [000.002]: finished in 0.000 s pending, rejected [000.002]: starting pending, rejected [000.002]: finished in 0.000 s pending, rejected, h raises [000.002]: starting pending, rejected, h raises [000.002]: finished in 0.000 s pending, rejected, h pending [000.002]: starting pending, rejected, h pending [000.002]: finished in 0.000 s proxy during callback (fulfilled) [000.002]: starting proxy during callback (fulfilled) [000.002]: finished in 0.000 s proxy during callback (rejected) [000.002]: starting proxy during callback (rejected) [000.002]: finished in 0.000 s fulfilled [000.002]: starting fulfilled [000.002]: finished in 0.000 s rejected [000.002]: starting rejected [000.002]: finished in 0.000 s f raises [000.002]: starting f raises [000.002]: finished in 0.000 s pending [000.002]: starting pending [000.002]: finished in 0.000 s pending, fulfilled [000.002]: starting pending, fulfilled [000.002]: finished in 0.000 s pending, fulfilled, f' raises [000.002]: starting pending, fulfilled, f' raises [000.002]: finished in 0.000 s pending, rejected [000.002]: starting pending, rejected [000.002]: finished in 0.000 s pending, rejected, h raises [000.002]: starting pending, rejected, h raises [000.002]: finished in 0.000 s proxy during callback (fulfilled) [000.002]: starting proxy during callback (fulfilled) [000.002]: finished in 0.000 s proxy during callback (rejected) [000.002]: starting proxy during callback (rejected) [000.002]: finished in 0.000 s fulfilled [000.002]: starting fulfilled [000.002]: finished in 0.000 s fulfilled, f' rejected [000.002]: starting fulfilled, f' rejected [000.002]: finished in 0.000 s fulfilled, f' raises [000.002]: starting fulfilled, f' raises [000.002]: finished in 0.000 s rejected [000.002]: starting rejected [000.002]: finished in 0.000 s rejected, f' rejected [000.002]: starting rejected, f' rejected [000.002]: finished in 0.000 s rejected, f' raises [000.002]: starting rejected, f' raises [000.002]: finished in 0.000 s pending [000.002]: starting pending [000.002]: finished in 0.000 s pending, fulfilled [000.002]: starting pending, fulfilled [000.002]: finished in 0.000 s pending, fulfilled, f' rejected [000.002]: starting pending, fulfilled, f' rejected [000.002]: finished in 0.000 s pending, fulfilled, f' raises [000.002]: starting pending, fulfilled, f' raises [000.002]: finished in 0.000 s pending, fulfilled, f' pending [000.002]: starting pending, fulfilled, f' pending [000.002]: finished in 0.000 s pending, fulfilled, f' pending, rejected [000.002]: starting pending, fulfilled, f' pending, rejected [000.002]: finished in 0.000 s pending, rejected [000.002]: starting pending, rejected [000.002]: finished in 0.000 s pending, rejected, f' rejected [000.002]: starting pending, rejected, f' rejected [000.002]: finished in 0.000 s pending, rejected, f' raises [000.002]: starting pending, rejected, f' raises [000.002]: finished in 0.000 s pending, rejected, f' pending [000.002]: starting pending, rejected, f' pending [000.002]: finished in 0.000 s pending, rejected, f' pending, rejected [000.002]: starting pending, rejected, f' pending, rejected [000.002]: finished in 0.000 s proxy during callback (fulfilled) [000.002]: starting proxy during callback (fulfilled) [000.002]: finished in 0.000 s proxy during callback (rejected) [000.002]: starting proxy during callback (rejected) [000.002]: finished in 0.000 s fulfilled [000.002]: starting fulfilled [000.002]: finished in 0.000 s fulfilled, f' rejected [000.002]: starting fulfilled, f' rejected [000.002]: finished in 0.000 s fulfilled, f' raises [000.002]: starting fulfilled, f' raises [000.002]: finished in 0.000 s rejected [000.002]: starting rejected [000.002]: finished in 0.000 s rejected, f' rejected [000.002]: starting rejected, f' rejected [000.002]: finished in 0.000 s rejected, f' raises [000.002]: starting rejected, f' raises [000.002]: finished in 0.000 s pending [000.002]: starting pending [000.002]: finished in 0.000 s pending, fulfilled [000.002]: starting pending, fulfilled [000.003]: finished in 0.000 s pending, fulfilled, f' rejected [000.003]: starting pending, fulfilled, f' rejected [000.003]: finished in 0.000 s pending, fulfilled, f' raises [000.003]: starting pending, fulfilled, f' raises [000.003]: finished in 0.000 s pending, rejected [000.003]: starting pending, rejected [000.003]: finished in 0.000 s pending, rejected, f' rejected [000.003]: starting pending, rejected, f' rejected [000.003]: finished in 0.000 s pending, rejected, f' raises [000.003]: starting pending, rejected, f' raises [000.003]: finished in 0.000 s proxy during callback (fulfilled) [000.003]: starting proxy during callback (fulfilled) [000.003]: finished in 0.000 s proxy during callback (rejected) [000.003]: starting proxy during callback (rejected) [000.003]: finished in 0.000 s fulfilled [000.003]: starting fulfilled [000.003]: finished in 0.000 s fulfilled, f raises [000.003]: starting fulfilled, f raises [000.003]: finished in 0.000 s rejected [000.003]: starting rejected [000.003]: finished in 0.000 s pending [000.003]: starting pending [000.003]: finished in 0.000 s pending, fulfilled [000.003]: starting pending, fulfilled [000.003]: finished in 0.000 s pending, fulfilled, f raises [000.003]: starting pending, fulfilled, f raises [000.003]: finished in 0.000 s pending, rejected [000.003]: starting pending, rejected [000.003]: finished in 0.000 s fulfilled [000.003]: starting fulfilled [000.003]: finished in 0.000 s rejected [000.003]: starting rejected [000.003]: finished in 0.000 s rejected, f raises [000.003]: starting rejected, f raises [000.003]: finished in 0.000 s pending [000.003]: starting pending [000.003]: finished in 0.000 s pending, fulfilled [000.003]: starting pending, fulfilled [000.003]: finished in 0.000 s pending, rejected [000.003]: starting pending, rejected [000.003]: finished in 0.000 s pending, rejected, f raises [000.003]: starting pending, rejected, f raises [000.003]: finished in 0.000 s fulfilled [000.003]: starting fulfilled [000.003]: finished in 0.000 s fulfilled, f raises [000.003]: starting fulfilled, f raises [000.003]: finished in 0.000 s rejected [000.003]: starting rejected [000.003]: finished in 0.000 s rejected, f raises [000.003]: starting rejected, f raises [000.003]: finished in 0.000 s pending [000.003]: starting pending [000.003]: finished in 0.000 s pending, fulfilled [000.003]: starting pending, fulfilled [000.003]: finished in 0.000 s pending, fulfilled, f raises [000.003]: starting pending, fulfilled, f raises [000.003]: finished in 0.000 s pending, rejected [000.003]: starting pending, rejected [000.003]: finished in 0.000 s pending, rejected, f raises [000.003]: starting pending, rejected, f raises [000.003]: finished in 0.000 s fulfilled [000.003]: starting fulfilled [000.003]: finished in 0.000 s fulfilled, f raises [000.003]: starting fulfilled, f raises [000.003]: finished in 0.000 s rejected [000.003]: starting rejected [000.003]: finished in 0.000 s rejected, f raises [000.003]: starting rejected, f raises [000.003]: finished in 0.000 s pending [000.003]: starting pending [000.003]: finished in 0.000 s pending, fulfilled [000.003]: starting pending, fulfilled [000.003]: finished in 0.000 s pending, fulfilled, f raises [000.003]: starting pending, fulfilled, f raises [000.003]: finished in 0.000 s pending, rejected [000.003]: starting pending, rejected [000.003]: finished in 0.000 s pending, rejected, g raises [000.003]: starting pending, rejected, g raises [000.003]: finished in 0.000 s fulfilled [000.003]: starting fulfilled [000.003]: finished in 0.000 s f raises [000.003]: starting f raises [000.003]: finished in 0.000 s rejected [000.003]: starting rejected [000.003]: finished in 0.000 s pending, fulfilled [000.003]: starting pending, fulfilled [000.003]: finished in 0.000 s pending, rejected [000.003]: starting pending, rejected [000.003]: finished in 0.000 s fulfilled [000.003]: starting fulfilled [000.003]: finished in 0.000 s f raises [000.003]: starting f raises [000.003]: finished in 0.000 s rejected [000.003]: starting rejected [000.003]: finished in 0.000 s pending, fulfilled [000.003]: starting pending, fulfilled [000.003]: finished in 0.000 s pending, rejected [000.003]: starting pending, rejected [000.003]: finished in 0.000 s fulfilled [000.003]: starting fulfilled [000.003]: finished in 0.000 s rejected [000.003]: starting rejected [000.003]: finished in 0.000 s pending, fulfilled [000.003]: starting pending, fulfilled [000.003]: finished in 0.000 s pending, rejected [000.003]: starting pending, rejected [000.003]: finished in 0.000 s empty [000.003]: starting empty [000.003]: finished in 0.000 s all fulfilled [000.003]: starting all fulfilled [000.003]: finished in 0.000 s all rejected [000.003]: starting all rejected [000.003]: finished in 0.000 s fulfilled and pending, fulfilled [000.003]: starting fulfilled and pending, fulfilled [000.003]: finished in 0.000 s rejected and pending, fulfilled [000.003]: starting rejected and pending, fulfilled [000.004]: finished in 0.000 s fulfilled and pending, rejected [000.004]: starting fulfilled and pending, rejected [000.004]: finished in 0.000 s rejected and pending, rejected [000.004]: starting rejected and pending, rejected [000.004]: finished in 0.000 s diamond [000.004]: starting diamond [000.004]: finished in 0.000 s empty [000.004]: starting empty [000.004]: finished in 0.000 s all fulfilled (one) [000.004]: starting all fulfilled (one) [000.004]: finished in 0.000 s all fulfilled (two) [000.004]: starting all fulfilled (two) [000.004]: finished in 0.000 s all fulfilled (three) [000.004]: starting all fulfilled (three) [000.004]: finished in 0.000 s all fulfilled (long) [000.004]: starting all fulfilled (long) [000.004]: finished in 0.000 s all rejected [000.004]: starting all rejected [000.004]: finished in 0.000 s fulfilled and pending, fulfilled [000.004]: starting fulfilled and pending, fulfilled [000.004]: finished in 0.000 s pending twice physically equal, fulfilled [000.004]: starting pending twice physically equal, fulfilled [000.004]: finished in 0.000 s pending twice physically equal twice, fulfilled [000.004]: starting pending twice physically equal twice, fulfilled [000.004]: finished in 0.000 s fulfilled and pending and fulfilled, fulfilled [000.004]: starting fulfilled and pending and fulfilled, fulfilled [000.004]: finished in 0.000 s fulfilled and pending, fulfilled (long) [000.004]: starting fulfilled and pending, fulfilled (long) [000.004]: finished in 0.000 s rejected and pending, fulfilled [000.004]: starting rejected and pending, fulfilled [000.004]: finished in 0.000 s fulfilled and pending, rejected [000.004]: starting fulfilled and pending, rejected [000.004]: finished in 0.000 s rejected and pending, rejected [000.004]: starting rejected and pending, rejected [000.004]: finished in 0.000 s diamond [000.004]: starting diamond [000.004]: finished in 0.000 s both fulfilled [000.004]: starting both fulfilled [000.004]: finished in 0.000 s both rejected [000.004]: starting both rejected [000.004]: finished in 0.000 s rejected, fulfilled [000.004]: starting rejected, fulfilled [000.004]: finished in 0.000 s fulfilled, rejected [000.004]: starting fulfilled, rejected [000.004]: finished in 0.000 s both pending [000.004]: starting both pending [000.004]: finished in 0.000 s pending, fulfilled [000.004]: starting pending, fulfilled [000.004]: finished in 0.000 s pending, rejected [000.004]: starting pending, rejected [000.004]: finished in 0.000 s fulfilled, pending [000.004]: starting fulfilled, pending [000.004]: finished in 0.000 s rejected, pending [000.004]: starting rejected, pending [000.004]: finished in 0.000 s pending, fulfilled, then fulfilled [000.004]: starting pending, fulfilled, then fulfilled [000.004]: finished in 0.000 s pending, rejected, then fulfilled [000.004]: starting pending, rejected, then fulfilled [000.004]: finished in 0.000 s pending, fulfilled, then rejected [000.004]: starting pending, fulfilled, then rejected [000.004]: finished in 0.000 s pending, rejected, then rejected [000.004]: starting pending, rejected, then rejected [000.004]: finished in 0.000 s fulfilled, pending, then fulfilled [000.004]: starting fulfilled, pending, then fulfilled [000.004]: finished in 0.000 s rejected, pending, then fulfilled [000.004]: starting rejected, pending, then fulfilled [000.004]: finished in 0.000 s fulfilled, pending, then rejected [000.004]: starting fulfilled, pending, then rejected [000.004]: finished in 0.000 s rejected, pending, then rejected [000.004]: starting rejected, pending, then rejected [000.004]: finished in 0.000 s pending, then first fulfilled [000.004]: starting pending, then first fulfilled [000.004]: finished in 0.000 s pending, then first rejected [000.004]: starting pending, then first rejected [000.004]: finished in 0.000 s pending, then second fulfilled [000.004]: starting pending, then second fulfilled [000.004]: finished in 0.000 s pending, then second rejected [000.004]: starting pending, then second rejected [000.004]: finished in 0.000 s pending, then first fulfilled, then fulfilled [000.004]: starting pending, then first fulfilled, then fulfilled [000.004]: finished in 0.000 s pending, then first fulfilled, then rejected [000.004]: starting pending, then first fulfilled, then rejected [000.004]: finished in 0.000 s pending, then first rejected, then fulfilled [000.004]: starting pending, then first rejected, then fulfilled [000.004]: finished in 0.000 s pending, then first rejected, then rejected [000.004]: starting pending, then first rejected, then rejected [000.004]: finished in 0.000 s pending, then second fulfilled, then fulfilled [000.004]: starting pending, then second fulfilled, then fulfilled [000.004]: finished in 0.000 s pending, then second fulfilled, then rejected [000.004]: starting pending, then second fulfilled, then rejected [000.004]: finished in 0.000 s pending, then second rejected, then fulfilled [000.004]: starting pending, then second rejected, then fulfilled [000.004]: finished in 0.000 s pending, then second rejected, then rejected [000.004]: starting pending, then second rejected, then rejected [000.004]: finished in 0.000 s diamond [000.004]: starting diamond [000.004]: finished in 0.000 s empty [000.004]: starting empty [000.004]: finished in 0.000 s fulfilled [000.004]: starting fulfilled [000.004]: finished in 0.000 s rejected [000.004]: starting rejected [000.004]: finished in 0.000 s multiple resolved [000.004]: starting multiple resolved [000.004]: finished in 0.000 s pending [000.004]: starting pending [000.004]: finished in 0.000 s pending, fulfilled [000.004]: starting pending, fulfilled [000.004]: finished in 0.000 s diamond [000.004]: starting diamond [000.004]: finished in 0.000 s empty [000.004]: starting empty [000.004]: finished in 0.000 s all fulfilled [000.004]: starting all fulfilled [000.004]: finished in 0.000 s fulfilled, rejected [000.004]: starting fulfilled, rejected [000.004]: finished in 0.000 s rejected, fulfilled [000.005]: starting rejected, fulfilled [000.005]: finished in 0.000 s some pending [000.005]: starting some pending [000.005]: finished in 0.000 s pending, fulfilled [000.005]: starting pending, fulfilled [000.005]: finished in 0.000 s pending, rejected [000.005]: starting pending, rejected [000.005]: finished in 0.000 s diamond [000.005]: starting diamond [000.005]: finished in 0.000 s diamond, rejected [000.005]: starting diamond, rejected [000.005]: finished in 0.000 s empty [000.005]: starting empty [000.005]: finished in 0.000 s some fulfilled [000.005]: starting some fulfilled [000.005]: finished in 0.000 s fulfilled, rejected [000.005]: starting fulfilled, rejected [000.005]: finished in 0.000 s rejected, fulfilled [000.005]: starting rejected, fulfilled [000.005]: finished in 0.000 s pending, rejected [000.005]: starting pending, rejected [000.005]: finished in 0.000 s pending, fulfilled [000.005]: starting pending, fulfilled [000.005]: finished in 0.000 s pending, rejected 2 [000.005]: starting pending, rejected 2 [000.005]: finished in 0.000 s diamond [000.005]: starting diamond [000.005]: finished in 0.000 s diamond, rejected [000.005]: starting diamond, rejected [000.005]: finished in 0.000 s is_sleeping: fulfilled [000.005]: starting is_sleeping: fulfilled [000.005]: finished in 0.000 s is_sleeping: rejected [000.005]: starting is_sleeping: rejected [000.005]: finished in 0.000 s is_sleeping: pending [000.005]: starting is_sleeping: pending [000.005]: finished in 0.000 s is_sleeping: proxy [000.005]: starting is_sleeping: proxy [000.005]: finished in 0.000 s poll: fulfilled [000.005]: starting poll: fulfilled [000.005]: finished in 0.000 s poll: rejected [000.005]: starting poll: rejected [000.005]: finished in 0.000 s poll: pending [000.005]: starting poll: pending [000.005]: finished in 0.000 s poll: proxy [000.005]: starting poll: proxy [000.005]: finished in 0.000 s wakeup_result: nested [000.005]: starting wakeup_result: nested [000.005]: finished in 0.000 s wakeup_later_result: immediate [000.005]: starting wakeup_later_result: immediate [000.005]: finished in 0.000 s wakeup_later: double use on wait [000.005]: starting wakeup_later: double use on wait [000.005]: finished in 0.000 s wakeup_later: double use on task [000.005]: starting wakeup_later: double use on task [000.005]: finished in 0.000 s wakeup_later_result: double use on wait [000.005]: starting wakeup_later_result: double use on wait [000.005]: finished in 0.000 s wakeup_later_result: double use on task [000.005]: starting wakeup_later_result: double use on task [000.005]: finished in 0.000 s wakeup_later_exn: double use on wait [000.005]: starting wakeup_later_exn: double use on wait [000.005]: finished in 0.000 s wakeup_later_exn: double use on task [000.005]: starting wakeup_later_exn: double use on task [000.005]: finished in 0.000 s wakeup_later_result: nested [000.005]: starting wakeup_later_result: nested [000.005]: finished in 0.000 s wakeup_later: basic [000.005]: starting wakeup_later: basic [000.005]: finished in 0.000 s wakeup_later_exn: basic [000.005]: starting wakeup_later_exn: basic [000.005]: finished in 0.000 s fulfilled [000.005]: starting fulfilled [000.005]: finished in 0.000 s rejected [000.005]: starting rejected [000.005]: finished in 0.000 s wait [000.005]: starting wait [000.005]: finished in 0.000 s task [000.005]: starting task [000.005]: finished in 0.000 s callback [000.005]: starting callback [000.005]: finished in 0.000 s nested [000.005]: starting nested [000.005]: finished in 0.000 s pending [000.005]: starting pending [000.005]: finished in 0.000 s multiple [000.005]: starting multiple [000.005]: finished in 0.000 s ordering [000.005]: starting ordering [000.005]: finished in 0.000 s fulfilled [000.005]: starting fulfilled [000.005]: finished in 0.000 s rejected [000.005]: starting rejected [000.005]: finished in 0.000 s already canceled [000.005]: starting already canceled [000.005]: finished in 0.000 s reject with Canceled [000.005]: starting reject with Canceled [000.005]: finished in 0.000 s fulfilled [000.005]: starting fulfilled [000.005]: finished in 0.000 s rejected [000.005]: starting rejected [000.005]: finished in 0.000 s pending [000.005]: starting pending [000.005]: finished in 0.000 s pending, fulfilled [000.005]: starting pending, fulfilled [000.005]: finished in 0.000 s pending, canceled [000.005]: starting pending, canceled [000.005]: finished in 0.000 s pending, canceled, fulfilled [000.005]: starting pending, canceled, fulfilled [000.005]: finished in 0.000 s pending, proxy [000.006]: starting pending, proxy [000.006]: finished in 0.000 s fulfilled [000.006]: starting fulfilled [000.006]: finished in 0.000 s rejected [000.006]: starting rejected [000.006]: finished in 0.000 s pending(task) [000.006]: starting pending(task) [000.006]: finished in 0.000 s pending(task), fulfilled [000.006]: starting pending(task), fulfilled [000.006]: finished in 0.000 s pending(task), canceled [000.006]: starting pending(task), canceled [000.006]: finished in 0.000 s pending(wait) [000.006]: starting pending(wait) [000.006]: finished in 0.000 s pending(wait), fulfilled [000.006]: starting pending(wait), fulfilled [000.006]: finished in 0.000 s pending(wait), canceled [000.006]: starting pending(wait), canceled [000.006]: finished in 0.000 s pending(task), canceled, fulfilled [000.006]: starting pending(task), canceled, fulfilled [000.006]: finished in 0.000 s pending(wait), canceled, fulfilled [000.006]: starting pending(wait), canceled, fulfilled [000.006]: finished in 0.000 s pending, proxy [000.006]: starting pending, proxy [000.006]: finished in 0.000 s fulfilled [000.006]: starting fulfilled [000.006]: finished in 0.000 s rejected [000.006]: starting rejected [000.006]: finished in 0.000 s pending [000.006]: starting pending [000.006]: finished in 0.000 s pending, fulfilled [000.006]: starting pending, fulfilled [000.006]: finished in 0.000 s pending, cancel attempt [000.006]: starting pending, cancel attempt [000.006]: finished in 0.000 s wakeup: canceled [000.006]: starting wakeup: canceled [000.006]: finished in 0.000 s wakeup_later: canceled [000.006]: starting wakeup_later: canceled [000.006]: finished in 0.000 s empty [000.006]: starting empty [000.006]: finished in 0.000 s fulfilled [000.006]: starting fulfilled [000.006]: finished in 0.000 s rejected [000.006]: starting rejected [000.006]: finished in 0.000 s multiple resolved [000.006]: starting multiple resolved [000.006]: finished in 0.000 s pending [000.006]: starting pending [000.006]: finished in 0.000 s pending, fulfilled [000.006]: starting pending, fulfilled [000.006]: finished in 0.000 s diamond [000.006]: starting diamond [000.006]: finished in 0.000 s pending, canceled [000.006]: starting pending, canceled [000.006]: finished in 0.000 s cancellation/resolution order [000.006]: starting cancellation/resolution order [000.006]: finished in 0.000 s empty [000.006]: starting empty [000.006]: finished in 0.000 s all fulfilled [000.006]: starting all fulfilled [000.006]: finished in 0.000 s fulfilled, rejected [000.006]: starting fulfilled, rejected [000.006]: finished in 0.000 s rejected, fulfilled [000.006]: starting rejected, fulfilled [000.006]: finished in 0.000 s some pending [000.006]: starting some pending [000.006]: finished in 0.000 s pending [000.006]: starting pending [000.006]: finished in 0.000 s pending, fulfilled [000.006]: starting pending, fulfilled [000.006]: finished in 0.000 s pending, rejected [000.006]: starting pending, rejected [000.006]: finished in 0.000 s diamond [000.006]: starting diamond [000.006]: finished in 0.000 s diamond, rejected [000.006]: starting diamond, rejected [000.006]: finished in 0.000 s pending, canceled [000.006]: starting pending, canceled [000.006]: finished in 0.000 s cancellation/resolution order [000.006]: starting cancellation/resolution order [000.006]: finished in 0.000 s wait, pending, canceled [000.006]: starting wait, pending, canceled [000.006]: finished in 0.000 s task, pending, canceled [000.006]: starting task, pending, canceled [000.006]: finished in 0.000 s pending, wait, canceled [000.006]: starting pending, wait, canceled [000.006]: finished in 0.000 s pending, task, canceled [000.006]: starting pending, task, canceled [000.006]: finished in 0.000 s pending, task, canceled, chain [000.006]: starting pending, task, canceled, chain [000.006]: finished in 0.000 s pending, on_cancel callbacks [000.006]: starting pending, on_cancel callbacks [000.006]: finished in 0.000 s pending, fulfilled, on_cancel callbacks [000.006]: starting pending, fulfilled, on_cancel callbacks [000.006]: finished in 0.000 s wait, pending, canceled [000.006]: starting wait, pending, canceled [000.006]: finished in 0.000 s task, pending, canceled [000.006]: starting task, pending, canceled [000.006]: finished in 0.000 s wait, pending, canceled [000.006]: starting wait, pending, canceled [000.006]: finished in 0.000 s task, pending, canceled [000.006]: starting task, pending, canceled [000.006]: finished in 0.000 s task, pending, canceled, on_cancel, intercepted [000.006]: starting task, pending, canceled, on_cancel, intercepted [000.006]: finished in 0.000 s task, pending, canceled, on_cancel, forwarded [000.006]: starting task, pending, canceled, on_cancel, forwarded [000.006]: finished in 0.000 s pending, wait, canceled [000.006]: starting pending, wait, canceled [000.006]: finished in 0.000 s pending, task, canceled [000.006]: starting pending, task, canceled [000.006]: finished in 0.000 s wait, pending, canceled [000.006]: starting wait, pending, canceled [000.006]: finished in 0.000 s task, pending, canceled [000.006]: starting task, pending, canceled [000.006]: finished in 0.000 s pending, fulfilled, wait, canceled [000.006]: starting pending, fulfilled, wait, canceled [000.006]: finished in 0.000 s pending, fulfilled, task, canceled [000.006]: starting pending, fulfilled, task, canceled [000.006]: finished in 0.000 s pending, rejected, wait, canceled [000.006]: starting pending, rejected, wait, canceled [000.006]: finished in 0.000 s pending, rejected, task, canceled [000.006]: starting pending, rejected, task, canceled [000.006]: finished in 0.000 s wait, pending, canceled [000.006]: starting wait, pending, canceled [000.006]: finished in 0.000 s task, pending, canceled [000.006]: starting task, pending, canceled [000.006]: finished in 0.000 s task, canceled, cancel exception replaced [000.006]: starting task, canceled, cancel exception replaced [000.006]: finished in 0.000 s pending, wait, canceled [000.006]: starting pending, wait, canceled [000.006]: finished in 0.000 s pending, task, canceled [000.006]: starting pending, task, canceled [000.006]: finished in 0.000 s on_success: pending, canceled [000.007]: starting on_success: pending, canceled [000.007]: finished in 0.000 s on_failure: pending, canceled [000.007]: starting on_failure: pending, canceled [000.007]: finished in 0.000 s on_termination: pending, canceled [000.007]: starting on_termination: pending, canceled [000.007]: finished in 0.000 s on_any: pending, canceled [000.007]: starting on_any: pending, canceled [000.007]: finished in 0.000 s wait, pending, cancel [000.007]: starting wait, pending, cancel [000.007]: finished in 0.000 s task, pending, cancel [000.007]: starting task, pending, cancel [000.007]: finished in 0.000 s wait and task, pending, cancel [000.007]: starting wait and task, pending, cancel [000.007]: finished in 0.000 s cancel diamond [000.007]: starting cancel diamond [000.007]: finished in 0.000 s wait, pending, cancel [000.007]: starting wait, pending, cancel [000.007]: finished in 0.000 s wait and task, pending, cancel [000.007]: starting wait and task, pending, cancel [000.007]: finished in 0.000 s wait, pending, cancel [000.007]: starting wait, pending, cancel [000.007]: finished in 0.000 s wait and task, pending, cancel [000.007]: starting wait and task, pending, cancel [000.007]: finished in 0.000 s wait, pending, cancel [000.007]: starting wait, pending, cancel [000.007]: finished in 0.000 s wait and task, pending, cancel [000.007]: starting wait and task, pending, cancel [000.007]: finished in 0.000 s wait, pending, cancel [000.007]: starting wait, pending, cancel [000.007]: finished in 0.000 s wait and task, pending, cancel [000.007]: starting wait and task, pending, cancel [000.007]: finished in 0.000 s wait, pending, cancel [000.007]: starting wait, pending, cancel [000.007]: finished in 0.000 s wait and task, pending, cancel [000.007]: starting wait and task, pending, cancel [000.007]: finished in 0.000 s initial [000.007]: starting initial [000.007]: finished in 0.000 s store, retrieve [000.007]: starting store, retrieve [000.007]: finished in 0.000 s store, restore [000.007]: starting store, restore [000.007]: finished in 0.000 s store, f raises, restore [000.007]: starting store, f raises, restore [000.007]: finished in 0.000 s store, overwrite, retrieve [000.007]: starting store, overwrite, retrieve [000.007]: finished in 0.000 s store, blank, retrieve [000.007]: starting store, blank, retrieve [000.007]: finished in 0.000 s distinct keys [000.007]: starting distinct keys [000.007]: finished in 0.000 s bind [000.007]: starting bind [000.007]: finished in 0.000 s map [000.007]: starting map [000.007]: finished in 0.000 s catch [000.007]: starting catch [000.007]: finished in 0.000 s try_bind, fulfilled [000.007]: starting try_bind, fulfilled [000.007]: finished in 0.000 s try_bind, rejected [000.007]: starting try_bind, rejected [000.007]: finished in 0.000 s finalize [000.007]: starting finalize [000.007]: finished in 0.000 s on_success [000.007]: starting on_success [000.007]: finished in 0.000 s on_failure [000.007]: starting on_failure [000.007]: finished in 0.000 s on_termination, fulfilled [000.007]: starting on_termination, fulfilled [000.007]: finished in 0.000 s on_termination, rejected [000.007]: starting on_termination, rejected [000.007]: finished in 0.000 s on_any, fulfilled [000.007]: starting on_any, fulfilled [000.007]: finished in 0.000 s on_any, rejected [000.007]: starting on_any, rejected [000.007]: finished in 0.000 s on_cancel [000.007]: starting on_cancel [000.007]: finished in 0.000 s >>= [000.007]: starting >>= [000.007]: finished in 0.000 s =<< [000.007]: starting =<< [000.007]: finished in 0.000 s >|= [000.007]: starting >|= [000.007]: finished in 0.000 s =|< [000.007]: starting =|< [000.007]: finished in 0.000 s <&> [000.007]: starting <&> [000.007]: finished in 0.000 s <?> [000.007]: starting <?> [000.007]: finished in 0.000 s return [000.007]: starting return [000.007]: finished in 0.000 s map [000.007]: starting map [000.007]: finished in 0.000 s bind [000.007]: starting bind [000.007]: finished in 0.000 s both [000.007]: starting both [000.007]: finished in 0.000 s Open_on_rhs [000.007]: starting Open_on_rhs [000.007]: finished in 0.000 s let* [000.007]: starting let* [000.007]: finished in 0.000 s and* [000.007]: starting and* [000.007]: finished in 0.000 s let+/and+ [000.007]: starting let+/and+ [000.007]: finished in 0.000 s add_task_r [000.007]: starting add_task_r [000.007]: finished in 0.000 s add_task_l [000.007]: starting add_task_l [000.007]: finished in 0.000 s initial state [000.007]: starting initial state [000.007]: finished in 0.000 s one promise [000.007]: starting one promise [000.007]: finished in 0.000 s multiple promises [000.007]: starting multiple promises [000.007]: finished in 0.000 s wakeup with no promises [000.007]: starting wakeup with no promises [000.007]: finished in 0.000 s pause notifier [000.007]: starting pause notifier [000.007]: finished in 0.000 s pause in unpause [000.007]: starting pause in unpause [000.007]: finished in 0.000 s recursive pause in notifier [000.007]: starting recursive pause in notifier [000.007]: finished in 0.000 s unpause in pause [000.007]: starting unpause in pause [000.007]: finished in 0.000 s apply [000.007]: starting apply [000.007]: finished in 0.000 s apply: raises [000.007]: starting apply: raises [000.007]: finished in 0.000 s wrap [000.007]: starting wrap [000.007]: finished in 0.000 s wrap: raises [000.007]: starting wrap: raises [000.007]: finished in 0.000 s wrap1 [000.007]: starting wrap1 [000.007]: finished in 0.000 s wrap1: raises [000.007]: starting wrap1: raises [000.007]: finished in 0.000 s wrap2 [000.007]: starting wrap2 [000.007]: finished in 0.000 s wrap2: raises [000.007]: starting wrap2: raises [000.008]: finished in 0.000 s wrap3 [000.008]: starting wrap3 [000.008]: finished in 0.000 s wrap3: raises [000.008]: starting wrap3: raises [000.008]: finished in 0.000 s wrap4 [000.008]: starting wrap4 [000.008]: finished in 0.000 s wrap4: raises [000.008]: starting wrap4: raises [000.008]: finished in 0.000 s wrap5 [000.008]: starting wrap5 [000.008]: finished in 0.000 s wrap5: raises [000.008]: starting wrap5: raises [000.008]: finished in 0.000 s wrap6 [000.008]: starting wrap6 [000.008]: finished in 0.000 s wrap6: raises [000.008]: starting wrap6: raises [000.008]: finished in 0.000 s wrap7 [000.008]: starting wrap7 [000.008]: finished in 0.000 s wrap7: raises [000.008]: starting wrap7: raises [000.008]: finished in 0.000 s choose [000.008]: starting choose [000.008]: finished in 0.000 s bind [000.008]: starting bind [000.008]: finished in 0.000 s tailrec [000.008]: starting tailrec [000.182]: finished in 0.175 s from [000.182]: starting from [000.182]: finished in 0.000 s return [000.182]: starting return [000.182]: finished in 0.000 s return_lwt [000.182]: starting return_lwt [000.182]: finished in 0.000 s return_lwt_with_pause [000.182]: starting return_lwt_with_pause [000.182]: finished in 0.000 s return_lwt_with_fail [000.182]: starting return_lwt_with_fail [000.182]: finished in 0.000 s of_seq [000.182]: starting of_seq [000.182]: finished in 0.000 s of_lwt_seq [000.182]: starting of_lwt_seq [000.182]: finished in 0.000 s of_list [000.182]: starting of_list [000.182]: finished in 0.000 s clone [000.182]: starting clone [000.182]: finished in 0.000 s clone 2 [000.182]: starting clone 2 [000.182]: finished in 0.000 s create [000.182]: starting create [000.183]: finished in 0.000 s create 2 [000.183]: starting create 2 [000.183]: finished in 0.000 s create_bounded [000.183]: starting create_bounded [000.183]: finished in 0.000 s create_bounded close [000.183]: starting create_bounded close [000.183]: finished in 0.000 s get_while [000.183]: starting get_while [000.183]: finished in 0.000 s peek [000.183]: starting peek [000.183]: finished in 0.000 s npeek [000.183]: starting npeek [000.183]: finished in 0.000 s get_available [000.183]: starting get_available [000.183]: finished in 0.000 s get_available_up_to [000.183]: starting get_available_up_to [000.183]: finished in 0.000 s filter [000.183]: starting filter [000.183]: finished in 0.000 s filter_map [000.183]: starting filter_map [000.183]: finished in 0.000 s last_new [000.183]: starting last_new [000.183]: finished in 0.000 s junk_available [000.183]: starting junk_available [000.183]: finished in 0.000 s junk_old [000.183]: starting junk_old [000.183]: finished in 0.000 s cancel push stream 1 [000.183]: starting cancel push stream 1 [000.183]: finished in 0.000 s cancel push stream 2 [000.183]: starting cancel push stream 2 [000.183]: finished in 0.000 s cancel push stream 3 [000.183]: starting cancel push stream 3 [000.183]: finished in 0.000 s push and GC [000.183]: starting push and GC [000.184]: finished in 0.001 s map_exn [000.184]: starting map_exn [000.184]: finished in 0.000 s is_closed [000.184]: starting is_closed [000.184]: finished in 0.000 s closed(bind) [000.184]: starting closed(bind) [000.184]: finished in 0.000 s closed(on_termination) [000.184]: starting closed(on_termination) [000.184]: finished in 0.000 s closed when closed [000.184]: starting closed when closed [000.184]: finished in 0.000 s choose_exhausted [000.184]: starting choose_exhausted [000.184]: finished in 0.000 s exception passing: basic, from [000.184]: starting exception passing: basic, from [000.184]: finished in 0.000 s exception passing: basic, from_direct [000.184]: starting exception passing: basic, from_direct [000.184]: finished in 0.000 s exception passing: to_list [000.184]: starting exception passing: to_list [000.184]: finished in 0.000 s exception passing: mapped [000.184]: starting exception passing: mapped [000.184]: finished in 0.000 s exception passing: resume, not closed, from [000.184]: starting exception passing: resume, not closed, from [000.184]: finished in 0.000 s exception passing: resume, not closed, from_direct [000.184]: starting exception passing: resume, not closed, from_direct [000.184]: finished in 0.000 s iter_p [000.184]: starting iter_p [000.184]: finished in 0.000 s iter_s [000.184]: starting iter_s [000.184]: finished in 0.000 s map_p [000.184]: starting map_p [000.184]: finished in 0.000 s map_s [000.184]: starting map_s [000.184]: finished in 0.000 s fold_left_s [000.184]: starting fold_left_s [000.184]: finished in 0.000 s for_all_s [000.184]: starting for_all_s [000.184]: finished in 0.000 s for_all_p [000.184]: starting for_all_p [000.184]: finished in 0.000 s exists_s true [000.184]: starting exists_s true [000.184]: finished in 0.000 s exists_p true [000.184]: starting exists_p true [000.184]: finished in 0.000 s exists_s false [000.184]: starting exists_s false [000.184]: finished in 0.000 s exists_p false [000.184]: starting exists_p false [000.184]: finished in 0.000 s filter_s [000.184]: starting filter_s [000.184]: finished in 0.000 s filter_p [000.184]: starting filter_p [000.184]: finished in 0.000 s partition_p [000.184]: starting partition_p [000.184]: finished in 0.000 s partition_s [000.184]: starting partition_s [000.184]: finished in 0.000 s filter_map_p [000.184]: starting filter_map_p [000.184]: finished in 0.000 s filter_map_s [000.184]: starting filter_map_s [000.184]: finished in 0.000 s iteri_p [000.184]: starting iteri_p [000.184]: finished in 0.000 s iteri_s [000.184]: starting iteri_s [000.184]: finished in 0.000 s mapi_p [000.184]: starting mapi_p [000.184]: finished in 0.000 s mapi_s [000.184]: starting mapi_s [000.184]: finished in 0.000 s find_s existing [000.184]: starting find_s existing [000.184]: finished in 0.000 s find_s missing [000.184]: starting find_s missing [000.184]: finished in 0.000 s rev_map_p [000.184]: starting rev_map_p [000.184]: finished in 0.000 s rev_map_s [000.184]: starting rev_map_s [000.184]: finished in 0.000 s fold_right_s [000.184]: starting fold_right_s [000.184]: finished in 0.000 s iteri_p exception [000.184]: starting iteri_p exception [000.184]: finished in 0.000 s iteri_s exception [000.184]: starting iteri_s exception [000.184]: finished in 0.000 s map_s exception [000.184]: starting map_s exception [000.184]: finished in 0.000 s map_p exception [000.184]: starting map_p exception [000.184]: finished in 0.000 s mapi_s exception [000.184]: starting mapi_s exception [000.184]: finished in 0.000 s mapi_p exception [000.184]: starting mapi_p exception [000.184]: finished in 0.000 s rev_map_s exception [000.184]: starting rev_map_s exception [000.184]: finished in 0.000 s rev_map_p exception [000.184]: starting rev_map_p exception [000.184]: finished in 0.000 s fold_left_s exception [000.184]: starting fold_left_s exception [000.184]: finished in 0.000 s fold_right_s exception [000.184]: starting fold_right_s exception [000.184]: finished in 0.000 s for_all_p exception [000.184]: starting for_all_p exception [000.185]: finished in 0.000 s for_all_s exception [000.185]: starting for_all_s exception [000.185]: finished in 0.000 s exists_p exception [000.185]: starting exists_p exception [000.185]: finished in 0.000 s exists_s exception [000.185]: starting exists_s exception [000.185]: finished in 0.000 s find_s exception [000.185]: starting find_s exception [000.185]: finished in 0.000 s filter_p exception [000.185]: starting filter_p exception [000.185]: finished in 0.000 s filter_s exception [000.185]: starting filter_s exception [000.185]: finished in 0.000 s filter_map_p exception [000.185]: starting filter_map_p exception [000.185]: finished in 0.000 s filter_map_s exception [000.185]: starting filter_map_s exception [000.185]: finished in 0.000 s partition_p exception [000.185]: starting partition_p exception [000.185]: finished in 0.000 s partition_s exception [000.185]: starting partition_s exception [000.185]: finished in 0.000 s iter_p parallelism [000.185]: starting iter_p parallelism [000.185]: finished in 0.000 s iter_s serialization [000.185]: starting iter_s serialization [000.185]: finished in 0.000 s iteri_p parallelism [000.185]: starting iteri_p parallelism [000.185]: finished in 0.000 s iteri_s serialization [000.185]: starting iteri_s serialization [000.185]: finished in 0.000 s map_p parallelism [000.185]: starting map_p parallelism [000.185]: finished in 0.000 s map_s serialization [000.185]: starting map_s serialization [000.185]: finished in 0.000 s mapi_p parallelism [000.185]: starting mapi_p parallelism [000.185]: finished in 0.000 s mapi_s serialization [000.185]: starting mapi_s serialization [000.185]: finished in 0.000 s rev_map_p parallelism [000.185]: starting rev_map_p parallelism [000.185]: finished in 0.000 s rev_map_s serialization [000.185]: starting rev_map_s serialization [000.185]: finished in 0.000 s fold_left_s serialization [000.185]: starting fold_left_s serialization [000.185]: finished in 0.000 s fold_right_s serialization [000.185]: starting fold_right_s serialization [000.185]: finished in 0.000 s filter_map_p parallelism [000.185]: starting filter_map_p parallelism [000.185]: finished in 0.000 s filter_map_s serlialism [000.185]: starting filter_map_s serlialism [000.185]: finished in 0.000 s for_all_p parallelism [000.185]: starting for_all_p parallelism [000.185]: finished in 0.000 s for_all_s serialization [000.185]: starting for_all_s serialization [000.185]: finished in 0.000 s exists_p parallelism [000.185]: starting exists_p parallelism [000.185]: finished in 0.000 s exists_s serialization [000.185]: starting exists_s serialization [000.185]: finished in 0.000 s find_s serialization [000.185]: starting find_s serialization [000.185]: finished in 0.000 s filter_p parallelism [000.185]: starting filter_p parallelism [000.185]: finished in 0.000 s filter_s serialization [000.185]: starting filter_s serialization [000.185]: finished in 0.000 s filter_map_s serialization [000.185]: starting filter_map_s serialization [000.185]: finished in 0.000 s partition_p parallelism [000.185]: starting partition_p parallelism [000.185]: finished in 0.000 s partition_s serialization [000.185]: starting partition_s serialization [000.185]: finished in 0.000 s turn_off, add_hook [000.185]: starting turn_off, add_hook [000.185]: finished in 0.000 s turn_off: hook exception [000.185]: starting turn_off: hook exception [000.185]: finished in 0.000 s with_switch: regular exit [000.185]: starting with_switch: regular exit [000.185]: finished in 0.000 s with_switch: exception [000.185]: starting with_switch: exception [000.185]: finished in 0.000 s check [000.185]: starting check [000.185]: finished in 0.000 s is_on [000.185]: starting is_on [000.185]: finished in 0.000 s add_hook_or_exec [000.185]: starting add_hook_or_exec [000.185]: finished in 0.000 s turn_off waits for hooks: regular exit [000.185]: starting turn_off waits for hooks: regular exit [000.185]: finished in 0.000 s turn_off waits for hooks: hook exception [000.185]: starting turn_off waits for hooks: hook exception [000.185]: finished in 0.000 s cancel [000.185]: starting cancel [000.185]: finished in 0.000 s cancel while queued by unlock [000.185]: starting cancel while queued by unlock [000.185]: finished in 0.000 s maps [000.185]: starting maps [000.185]: finished in 0.000 s >|= is a variant of map [000.185]: starting >|= is a variant of map [000.185]: finished in 0.000 s map, error case [000.185]: starting map, error case [000.185]: finished in 0.000 s map_error [000.185]: starting map_error [000.185]: finished in 0.000 s map_error, error case [000.185]: starting map_error, error case [000.185]: finished in 0.000 s bind [000.185]: starting bind [000.185]: finished in 0.000 s bind, error case [000.185]: starting bind, error case [000.185]: finished in 0.000 s bind_error [000.185]: starting bind_error [000.185]: finished in 0.000 s bind_error, error case [000.185]: starting bind_error, error case [000.185]: finished in 0.000 s ok [000.185]: starting ok [000.185]: finished in 0.000 s error [000.185]: starting error [000.185]: finished in 0.000 s catch [000.185]: starting catch [000.185]: finished in 0.000 s catch, error case [000.185]: starting catch, error case [000.185]: finished in 0.000 s catch, bound raise [000.185]: starting catch, bound raise [000.185]: finished in 0.000 s catch, immediate raise [000.185]: starting catch, immediate raise [000.185]: finished in 0.000 s get_exn [000.185]: starting get_exn [000.185]: finished in 0.000 s get_exn, error case [000.186]: starting get_exn, error case [000.186]: finished in 0.000 s bind_lwt [000.186]: starting bind_lwt [000.186]: finished in 0.000 s bind_lwt, error case [000.186]: starting bind_lwt, error case [000.186]: finished in 0.000 s bind_lwt_error [000.186]: starting bind_lwt_error [000.186]: finished in 0.000 s bind_lwt_error, error case [000.186]: starting bind_lwt_error, error case [000.186]: finished in 0.000 s bind_result [000.186]: starting bind_result [000.186]: finished in 0.000 s bind_result, error case [000.186]: starting bind_result, error case [000.186]: finished in 0.000 s both ok [000.186]: starting both ok [000.186]: finished in 0.000 s both only fst error [000.186]: starting both only fst error [000.186]: finished in 0.000 s both only snd error [000.186]: starting both only snd error [000.186]: finished in 0.000 s both error, fst [000.186]: starting both error, fst [000.186]: finished in 0.000 s both error, snd [000.186]: starting both error, snd [000.186]: finished in 0.000 s iter [000.186]: starting iter [000.186]: finished in 0.000 s iter, error case [000.186]: starting iter, error case [000.186]: finished in 0.000 s iter_error [000.186]: starting iter_error [000.186]: finished in 0.000 s iter_error, success case [000.186]: starting iter_error, success case [000.186]: finished in 0.000 s let* [000.186]: starting let* [000.186]: finished in 0.000 s and* [000.186]: starting and* [000.186]: finished in 0.000 s let+/and+ [000.186]: starting let+/and+ [000.186]: finished in 0.000 s basic take [000.186]: starting basic take [000.186]: finished in 0.000 s take_available (full) [000.186]: starting take_available (full) [000.186]: finished in 0.000 s take_available (empty) [000.186]: starting take_available (empty) [000.186]: finished in 0.000 s take_available (twice) [000.186]: starting take_available (twice) [000.186]: finished in 0.000 s is_empty (full) [000.186]: starting is_empty (full) [000.186]: finished in 0.000 s is_empty (empty) [000.186]: starting is_empty (empty) [000.186]: finished in 0.000 s blocking put [000.186]: starting blocking put [000.186]: finished in 0.000 s put-take [000.186]: starting put-take [000.186]: finished in 0.000 s take-put [000.186]: starting take-put [000.186]: finished in 0.000 s enqueued writer [000.186]: starting enqueued writer [000.186]: finished in 0.000 s writer cancellation [000.186]: starting writer cancellation [000.186]: finished in 0.000 s basic wait [000.186]: starting basic wait [000.186]: finished in 0.000 s mutex unlocked during wait [000.186]: starting mutex unlocked during wait [000.186]: finished in 0.000 s mutex relocked after wait [000.186]: starting mutex relocked after wait [000.186]: finished in 0.000 s signal is not sticky [000.186]: starting signal is not sticky [000.186]: finished in 0.000 s broadcast [000.186]: starting broadcast [000.186]: finished in 0.000 s broadcast exception [000.186]: starting broadcast exception [000.186]: finished in 0.000 s basic create-use [000.186]: starting basic create-use [000.186]: finished in 0.000 s creator exception [000.186]: starting creator exception [000.186]: finished in 0.000 s pool elements are reused [000.186]: starting pool elements are reused [000.186]: finished in 0.000 s pool elements are validated when returned [000.186]: starting pool elements are validated when returned [000.186]: finished in 0.000 s validation exceptions are propagated to users [000.186]: starting validation exceptions are propagated to users [000.186]: finished in 0.000 s multiple creation [000.186]: starting multiple creation [000.186]: finished in 0.000 s users of an empty pool will wait [000.186]: starting users of an empty pool will wait [000.186]: finished in 0.000 s on check, good elements are retained [000.186]: starting on check, good elements are retained [000.186]: finished in 0.000 s on check, bad elements are disposed of and replaced [000.186]: starting on check, bad elements are disposed of and replaced [000.186]: finished in 0.000 s clear disposes of all elements [000.186]: starting clear disposes of all elements [000.186]: finished in 0.000 s waiter are notified on replacement [000.186]: starting waiter are notified on replacement [000.186]: finished in 0.000 s waiter are notified on replacement exception [000.186]: starting waiter are notified on replacement exception [000.186]: finished in 0.000 s check and validate can be used together [000.186]: starting check and validate can be used together [000.186]: finished in 0.000 s verify default check behavior [000.186]: starting verify default check behavior [000.186]: finished in 0.000 s create [000.186]: starting create [000.186]: finished in 0.000 s add_l [000.186]: starting add_l [000.186]: finished in 0.000 s add_r [000.186]: starting add_r [000.186]: finished in 0.000 s take_l Empty [000.186]: starting take_l Empty [000.186]: finished in 0.000 s take_l [000.186]: starting take_l [000.186]: finished in 0.000 s take_r Empty [000.186]: starting take_r Empty [000.186]: finished in 0.000 s take_r [000.186]: starting take_r [000.186]: finished in 0.000 s take_opt_l Empty [000.186]: starting take_opt_l Empty [000.186]: finished in 0.000 s take_opt_l [000.186]: starting take_opt_l [000.186]: finished in 0.000 s take_opt_r Empty [000.186]: starting take_opt_r Empty [000.186]: finished in 0.000 s take_opt_r [000.186]: starting take_opt_r [000.186]: finished in 0.000 s transfer_l Empty [000.186]: starting transfer_l Empty [000.186]: finished in 0.000 s transfer_l [000.186]: starting transfer_l [000.186]: finished in 0.000 s transfer_r Empty [000.186]: starting transfer_r Empty [000.186]: finished in 0.000 s transfer_r [000.186]: starting transfer_r [000.186]: finished in 0.000 s iter_l Empty [000.186]: starting iter_l Empty [000.186]: finished in 0.000 s iter_l [000.186]: starting iter_l [000.187]: finished in 0.000 s iter_r Empty [000.187]: starting iter_r Empty [000.187]: finished in 0.000 s iter_r [000.187]: starting iter_r [000.187]: finished in 0.000 s iter_node_l Empty [000.187]: starting iter_node_l Empty [000.187]: finished in 0.000 s iter_node_l [000.187]: starting iter_node_l [000.187]: finished in 0.000 s iter_node_r Empty [000.187]: starting iter_node_r Empty [000.187]: finished in 0.000 s iter_node_r [000.187]: starting iter_node_r [000.187]: finished in 0.000 s iter_node_l with removal [000.187]: starting iter_node_l with removal [000.187]: finished in 0.000 s iter_node_r with removal [000.187]: starting iter_node_r with removal [000.187]: finished in 0.000 s fold_l [000.187]: starting fold_l [000.187]: finished in 0.000 s fold_l Empty [000.187]: starting fold_l Empty [000.187]: finished in 0.000 s fold_r [000.187]: starting fold_r [000.187]: finished in 0.000 s fold_r Empty [000.187]: starting fold_r Empty [000.187]: finished in 0.000 s find_node_opt_l Empty [000.187]: starting find_node_opt_l Empty [000.187]: finished in 0.000 s find_node_opt_l not found [000.187]: starting find_node_opt_l not found [000.187]: finished in 0.000 s find_node_opt_l [000.187]: starting find_node_opt_l [000.187]: finished in 0.000 s find_node_opt_r Empty [000.187]: starting find_node_opt_r Empty [000.187]: finished in 0.000 s find_node_opt_r not found [000.187]: starting find_node_opt_r not found [000.187]: finished in 0.000 s find_node_opt_r [000.187]: starting find_node_opt_r [000.187]: finished in 0.000 s find_node_l Empty [000.187]: starting find_node_l Empty [000.187]: finished in 0.000 s find_node_l [000.187]: starting find_node_l [000.187]: finished in 0.000 s find_node_r Empty [000.187]: starting find_node_r Empty [000.187]: finished in 0.000 s find_node_r [000.187]: starting find_node_r [000.187]: finished in 0.000 s set [000.187]: starting set [000.187]: finished in 0.000 s fold_r with multiple removal [000.187]: starting fold_r with multiple removal [000.187]: finished in 0.000 s fold_l multiple removal [000.187]: starting fold_l multiple removal [000.187]: finished in 0.000 s find_node_r with multiple removal [000.187]: starting find_node_r with multiple removal [000.187]: finished in 0.000 s find_node_l with multiple removal [000.187]: starting find_node_l with multiple removal [000.187]: finished in 0.000 s fold_left [000.187]: starting fold_left [000.187]: finished in 0.000 s fold_left_s [000.187]: starting fold_left_s [000.187]: finished in 0.000 s map [000.187]: starting map [000.187]: finished in 0.000 s map_s [000.187]: starting map_s [000.187]: finished in 0.000 s filter [000.187]: starting filter [000.187]: finished in 0.000 s filter_s [000.187]: starting filter_s [000.187]: finished in 0.000 s iter_n(1) [000.187]: starting iter_n(1) [000.187]: finished in 0.000 s iter_n(2) [000.187]: starting iter_n(2) [000.187]: finished in 0.000 s iter_n(100) [000.187]: starting iter_n(100) [000.187]: finished in 0.000 s filter_map [000.187]: starting filter_map [000.187]: finished in 0.000 s filter_map_s [000.187]: starting filter_map_s [000.187]: finished in 0.000 s unfold [000.187]: starting unfold [000.187]: finished in 0.000 s unfold_lwt [000.187]: starting unfold_lwt [000.187]: finished in 0.000 s fold-into-exception-from-of-seq [000.187]: starting fold-into-exception-from-of-seq [000.187]: finished in 0.000 s fold-into-immediate-exception-from-of-seq [000.187]: starting fold-into-immediate-exception-from-of-seq [000.187]: finished in 0.000 s fold-into-exception-from-of-seq-lwt [000.187]: starting fold-into-exception-from-of-seq-lwt [000.187]: finished in 0.000 s fold-into-immediate-exception-from-of-seq-lwt [000.187]: starting fold-into-immediate-exception-from-of-seq-lwt [000.187]: finished in 0.000 s map [000.187]: starting map [000.187]: finished in 0.000 s map_s [000.187]: starting map_s [000.188]: finished in 0.000 s iter [000.188]: starting iter [000.188]: finished in 0.000 s iter_s [000.188]: starting iter_s [000.188]: finished in 0.000 s iter_p [000.188]: starting iter_p [000.188]: finished in 0.000 s iter_p (pause) [000.188]: starting iter_p (pause) [000.190]: finished in 0.002 s iter_n [000.190]: starting iter_n [000.191]: finished in 0.001 s iter_n (pause) [000.191]: starting iter_n (pause) [000.207]: finished in 0.016 s (cd _build/default/test/ppx && ./main.exe) Testing library 'ppx'... ................ Ok. 16 tests ran, 0 tests skipped in 1.20 seconds let [000.000]: starting let [000.000]: finished in 0.000 s nested let [000.000]: starting nested let [000.000]: finished in 0.000 s and let [000.000]: starting and let [000.000]: finished in 0.000 s match [000.000]: starting match [000.000]: finished in 0.000 s match-exn [000.000]: starting match-exn [000.000]: finished in 0.000 s if [000.000]: starting if [000.000]: finished in 0.000 s for [000.000]: starting for [000.601]: finished in 0.601 s while [000.601]: starting while [001.202]: finished in 0.601 s assert [001.202]: starting assert [001.202]: finished in 0.000 s try [001.202]: starting try [001.202]: finished in 0.000 s try raise [001.202]: starting try raise [001.202]: finished in 0.000 s try fallback [001.202]: starting try fallback [001.202]: finished in 0.000 s finally body [001.202]: starting finally body [001.202]: finished in 0.000 s finally exn [001.202]: starting finally exn [001.202]: finished in 0.000 s finally exn default [001.202]: starting finally exn default [001.202]: finished in 0.000 s structure let [001.202]: starting structure let [001.202]: finished in 0.000 s (cd _build/default/test/react && ./main.exe) Testing library 'react'... ........... Ok. 11 tests ran, 0 tests skipped in 4.51 seconds to_stream [000.000]: starting to_stream [000.000]: finished in 0.000 s to_stream 2 [000.000]: starting to_stream 2 [000.000]: finished in 0.000 s map_s [000.000]: starting map_s [000.000]: finished in 0.000 s map_p [000.000]: starting map_p [000.000]: finished in 0.000 s limit_race [000.000]: starting limit_race [002.501]: finished in 2.500 s of_stream [002.501]: starting of_stream [002.501]: finished in 0.000 s limit [002.501]: starting limit [002.501]: finished in 0.000 s with_finaliser lifetime [002.501]: starting with_finaliser lifetime [002.503]: finished in 0.002 s limit [002.503]: starting limit [002.503]: finished in 0.000 s limit race condition [002.503]: starting limit race condition [004.504]: finished in 2.001 s with_finaliser lifetime [004.504]: starting with_finaliser lifetime [004.505]: finished in 0.001 s (cd _build/default/test/unix && ./main.exe) Testing library 'unix'... .................................SSSSS..............................................................................................................SSSS...................................................................................... Ok. 229 tests ran, 9 tests skipped in 6.32 seconds wait [000.000]: starting wait [000.006]: finished in 0.006 s waitpid [000.006]: starting waitpid [000.011]: finished in 0.005 s waitpid: any child [000.011]: starting waitpid: any child [000.017]: finished in 0.006 s wait4 [000.017]: starting wait4 [000.023]: finished in 0.006 s wait4: any child [000.023]: starting wait4: any child [000.030]: finished in 0.007 s basic pread blocking [000.030]: starting basic pread blocking [000.031]: finished in 0.001 s basic pwrite blocking [000.031]: starting basic pwrite blocking [000.031]: finished in 0.000 s remove file blocking [000.031]: starting remove file blocking [000.031]: finished in 0.000 s basic pread nonblocking [000.032]: starting basic pread nonblocking [000.032]: finished in 0.000 s basic pwrite nonblocking [000.032]: starting basic pwrite nonblocking [000.032]: finished in 0.000 s remove file nonblocking [000.032]: starting remove file nonblocking [000.032]: finished in 0.000 s dup on socket blocking [000.032]: starting dup on socket blocking [000.032]: finished in 0.000 s dup on file blocking [000.033]: starting dup on file blocking [000.033]: finished in 0.001 s dup on socket nonblocking [000.033]: starting dup on socket nonblocking [000.033]: finished in 0.000 s dup on file nonblocking [000.033]: starting dup on file nonblocking [000.034]: finished in 0.000 s auto-flush [000.034]: starting auto-flush [000.134]: finished in 0.100 s auto-flush in atomic [000.134]: starting auto-flush in atomic [000.234]: finished in 0.100 s establish_server: implicit close on exception [000.234]: starting establish_server: implicit close on exception [000.285]: finished in 0.051 s with_temp_dir [000.285]: starting with_temp_dir [000.287]: finished in 0.002 s file does not exist [000.287]: starting file does not exist [000.287]: finished in 0.000 s file does not exist (invalid path) [000.287]: starting file does not exist (invalid path) [000.287]: finished in 0.000 s file does not exist (LargeFile) [000.287]: starting file does not exist (LargeFile) [000.287]: finished in 0.000 s file does not exist (LargeFile, invalid path) [000.287]: starting file does not exist (LargeFile, invalid path) [000.287]: finished in 0.000 s create file [000.287]: starting create file [000.287]: finished in 0.000 s file exists [000.287]: starting file exists [000.287]: finished in 0.000 s file exists (LargeFile) [000.288]: starting file exists (LargeFile) [000.288]: finished in 0.000 s read file [000.288]: starting read file [000.288]: finished in 0.000 s remove file [000.288]: starting remove file [000.288]: finished in 0.000 s Lwt_main.run: nested call [000.288]: starting Lwt_main.run: nested call [000.288]: finished in 0.000 s exception in action [000.288]: starting exception in action [002.290]: finished in 2.002 s openfile: O_CLOEXEC [002.290]: starting openfile: O_CLOEXEC not given [002.290]: starting openfile: O_KEEPEXEC [002.290]: starting openfile: O_CLOEXEC, O_KEEPEXEC [002.290]: starting openfile: O_KEEPEXEC, O_CLOEXEC [002.290]: starting utimes: basic [002.290]: starting utimes: current time [002.291]: starting utimes: missing file [002.291]: starting readdir: basic [002.291]: starting readdir: rewinddir [002.291]: starting readdir: readdir_n [002.291]: starting readdir: files_of_directory [002.292]: starting readdir: already closed [002.292]: starting io_vector_byte_count: basic [002.292]: starting io_vector_byte_count: basic [002.292]: finished in 0.000 s io_vector_byte_count: offsets, partials [002.292]: starting io_vector_byte_count: offsets, partials [002.292]: finished in 0.000 s io_vector_byte_count: drops [002.292]: starting io_vector_byte_count: drops [002.292]: finished in 0.000 s readv: basic non-blocking [002.292]: starting readv: basic blocking [002.292]: starting readv: drop [002.292]: starting readv: iovecs exceeding limit [002.292]: starting readv: windows [002.293]: skipping writev: basic non-blocking [002.293]: starting writev: basic blocking [002.295]: starting writev: slices [002.296]: starting writev: drop, is_empty [002.297]: starting writev: degenerate vectors [002.298]: starting writev: bad iovec [002.299]: starting writev: iovecs exceeding limit [002.299]: starting writev: negative drop [002.300]: starting writev: windows [002.302]: skipping send_msg, recv_msg [002.302]: starting send_msg, recv_msg (Lwt_bytes, old) [002.302]: starting bind: basic [002.302]: starting bind: Unix domain [002.302]: starting bind: closed [002.302]: starting bind: aborted [002.302]: starting getcwd [002.302]: starting getcwd and chdir [002.302]: starting getcwd and Unix.getcwd [002.302]: starting run_in_main [002.302]: starting run_in_main_dont_wait [002.302]: starting run_in_main_dont_wait_fail [002.302]: starting run_in_main_with_dont_wait [002.302]: starting getlogin and Unix.getlogin [002.302]: skipping getpwnam and Unix.getpwnam [002.302]: skipping getpwuid and Unix.getpwuid [002.302]: skipping getgrgid and Unix.getgrgid [002.302]: starting getgrnam and Unix.getgrnam [002.303]: starting establish_server_1: shutdown: client closes first [002.303]: starting open_connection: shutdown: server closes first [002.303]: starting establish_server: implicit close [002.303]: starting establish_server: explicit close [002.303]: starting with_connection [002.303]: starting with_close_connection: no duplicate exceptions [002.303]: starting open_temp_file [002.303]: starting with_temp_filename [002.303]: starting with_temp_filename close handle [002.303]: starting create_temp_dir [002.303]: starting file_length on directory [002.303]: starting input channel of_bytes initial position [002.303]: starting input channel of_bytes initial position [002.303]: finished in 0.000 s input channel of_bytes position after read [002.303]: starting input channel of_bytes position after read [002.303]: finished in 0.000 s input channel of_bytes position after set_position [002.303]: starting input channel of_bytes position after set_position [002.303]: finished in 0.000 s output channel of_bytes initial position [002.303]: starting output channel of_bytes initial position [002.303]: finished in 0.000 s output channel of_bytes position after read [002.303]: starting output channel of_bytes position after read [002.303]: finished in 0.000 s output channel of_bytes position after set_position [002.303]: starting output channel of_bytes position after set_position [002.303]: finished in 0.000 s NumberIO.LE.read_int [002.303]: starting NumberIO.LE.read_int [002.303]: finished in 0.000 s NumberIO.BE.read_int [002.303]: starting NumberIO.BE.read_int [002.303]: finished in 0.000 s NumberIO.LE.read_int16 [002.303]: starting NumberIO.LE.read_int16 [002.303]: finished in 0.000 s NumberIO.BE.read_int16 [002.303]: starting NumberIO.BE.read_int16 [002.303]: finished in 0.000 s NumberIO.LE.read_int16, negative [002.303]: starting NumberIO.LE.read_int16, negative [002.303]: finished in 0.000 s NumberIO.BE.read_int16, negative [002.303]: starting NumberIO.BE.read_int16, negative [002.303]: finished in 0.000 s NumberIO.LE.read_int32 [002.303]: starting NumberIO.LE.read_int32 [002.303]: finished in 0.000 s NumberIO.BE.read_int32 [002.303]: starting NumberIO.BE.read_int32 [002.303]: finished in 0.000 s NumberIO.LE.read_int64 [002.303]: starting NumberIO.LE.read_int64 [002.303]: finished in 0.000 s NumberIO.BE.read_int64 [002.303]: starting NumberIO.BE.read_int64 [002.303]: finished in 0.000 s NumberIO.LE.read_float32 [002.303]: starting NumberIO.LE.read_float32 [002.303]: finished in 0.000 s NumberIO.BE.read_float32 [002.303]: starting NumberIO.BE.read_float32 [002.303]: finished in 0.000 s NumberIO.LE.read_float64 [002.303]: starting NumberIO.LE.read_float64 [002.303]: finished in 0.000 s NumberIO.BE.read_float64 [002.303]: starting NumberIO.BE.read_float64 [002.303]: finished in 0.000 s NumberIO.LE.write_int [002.304]: starting NumberIO.LE.write_int [002.304]: finished in 0.000 s NumberIO.BE.write_int [002.304]: starting NumberIO.BE.write_int [002.304]: finished in 0.000 s NumberIO.LE.write_int16 [002.304]: starting NumberIO.LE.write_int16 [002.304]: finished in 0.000 s NumberIO.BE.write_int16 [002.304]: starting NumberIO.BE.write_int16 [002.304]: finished in 0.000 s NumberIO.LE.write_int32 [002.304]: starting NumberIO.LE.write_int32 [002.304]: finished in 0.000 s NumberIO.BE.write_int32 [002.304]: starting NumberIO.BE.write_int32 [002.304]: finished in 0.000 s NumberIO.LE.write_int64 [002.304]: starting NumberIO.LE.write_int64 [002.304]: finished in 0.000 s NumberIO.BE.write_int64 [002.304]: starting NumberIO.BE.write_int64 [002.304]: finished in 0.000 s NumberIO.LE.write_float32 [002.304]: starting NumberIO.LE.write_float32 [002.304]: finished in 0.000 s NumberIO.BE.write_float32 [002.304]: starting NumberIO.BE.write_float32 [002.304]: finished in 0.000 s NumberIO.LE.write_float64 [002.304]: starting NumberIO.LE.write_float64 [002.304]: finished in 0.000 s NumberIO.BE.write_float64 [002.304]: starting NumberIO.BE.write_float64 [002.304]: finished in 0.000 s Write from Lwt_bytes [002.304]: starting Write from Lwt_bytes [002.304]: finished in 0.000 s Read from Lwt_bytes [002.304]: starting Read from Lwt_bytes [002.304]: finished in 0.000 s lazy_undefined [002.304]: starting subproc stdout can be redirected to null [002.304]: starting subproc stderr can be redirected to null [002.305]: starting subproc cannot write on closed stdout [002.305]: starting subproc cannot write on closed stderr [002.305]: starting can write to subproc stdin [002.306]: starting can read from subproc stdout [002.306]: starting can read from subproc stderr [002.306]: starting overrides env [002.307]: starting passes env [002.307]: starting inherits env [002.308]: starting libev: timer delays are not too short [002.309]: starting mcast-join-loop [003.309]: starting mcast-nojoin-loop [003.309]: starting mcast-join-noloop [003.310]: starting mcast-nojoin-noloop [003.316]: starting flushing [003.316]: starting flushing [003.316]: finished in 0.000 s with combinator [003.316]: starting with combinator [003.316]: finished in 0.000 s box [003.316]: starting box [003.316]: finished in 0.000 s boxsplit [003.316]: starting boxsplit [003.316]: finished in 0.000 s box close with flush [003.316]: starting box close with flush [003.316]: finished in 0.000 s stream [003.316]: starting stream [003.316]: finished in 0.000 s basic [003.316]: starting not started [003.316]: starting double start [003.316]: starting restart [003.316]: starting stop [003.316]: starting stop when not stopped [003.316]: starting stop when not stopped [003.316]: finished in 0.000 s invalid delay [003.316]: starting invalid delay [003.316]: finished in 0.000 s change [003.316]: starting change does not start [003.317]: starting change after start [003.317]: starting change: invalid delay [003.317]: starting change: invalid delay [003.317]: finished in 0.000 s set_exn_handler [003.317]: starting two [003.317]: starting simultaneous [003.317]: starting two, first stopped [003.317]: starting create [003.317]: starting create [003.317]: finished in 0.000 s get/set [003.317]: starting get/set [003.317]: finished in 0.000 s get out of bounds : lower limit [003.317]: starting get out of bounds : lower limit [003.317]: finished in 0.000 s get out of bounds : upper limit [003.317]: starting get out of bounds : upper limit [003.317]: finished in 0.000 s set out of bounds : lower limit [003.317]: starting set out of bounds : lower limit [003.317]: finished in 0.000 s set out of bounds : upper limit [003.317]: starting set out of bounds : upper limit [003.317]: finished in 0.000 s unsafe_get/unsafe_set [003.317]: starting unsafe_get/unsafe_set [003.317]: finished in 0.000 s of bytes [003.317]: starting of bytes [003.317]: finished in 0.000 s of string [003.317]: starting of string [003.317]: finished in 0.000 s to bytes [003.317]: starting to bytes [003.317]: finished in 0.000 s to string [003.317]: starting to string [003.317]: finished in 0.000 s blit [003.317]: starting blit [003.317]: finished in 0.000 s blit source out of bounds: lower limit [003.317]: starting blit source out of bounds: lower limit [003.317]: finished in 0.000 s blit source out of bounds: upper limit [003.317]: starting blit source out of bounds: upper limit [003.317]: finished in 0.000 s blit destination out of bounds: lower limit [003.317]: starting blit destination out of bounds: lower limit [003.317]: finished in 0.000 s blit destination out of bounds: upper limit [003.317]: starting blit destination out of bounds: upper limit [003.317]: finished in 0.000 s blit length out of bounds: lower limit [003.317]: starting blit length out of bounds: lower limit [003.317]: finished in 0.000 s blit from bytes [003.317]: starting blit from bytes [003.317]: finished in 0.000 s blit from bytes source out of bounds: lower limit [003.318]: starting blit from bytes source out of bounds: lower limit [003.318]: finished in 0.000 s blit from bytes source out of bounds: upper limit [003.318]: starting blit from bytes source out of bounds: upper limit [003.318]: finished in 0.000 s blit from bytes destination out of bounds: lower limit [003.318]: starting blit from bytes destination out of bounds: lower limit [003.318]: finished in 0.000 s blit from bytes destination out of bounds: upper limit [003.318]: starting blit from bytes destination out of bounds: upper limit [003.318]: finished in 0.000 s blit from bytes length out of bounds: lower limit [003.318]: starting blit from bytes length out of bounds: lower limit [003.318]: finished in 0.000 s blit from string [003.318]: starting blit from string [003.318]: finished in 0.000 s blit from string source out of bounds: lower limit [003.318]: starting blit from string source out of bounds: lower limit [003.318]: finished in 0.000 s blit from string source out of bounds: upper limit [003.318]: starting blit from string source out of bounds: upper limit [003.318]: finished in 0.000 s blit from string destination out of bounds: lower limit [003.318]: starting blit from string destination out of bounds: lower limit [003.318]: finished in 0.000 s blit from string destination out of bounds: upper limit [003.318]: starting blit from string destination out of bounds: upper limit [003.318]: finished in 0.000 s blit from string length out of bounds: lower limit [003.318]: starting blit from string length out of bounds: lower limit [003.318]: finished in 0.000 s blit from string length out of bounds: upper limit [003.318]: starting blit from string length out of bounds: upper limit [003.318]: finished in 0.000 s blit to bytes [003.318]: starting blit to bytes [003.318]: finished in 0.000 s blit to bytes source out of bounds: lower limit [003.318]: starting blit to bytes source out of bounds: lower limit [003.318]: finished in 0.000 s blit to bytes source out of bounds: upper limit [003.318]: starting blit to bytes source out of bounds: upper limit [003.318]: finished in 0.000 s blit to bytes destination out of bounds: lower limit [003.318]: starting blit to bytes destination out of bounds: lower limit [003.318]: finished in 0.000 s blit to bytes destination out of bounds: upper limit [003.318]: starting blit to bytes destination out of bounds: upper limit [003.318]: finished in 0.000 s blit to bytes length out of bounds: lower limit [003.318]: starting blit to bytes length out of bounds: lower limit [003.318]: finished in 0.000 s unsafe blit [003.318]: starting unsafe blit [003.318]: finished in 0.000 s unsafe blit from bytes [003.318]: starting unsafe blit from bytes [003.318]: finished in 0.000 s unsafe blit from string [003.318]: starting unsafe blit from string [003.318]: finished in 0.000 s unsafe blit to bytes [003.318]: starting unsafe blit to bytes [003.318]: finished in 0.000 s proxy [003.318]: starting proxy [003.318]: finished in 0.000 s proxy offset out of bounds: lower limit [003.319]: starting proxy offset out of bounds: lower limit [003.319]: finished in 0.000 s proxy offset out of bounds: upper limit [003.319]: starting proxy offset out of bounds: upper limit [003.319]: finished in 0.000 s proxy length out of bounds: lower limit [003.319]: starting proxy length out of bounds: lower limit [003.319]: finished in 0.000 s extract [003.319]: starting extract [003.319]: finished in 0.000 s extract offset out of bounds: lower limit [003.319]: starting extract offset out of bounds: lower limit [003.319]: finished in 0.000 s extract offset out of bounds: upper limit [003.319]: starting extract offset out of bounds: upper limit [003.319]: finished in 0.000 s extract length out of bounds: lower limit [003.319]: starting extract length out of bounds: lower limit [003.319]: finished in 0.000 s copy [003.319]: starting copy [003.319]: finished in 0.000 s fill [003.319]: starting fill [003.319]: finished in 0.000 s fill offset out of bounds: lower limit [003.319]: starting fill offset out of bounds: lower limit [003.319]: finished in 0.000 s fill offset out of bounds: upper limit [003.319]: starting fill offset out of bounds: upper limit [003.319]: finished in 0.000 s fill length out of bounds lower limit [003.319]: starting fill length out of bounds lower limit [003.319]: finished in 0.000 s unsafe fill [003.319]: starting unsafe fill [003.319]: finished in 0.000 s bytes read [003.319]: starting bytes write [003.319]: starting bytes recv [003.319]: starting bytes send [003.319]: starting bytes recvfrom [003.319]: starting bytes sendto [003.320]: starting bytes recv_msg [003.320]: starting bytes send_msg [003.320]: starting send_msgto [003.320]: starting map_file [003.320]: starting map_file [003.320]: finished in 0.000 s page_size [003.320]: starting page_size [003.320]: finished in 0.000 s mincore buffer length = page_size * 2, n_states = 1 [003.320]: starting mincore buffer length = page_size * 2, n_states = 2 [003.320]: starting mincore buffer length = page_size * 2 + 1, n_states = 2 [003.320]: starting mincore buffer length = page_size , n_states = 0 [003.320]: starting wait_mincore correct bounds [003.320]: starting wait_mincore offset < 0 [003.320]: starting wait_mincore offset > buffer length [003.320]: starting sleep [003.320]: starting timeout [003.320]: starting with_timeout : no timeout [003.320]: starting with_timeout : timeout [003.320]: starting pause [003.320]: starting pause [003.320]: finished in 0.000 s auto_pause [003.320]: starting utimes: basic [003.323]: finished in 1.032 s utimes: missing file [003.323]: finished in 1.032 s bind: basic [003.332]: finished in 1.030 s bind: closed [003.332]: finished in 1.030 s bind: aborted [003.332]: finished in 1.030 s getcwd [003.332]: finished in 1.030 s getcwd and Unix.getcwd [003.332]: finished in 1.030 s getgrgid and Unix.getgrgid [003.332]: finished in 1.030 s getgrnam and Unix.getgrnam [003.332]: finished in 1.029 s file_length on directory [003.332]: finished in 1.029 s mcast-join-loop [003.332]: finished in 0.023 s mcast-nojoin-loop [003.332]: finished in 0.023 s mcast-join-noloop [003.332]: finished in 0.023 s mcast-nojoin-noloop [003.332]: finished in 0.016 s with_timeout : no timeout [003.332]: finished in 0.012 s can write to subproc stdin [003.332]: finished in 1.027 s subproc cannot write on closed stderr [003.332]: finished in 1.027 s subproc cannot write on closed stdout [003.333]: finished in 1.028 s subproc stderr can be redirected to null [003.333]: finished in 1.028 s subproc stdout can be redirected to null [003.333]: finished in 1.028 s can read from subproc stdout [003.333]: finished in 1.027 s can read from subproc stderr [003.333]: finished in 1.026 s overrides env [003.333]: finished in 1.026 s passes env [003.333]: finished in 1.025 s inherits env [003.333]: finished in 1.025 s openfile: O_CLOEXEC [003.333]: finished in 1.043 s utimes: current time [003.333]: finished in 1.042 s readdir: already closed [003.333]: finished in 1.041 s readv: basic non-blocking [003.333]: finished in 1.041 s readv: drop [003.333]: finished in 1.041 s readv: iovecs exceeding limit [003.333]: finished in 1.041 s writev: basic non-blocking [003.333]: finished in 1.041 s writev: slices [003.333]: finished in 1.037 s writev: drop, is_empty [003.333]: finished in 1.036 s writev: degenerate vectors [003.333]: finished in 1.035 s writev: bad iovec [003.333]: finished in 1.034 s writev: iovecs exceeding limit [003.333]: finished in 1.034 s writev: negative drop [003.333]: finished in 1.033 s bind: Unix domain [003.334]: finished in 1.032 s with_close_connection: no duplicate exceptions [003.334]: finished in 1.030 s create_temp_dir [003.334]: finished in 1.030 s bytes recvfrom [003.334]: finished in 0.014 s bytes recv_msg [003.334]: finished in 0.014 s bytes sendto [003.334]: finished in 0.014 s bytes send_msg [003.334]: finished in 0.014 s send_msgto [003.334]: finished in 0.014 s lazy_undefined [003.334]: finished in 1.030 s readdir: files_of_directory [003.334]: finished in 1.042 s readv: basic blocking [003.334]: finished in 1.042 s writev: basic blocking [003.334]: finished in 1.039 s getcwd and chdir [003.334]: finished in 1.032 s open_temp_file [003.334]: finished in 1.031 s bytes read [003.334]: finished in 0.015 s mincore buffer length = page_size * 2, n_states = 1 [003.334]: finished in 0.014 s mincore buffer length = page_size * 2, n_states = 2 [003.334]: finished in 0.014 s mincore buffer length = page_size * 2 + 1, n_states = 2 [003.334]: finished in 0.014 s mincore buffer length = page_size , n_states = 0 [003.334]: finished in 0.014 s wait_mincore correct bounds [003.334]: finished in 0.014 s wait_mincore offset < 0 [003.334]: finished in 0.014 s wait_mincore offset > buffer length [003.334]: finished in 0.014 s run_in_main_dont_wait [003.334]: finished in 1.032 s run_in_main_dont_wait_fail [003.334]: finished in 1.032 s run_in_main_with_dont_wait [003.334]: finished in 1.032 s establish_server: explicit close [003.334]: finished in 1.031 s bytes recv [003.334]: finished in 0.015 s bytes send [003.334]: finished in 0.015 s establish_server_1: shutdown: client closes first [003.334]: finished in 1.032 s with_connection [003.334]: finished in 1.031 s openfile: O_KEEPEXEC [003.335]: finished in 1.044 s openfile: O_CLOEXEC not given [003.335]: finished in 1.044 s readdir: readdir_n [003.335]: finished in 1.043 s send_msg, recv_msg [003.335]: finished in 1.033 s send_msg, recv_msg (Lwt_bytes, old) [003.335]: finished in 1.033 s with_temp_filename close handle [003.335]: finished in 1.031 s open_connection: shutdown: server closes first [003.335]: finished in 1.032 s bytes write [003.335]: finished in 0.016 s readdir: basic [003.335]: finished in 1.044 s with_temp_filename [003.335]: finished in 1.032 s openfile: O_KEEPEXEC, O_CLOEXEC [003.335]: finished in 1.044 s readdir: rewinddir [003.335]: finished in 1.044 s openfile: O_CLOEXEC, O_KEEPEXEC [003.335]: finished in 1.045 s run_in_main [003.343]: finished in 1.041 s establish_server: implicit close [003.385]: finished in 1.082 s libev: timer delays are not too short [003.810]: finished in 1.501 s sleep [004.320]: finished in 1.000 s timeout [004.321]: finished in 1.000 s with_timeout : timeout [004.321]: finished in 1.000 s auto_pause [004.321]: finished in 1.000 s simultaneous [005.318]: finished in 2.001 s set_exn_handler [005.318]: finished in 2.002 s change after start [005.318]: finished in 2.002 s change [005.318]: finished in 2.002 s basic [005.318]: finished in 2.002 s not started [006.317]: finished in 3.001 s double start [006.318]: finished in 3.001 s stop [006.318]: finished in 3.001 s change does not start [006.318]: finished in 3.001 s two, first stopped [006.318]: finished in 3.002 s two [006.318]: finished in 3.002 s restart [006.318]: finished in 3.002 s 2026-01-23 11:51.35 ---> saved as "110019392fdefc0ae67475fe7565aae57b73690230fa665722a5aff73370318e" Job succeeded 2026-01-23 11:51.36: Job succeeded