2026-01-26 09:07.29: New job: test ocsigen/lwt https://github.com/ocsigen/lwt.git#refs/pull/967/head (7df872366b2884f67d8dec5649dbe3ce4d875557) (linux-x86_64:ubuntu-25.10-4.14_opam-2.5)Base: ocaml/opam:ubuntu-25.10-ocaml-4.14@sha256:f196fdde96e2d2ab463174a3fd909d35d2174726cd99ad8d090aae5fc21672ecOpam project buildTo reproduce locally:git clone --recursive "https://github.com/ocsigen/lwt.git" && cd "lwt" && git fetch origin "refs/pull/967/head" && git reset --hard 7df87236cat > Dockerfile <<'END-OF-DOCKERFILE'FROM ocaml/opam:ubuntu-25.10-ocaml-4.14@sha256:f196fdde96e2d2ab463174a3fd909d35d2174726cd99ad8d090aae5fc21672ec# ubuntu-25.10-4.14_opam-2.5USER 1000:1000ENV CLICOLOR_FORCE="1"ENV OPAMCOLOR="always"WORKDIR /srcRUN sudo ln -f /usr/bin/opam-2.5 /usr/bin/opamRUN opam init --reinit -niRUN uname -rs && opam exec -- ocaml -version && opam --versionWORKDIR /srcRUN sudo chown opam /srcRUN 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 -uCOPY --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 $DEPSRUN opam install $DEPSCOPY --chown=1000:1000 . /srcRUN opam exec -- dune build --only-packages=lwt_retry,lwt_react,lwt_ppx,lwt @install @check @runtest && rm -rf _buildEND-OF-DOCKERFILEdocker build .END-REPRO-BLOCK2026-01-26 09:07.29: Using cache hint "ocsigen/lwt-ocaml/opam:ubuntu-25.10-ocaml-4.14@sha256:f196fdde96e2d2ab463174a3fd909d35d2174726cd99ad8d090aae5fc21672ec-ubuntu-25.10-4.14_opam-2.5-f80664a0fc43791440912ae83095cdb3"2026-01-26 09:07.29: Using OBuilder spec:((from ocaml/opam:ubuntu-25.10-ocaml-4.14@sha256:f196fdde96e2d2ab463174a3fd909d35d2174726cd99ad8d090aae5fc21672ec)(comment ubuntu-25.10-4.14_opam-2.5)(user (uid 1000) (gid 1000))(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-26 09:07.29: Waiting for resource in pool OCluster2026-01-26 09:07.29: Waiting for worker…2026-01-26 09:07.29: Got resource from pool OClusterBuilding on toxis.caelum.ci.devHEAD is now at 1fdcd4bf5 Merge pull request #1078 from yawaramin/doc-lwt-ppx-sequenceHEAD is now at 7df872366 TEMP deactivate other workflows whilst debugging windows issue(from ocaml/opam:ubuntu-25.10-ocaml-4.14@sha256:f196fdde96e2d2ab463174a3fd909d35d2174726cd99ad8d090aae5fc21672ec)2026-01-26 09:07.31 ---> using "75df70da953d671714cf7970335f8c27515d2f02b005faf9feb62dfc11340dab" from cache/: (comment ubuntu-25.10-4.14_opam-2.5)/: (user (uid 1000) (gid 1000))/: (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-26 09:07.31 ---> using "0868f6818629c9b1fa57e9f1c50087daaf981ede9b3ef45b1cac6a45ce3b4ca0" 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] yFormat upgrade done.<><> Updating repositories ><><><><><><><><><><><><><><><><><><><><><><><><><><>[ERROR] Could not update repository "ocaml-patches-overlay": "/usr/bin/git fetch -q" exited with code 128 "fatal: unable to access 'https://github.com/ocurrent/opam-repository/': Could not resolve host: github.com"[default] Initialised2026-01-26 09:07.31 ---> using "89677c0f84fccc82a17b3a8527b0294b759518be030b5c5a65a2fc0031b0a85c" from cache/src: (run (shell "uname -rs && opam exec -- ocaml -version && opam --version"))Linux 5.15.0-134-genericThe OCaml toplevel, version 4.14.22.5.02026-01-26 09:07.31 ---> using "296a891161693ac3543b75e3b7cd7f7cf6580366f514416736fdfe515b00ffd0" from cache/src: (workdir /src)/src: (run (shell "sudo chown opam /src"))2026-01-26 09:07.31 ---> using "fcaed8e9cab88da0ffb726db9094aacfbc5c85d997ff47780dee0c4350554c52" 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_HEADa6b2f19780..64cd1a4d8a master -> origin/master873cb18c37 Merge pull request #29216 from shonfeder/release-dune-3.21.0<><> Updating package repositories ><><><><><><><><><><><><><><><><><><><><><><>[default] synchronised from git+file:///home/opam/opam-repository[ocaml-patches-overlay] synchronised from git+https://github.com/ocurrent/opam-repository#patchesThe following actions will be performed:=== recompile 2 packages- recompile ocaml 4.14.2 [upstream or system changes]- recompile opam-depext 1.2.3 [uses ocaml]<><> Processing actions <><><><><><><><><><><><><><><><><><><><><><><><><><><><>-> retrieved opam-depext.1.2.3 (cached)-> removed opam-depext.1.2.3-> removed ocaml.4.14.2-> installed ocaml.4.14.2-> installed opam-depext.1.2.3Done.<><> opam-depext.1.2.3 installed successfully <><><><><><><><><><><><><><><><><>=> opam-depext is unnecessary when used with opam >= 2.1. Please use opam install directly instead# To update the current shell environment, run: eval $(opam env)2026-01-26 09:07.31 ---> using "39defe92d95778e17f342e83d7489986e082573b183e1145409ad3d2115053ae" 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-26 09:07.31 ---> using "f3cf5989db5740f3a4f62cd1a845dae9a603fe3b35da704c876105ecf78d0eb7" 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-26 09:07.31 ---> using "75ed434da22aa393b994a5545b7b0f6a527981be0c5bfd8fd651aa945cde7fac" from cache/src: (run (network host)(shell "echo '(lang dune 3.0)' > './dune-project'"))2026-01-26 09:07.31 ---> using "a0d74bfb36185e5e8a9a074119b893ff70596f4beb1fc566f9775a9de114db13" 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://security.ubuntu.com/ubuntu questing-security InRelease [136 kB]- Get:2 http://security.ubuntu.com/ubuntu questing-security/restricted amd64 Packages [120 kB]- Get:3 http://security.ubuntu.com/ubuntu questing-security/universe amd64 Packages [119 kB]- Get:4 http://security.ubuntu.com/ubuntu questing-security/main amd64 Packages [184 kB]- Hit:5 http://archive.ubuntu.com/ubuntu questing InRelease- Get:6 http://archive.ubuntu.com/ubuntu questing-updates InRelease [136 kB]- Get:7 http://archive.ubuntu.com/ubuntu questing-backports InRelease [133 kB]- Get:8 http://archive.ubuntu.com/ubuntu questing-updates/universe amd64 Packages [176 kB]- Get:9 http://archive.ubuntu.com/ubuntu questing-updates/main amd64 Packages [306 kB]- Get:10 http://archive.ubuntu.com/ubuntu questing-updates/multiverse amd64 Packages [19.4 kB]- Get:11 http://archive.ubuntu.com/ubuntu questing-updates/restricted amd64 Packages [147 kB]- Fetched 1475 kB in 1s (2355 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-26 09:07.31 ---> using "8b59d9539779c1e9918ede3de0a78bf0148e47a0f7d19e6f39d5b40003a8c1ee" 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 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 react.1.2.2 (cached)-> retrieved sexplib0.v0.17.0 (cached)-> retrieved stdlib-shims.0.3.0 (cached)-> retrieved topkg.1.1.1 (cached)-> retrieved ppxlib.0.37.0 (cached)-> retrieved dune.3.21.0, dune-configurator.3.21.0 (cached)-> installed ocamlbuild.0.16.1-> installed ocamlfind.1.9.8-> installed base-bytes.base-> installed topkg.1.1.1-> installed react.1.2.2-> installed dune.3.21.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 stdlib-shims.0.3.0-> installed ocplib-endian.1.2-> installed dune-configurator.3.21.0-> installed ppxlib.0.37.0Done.# To update the current shell environment, run: eval $(opam env)2026-01-26 09:07.31 ---> using "66cb226dfe40b84e28366c3e9b0662e1190b6336a3d9ee7eac2ab7ac25de1e01" from cache/src: (copy (src .) (dst /src))2026-01-26 09:07.31 ---> saved as "193ff6283f3ea3460e293fca3e75775d2d72516c6c3b45fada9d36fe2275c17e"/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[lwt_retry] can format retries outcomes [000.000]: starting[lwt_retry] can format retries outcomes [000.000]: finished in 0.000 s[lwt_retry] can format fatal outcomes [000.000]: starting[lwt_retry] can format fatal outcomes [000.000]: finished in 0.000 s[lwt_retry] can format with default printer [000.000]: starting[lwt_retry] can format with default printer [000.000]: finished in 0.000 s[lwt_retry] success without retry [000.000]: starting[lwt_retry] success without retry [000.000]: finished in 0.000 s[lwt_retry] does not run extra attempts [000.000]: starting[lwt_retry] does not run extra attempts [000.000]: finished in 0.000 s[lwt_retry] just retries [000.000]: starting[lwt_retry] just retries [000.000]: finished in 0.000 s[lwt_retry] retries before fatal error [000.000]: starting[lwt_retry] retries before fatal error [000.000]: finished in 0.000 s[lwt_retry] retries before success [000.000]: starting[lwt_retry] retries before success [000.000]: finished in 0.000 s[lwt_retry] [n_times 0] runs one attempt [000.000]: starting[lwt_retry] [n_times 0] runs one attempt [000.000]: finished in 0.000 s[lwt_retry] n_times gives up on a fatal error [000.000]: starting[lwt_retry] n_times gives up on a fatal error [000.000]: finished in 0.000 s[lwt_retry] n_times gives a retry error when exhausted [000.000]: starting[lwt_retry] n_times gives a retry error when exhausted [000.000]: finished in 0.000 s[lwt_retry] n_times is ok on success [000.000]: starting[lwt_retry] n_times is ok on success [000.000]: finished in 0.000 s[lwt_retry] n_times on negative raises Invalid_argument [000.000]: starting[lwt_retry] n_times on negative raises Invalid_argument [000.000]: finished in 0.000 s[lwt_retry] with_sleep really does sleep [000.000]: starting[lwt_retry] with_sleep really does sleep [000.011]: finished in 0.010 s(cd _build/default/test/unix && ./wait.exe)Testing library 'unix.wait'........Ok. 5 tests ran, 0 tests skipped in 0.04 seconds[lwt_unix.wait] wait [000.000]: starting[lwt_unix.wait] wait [000.009]: finished in 0.009 s[lwt_unix.wait] waitpid [000.009]: starting[lwt_unix.wait] waitpid [000.017]: finished in 0.008 s[lwt_unix.wait] waitpid: any child [000.017]: starting[lwt_unix.wait] waitpid: any child [000.025]: finished in 0.008 s[lwt_unix.wait] wait4 [000.025]: starting[lwt_unix.wait] wait4 [000.032]: finished in 0.007 s[lwt_unix.wait] wait4: any child [000.032]: starting[lwt_unix.wait] wait4: any child [000.040]: finished in 0.008 s(cd _build/default/test/unix && ./cloexec.exe)Testing library 'unix.cloexec'........Ok. 5 tests ran, 0 tests skipped in 0.02 seconds[lwt_unix.cloexec] openfile: O_CLOEXEC [000.000]: starting[lwt_unix.cloexec] openfile: O_CLOEXEC not given [000.000]: starting[lwt_unix.cloexec] openfile: O_KEEPEXEC [000.000]: starting[lwt_unix.cloexec] openfile: O_CLOEXEC, O_KEEPEXEC [000.000]: starting[lwt_unix.cloexec] openfile: O_KEEPEXEC, O_CLOEXEC [000.000]: starting[lwt_unix.cloexec] openfile: O_KEEPEXEC, O_CLOEXEC [000.008]: finished in 0.008 s[lwt_unix.cloexec] openfile: O_CLOEXEC not given [000.008]: finished in 0.008 s[lwt_unix.cloexec] openfile: O_KEEPEXEC [000.014]: finished in 0.014 s[lwt_unix.cloexec] openfile: O_CLOEXEC, O_KEEPEXEC [000.015]: finished in 0.015 s[lwt_unix.cloexec] openfile: O_CLOEXEC [000.023]: finished in 0.023 s(cd _build/default/test/core && ./main.exe)Testing library 'core'..................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................SSSSSSSSSSSSSSSSSSSSSSS..................................................................................................................................................Ok. 705 tests ran, 23 tests skipped in 0.38 seconds[trivial promises] return [000.000]: starting[trivial promises] return [000.000]: finished in 0.000 s[trivial promises] reject [000.000]: starting[trivial promises] reject [000.000]: finished in 0.000 s[trivial promises] of_result: fulfilled [000.000]: starting[trivial promises] of_result: fulfilled [000.000]: finished in 0.000 s[trivial promises] of_result: rejected [000.000]: starting[trivial promises] of_result: rejected [000.000]: finished in 0.000 s[trivial promises] return_unit [000.000]: starting[trivial promises] return_unit [000.000]: finished in 0.000 s[trivial promises] return_true [000.000]: starting[trivial promises] return_true [000.000]: finished in 0.000 s[trivial promises] return_false [000.000]: starting[trivial promises] return_false [000.000]: finished in 0.000 s[trivial promises] return_none [000.000]: starting[trivial promises] return_none [000.000]: finished in 0.000 s[trivial promises] return_some [000.000]: starting[trivial promises] return_some [000.000]: finished in 0.000 s[trivial promises] return_ok [000.000]: starting[trivial promises] return_ok [000.000]: finished in 0.000 s[trivial promises] return_error [000.000]: starting[trivial promises] return_error [000.000]: finished in 0.000 s[trivial promises] fail_with [000.000]: starting[trivial promises] fail_with [000.000]: finished in 0.000 s[trivial promises] fail_invalid_arg [000.000]: starting[trivial promises] fail_invalid_arg [000.000]: finished in 0.000 s[initial promises] wait: pending [000.000]: starting[initial promises] wait: pending [000.000]: finished in 0.000 s[initial promises] task: pending [000.000]: starting[initial promises] task: pending [000.000]: finished in 0.000 s[initial promises] wait: fulfill [000.000]: starting[initial promises] wait: fulfill [000.000]: finished in 0.000 s[initial promises] task: fulfill [000.000]: starting[initial promises] task: fulfill [000.000]: finished in 0.000 s[initial promises] wait: reject [000.000]: starting[initial promises] wait: reject [000.000]: finished in 0.000 s[initial promises] task: reject [000.000]: starting[initial promises] task: reject [000.000]: finished in 0.000 s[initial promises] wait: resolve [000.000]: starting[initial promises] wait: resolve [000.000]: finished in 0.000 s[initial promises] task: resolve [000.000]: starting[initial promises] task: resolve [000.000]: finished in 0.000 s[double resolve] wakeup: double use on wait [000.000]: starting[double resolve] wakeup: double use on wait [000.000]: finished in 0.000 s[double resolve] wakeup: double use on task [000.000]: starting[double resolve] wakeup: double use on task [000.000]: finished in 0.000 s[double resolve] wakeup_exn: double use on wait [000.001]: starting[double resolve] wakeup_exn: double use on wait [000.001]: finished in 0.000 s[double resolve] wakeup_exn: double use on task [000.001]: starting[double resolve] wakeup_exn: double use on task [000.001]: finished in 0.000 s[double resolve] wakeup_result: double use on wait [000.001]: starting[double resolve] wakeup_result: double use on wait [000.001]: finished in 0.000 s[double resolve] wakeup_result: double use on task [000.001]: starting[double resolve] wakeup_result: double use on task [000.001]: finished in 0.000 s[bind] already fulfilled [000.001]: starting[bind] already fulfilled [000.001]: finished in 0.000 s[bind] already fulfilled, f raises [000.001]: starting[bind] already fulfilled, f raises [000.001]: finished in 0.000 s[bind] already rejected [000.001]: starting[bind] already rejected [000.001]: finished in 0.000 s[bind] pending [000.001]: starting[bind] pending [000.001]: finished in 0.000 s[bind] pending, fulfilled [000.001]: starting[bind] pending, fulfilled [000.001]: finished in 0.000 s[bind] pending, fulfilled, f raises [000.001]: starting[bind] pending, fulfilled, f raises [000.001]: finished in 0.000 s[bind] pending, rejected [000.001]: starting[bind] pending, rejected [000.001]: finished in 0.000 s[bind] chain [000.001]: starting[bind] chain [000.001]: finished in 0.000 s[bind] suspended chain [000.001]: starting[bind] suspended chain [000.001]: finished in 0.000 s[bind] fanout [000.001]: starting[bind] fanout [000.001]: finished in 0.000 s[bind] double pending [000.001]: starting[bind] double pending [000.001]: finished in 0.000 s[bind] same pending [000.001]: starting[bind] same pending [000.001]: finished in 0.000 s[bind] nested [000.001]: starting[bind] nested [000.001]: finished in 0.000 s[bind] proxy chain [000.001]: starting[bind] proxy chain [000.001]: finished in 0.000 s[bind] cycle [000.001]: starting[bind] cycle [000.001]: finished in 0.000 s[bind] proxy during callback [000.001]: starting[bind] proxy during callback [000.001]: finished in 0.000 s[backtrace_bind] fulfilled [000.001]: starting[backtrace_bind] fulfilled [000.001]: finished in 0.000 s[backtrace_bind] rejected [000.001]: starting[backtrace_bind] rejected [000.001]: finished in 0.000 s[backtrace_bind] pending, fulfilled [000.001]: starting[backtrace_bind] pending, fulfilled [000.001]: finished in 0.000 s[backtrace_bind] pending, fulfilled, f raises [000.001]: starting[backtrace_bind] pending, fulfilled, f raises [000.001]: finished in 0.000 s[backtrace_bind] pending, rejected [000.001]: starting[backtrace_bind] pending, rejected [000.001]: finished in 0.000 s[backtrace_bind] proxy during callback [000.001]: starting[backtrace_bind] proxy during callback [000.001]: finished in 0.000 s[map] fulfilled [000.001]: starting[map] fulfilled [000.001]: finished in 0.000 s[map] fulfilled, f raises [000.001]: starting[map] fulfilled, f raises [000.001]: finished in 0.000 s[map] rejected [000.001]: starting[map] rejected [000.001]: finished in 0.000 s[map] pending [000.001]: starting[map] pending [000.001]: finished in 0.000 s[map] pending, fulfilled [000.001]: starting[map] pending, fulfilled [000.001]: finished in 0.000 s[map] pending, fulfilled, f raises [000.001]: starting[map] pending, fulfilled, f raises [000.001]: finished in 0.000 s[map] pending, rejected [000.001]: starting[map] pending, rejected [000.001]: finished in 0.000 s[map] proxy during callback [000.001]: starting[map] proxy during callback [000.001]: finished in 0.000 s[catch] fulfilled [000.001]: starting[catch] fulfilled [000.001]: finished in 0.000 s[catch] f raises [000.001]: starting[catch] f raises [000.001]: finished in 0.000 s[catch] rejected [000.001]: starting[catch] rejected [000.001]: finished in 0.000 s[catch] rejected (raise) [000.001]: starting[catch] rejected (raise) [000.001]: finished in 0.000 s[catch] rejected, h raises [000.001]: starting[catch] rejected, h raises [000.001]: finished in 0.000 s[catch] pending [000.001]: starting[catch] pending [000.001]: finished in 0.000 s[catch] pending, fulfilled [000.001]: starting[catch] pending, fulfilled [000.001]: finished in 0.000 s[catch] pending, rejected [000.001]: starting[catch] pending, rejected [000.001]: finished in 0.000 s[catch] pending, rejected, h raises [000.001]: starting[catch] pending, rejected, h raises [000.001]: finished in 0.000 s[catch] pending, rejected, h pending [000.001]: starting[catch] pending, rejected, h pending [000.001]: finished in 0.000 s[catch] proxy during callback [000.001]: starting[catch] proxy during callback [000.001]: finished in 0.000 s[catch] catch with ocaml-runtime exception [000.001]: starting[catch] catch with ocaml-runtime exception [000.001]: finished in 0.000 s[catch] try_bind with ocaml-runtime exception [000.002]: starting[catch] try_bind with ocaml-runtime exception [000.002]: finished in 0.000 s[catch] try_bind(2) with ocaml-runtime exception [000.002]: starting[catch] try_bind(2) with ocaml-runtime exception [000.002]: finished in 0.000 s[backtrace_catch] fulfilled [000.002]: starting[backtrace_catch] fulfilled [000.002]: finished in 0.000 s[backtrace_catch] f raises [000.002]: starting[backtrace_catch] f raises [000.002]: finished in 0.000 s[backtrace_catch] rejected [000.002]: starting[backtrace_catch] rejected [000.002]: finished in 0.000 s[backtrace_catch] pending [000.002]: starting[backtrace_catch] pending [000.002]: finished in 0.000 s[backtrace_catch] pending, fulfilled [000.002]: starting[backtrace_catch] pending, fulfilled [000.002]: finished in 0.000 s[backtrace_catch] pending, rejected [000.002]: starting[backtrace_catch] pending, rejected [000.002]: finished in 0.000 s[backtrace_catch] pending, rejected, h raises [000.002]: starting[backtrace_catch] pending, rejected, h raises [000.002]: finished in 0.000 s[backtrace_catch] proxy during callback [000.002]: starting[backtrace_catch] proxy during callback [000.002]: finished in 0.000 s[try_bind] fulfilled [000.002]: starting[try_bind] fulfilled [000.002]: finished in 0.000 s[try_bind] fulfilled, f' raises [000.002]: starting[try_bind] fulfilled, f' raises [000.002]: finished in 0.000 s[try_bind] rejected [000.002]: starting[try_bind] rejected [000.002]: finished in 0.000 s[try_bind] f raises [000.002]: starting[try_bind] f raises [000.002]: finished in 0.000 s[try_bind] rejected, h raises [000.002]: starting[try_bind] rejected, h raises [000.002]: finished in 0.000 s[try_bind] pending [000.002]: starting[try_bind] pending [000.002]: finished in 0.000 s[try_bind] pending, fulfilled [000.002]: starting[try_bind] pending, fulfilled [000.002]: finished in 0.000 s[try_bind] pending, fulfilled, f' raises [000.002]: starting[try_bind] pending, fulfilled, f' raises [000.002]: finished in 0.000 s[try_bind] pending, fulfilled, f' pending [000.002]: starting[try_bind] pending, fulfilled, f' pending [000.002]: finished in 0.000 s[try_bind] pending, rejected [000.002]: starting[try_bind] pending, rejected [000.002]: finished in 0.000 s[try_bind] pending, rejected, h raises [000.002]: starting[try_bind] pending, rejected, h raises [000.002]: finished in 0.000 s[try_bind] pending, rejected, h pending [000.002]: starting[try_bind] pending, rejected, h pending [000.002]: finished in 0.000 s[try_bind] proxy during callback (fulfilled) [000.002]: starting[try_bind] proxy during callback (fulfilled) [000.002]: finished in 0.000 s[try_bind] proxy during callback (rejected) [000.002]: starting[try_bind] proxy during callback (rejected) [000.002]: finished in 0.000 s[backtrace_try_bind] fulfilled [000.002]: starting[backtrace_try_bind] fulfilled [000.002]: finished in 0.000 s[backtrace_try_bind] rejected [000.002]: starting[backtrace_try_bind] rejected [000.002]: finished in 0.000 s[backtrace_try_bind] f raises [000.002]: starting[backtrace_try_bind] f raises [000.002]: finished in 0.000 s[backtrace_try_bind] pending [000.002]: starting[backtrace_try_bind] pending [000.002]: finished in 0.000 s[backtrace_try_bind] pending, fulfilled [000.002]: starting[backtrace_try_bind] pending, fulfilled [000.002]: finished in 0.000 s[backtrace_try_bind] pending, fulfilled, f' raises [000.002]: starting[backtrace_try_bind] pending, fulfilled, f' raises [000.002]: finished in 0.000 s[backtrace_try_bind] pending, rejected [000.002]: starting[backtrace_try_bind] pending, rejected [000.002]: finished in 0.000 s[backtrace_try_bind] pending, rejected, h raises [000.002]: starting[backtrace_try_bind] pending, rejected, h raises [000.002]: finished in 0.000 s[backtrace_try_bind] proxy during callback (fulfilled) [000.002]: starting[backtrace_try_bind] proxy during callback (fulfilled) [000.002]: finished in 0.000 s[backtrace_try_bind] proxy during callback (rejected) [000.002]: starting[backtrace_try_bind] proxy during callback (rejected) [000.002]: finished in 0.000 s[finalize] fulfilled [000.002]: starting[finalize] fulfilled [000.002]: finished in 0.000 s[finalize] fulfilled, f' rejected [000.002]: starting[finalize] fulfilled, f' rejected [000.002]: finished in 0.000 s[finalize] fulfilled, f' raises [000.002]: starting[finalize] fulfilled, f' raises [000.002]: finished in 0.000 s[finalize] rejected [000.002]: starting[finalize] rejected [000.002]: finished in 0.000 s[finalize] rejected, f' rejected [000.002]: starting[finalize] rejected, f' rejected [000.002]: finished in 0.000 s[finalize] rejected, f' raises [000.002]: starting[finalize] rejected, f' raises [000.002]: finished in 0.000 s[finalize] pending [000.002]: starting[finalize] pending [000.002]: finished in 0.000 s[finalize] pending, fulfilled [000.002]: starting[finalize] pending, fulfilled [000.002]: finished in 0.000 s[finalize] pending, fulfilled, f' rejected [000.002]: starting[finalize] pending, fulfilled, f' rejected [000.002]: finished in 0.000 s[finalize] pending, fulfilled, f' raises [000.002]: starting[finalize] pending, fulfilled, f' raises [000.002]: finished in 0.000 s[finalize] pending, fulfilled, f' pending [000.002]: starting[finalize] pending, fulfilled, f' pending [000.002]: finished in 0.000 s[finalize] pending, fulfilled, f' pending, rejected [000.002]: starting[finalize] pending, fulfilled, f' pending, rejected [000.002]: finished in 0.000 s[finalize] pending, rejected [000.002]: starting[finalize] pending, rejected [000.003]: finished in 0.000 s[finalize] pending, rejected, f' rejected [000.003]: starting[finalize] pending, rejected, f' rejected [000.003]: finished in 0.000 s[finalize] pending, rejected, f' raises [000.003]: starting[finalize] pending, rejected, f' raises [000.003]: finished in 0.000 s[finalize] pending, rejected, f' pending [000.003]: starting[finalize] pending, rejected, f' pending [000.003]: finished in 0.000 s[finalize] pending, rejected, f' pending, rejected [000.003]: starting[finalize] pending, rejected, f' pending, rejected [000.003]: finished in 0.000 s[finalize] proxy during callback (fulfilled) [000.003]: starting[finalize] proxy during callback (fulfilled) [000.003]: finished in 0.000 s[finalize] proxy during callback (rejected) [000.003]: starting[finalize] proxy during callback (rejected) [000.003]: finished in 0.000 s[backtrace_finalize] fulfilled [000.003]: starting[backtrace_finalize] fulfilled [000.003]: finished in 0.000 s[backtrace_finalize] fulfilled, f' rejected [000.003]: starting[backtrace_finalize] fulfilled, f' rejected [000.003]: finished in 0.000 s[backtrace_finalize] fulfilled, f' raises [000.003]: starting[backtrace_finalize] fulfilled, f' raises [000.003]: finished in 0.000 s[backtrace_finalize] rejected [000.003]: starting[backtrace_finalize] rejected [000.003]: finished in 0.000 s[backtrace_finalize] rejected, f' rejected [000.003]: starting[backtrace_finalize] rejected, f' rejected [000.003]: finished in 0.000 s[backtrace_finalize] rejected, f' raises [000.003]: starting[backtrace_finalize] rejected, f' raises [000.003]: finished in 0.000 s[backtrace_finalize] pending [000.003]: starting[backtrace_finalize] pending [000.003]: finished in 0.000 s[backtrace_finalize] pending, fulfilled [000.003]: starting[backtrace_finalize] pending, fulfilled [000.003]: finished in 0.000 s[backtrace_finalize] pending, fulfilled, f' rejected [000.003]: starting[backtrace_finalize] pending, fulfilled, f' rejected [000.003]: finished in 0.000 s[backtrace_finalize] pending, fulfilled, f' raises [000.003]: starting[backtrace_finalize] pending, fulfilled, f' raises [000.003]: finished in 0.000 s[backtrace_finalize] pending, rejected [000.003]: starting[backtrace_finalize] pending, rejected [000.003]: finished in 0.000 s[backtrace_finalize] pending, rejected, f' rejected [000.003]: starting[backtrace_finalize] pending, rejected, f' rejected [000.003]: finished in 0.000 s[backtrace_finalize] pending, rejected, f' raises [000.003]: starting[backtrace_finalize] pending, rejected, f' raises [000.003]: finished in 0.000 s[backtrace_finalize] proxy during callback (fulfilled) [000.003]: starting[backtrace_finalize] proxy during callback (fulfilled) [000.003]: finished in 0.000 s[backtrace_finalize] proxy during callback (rejected) [000.003]: starting[backtrace_finalize] proxy during callback (rejected) [000.003]: finished in 0.000 s[on_success] fulfilled [000.003]: starting[on_success] fulfilled [000.003]: finished in 0.000 s[on_success] fulfilled, f raises [000.003]: starting[on_success] fulfilled, f raises [000.003]: finished in 0.000 s[on_success] rejected [000.003]: starting[on_success] rejected [000.003]: finished in 0.000 s[on_success] pending [000.003]: starting[on_success] pending [000.003]: finished in 0.000 s[on_success] pending, fulfilled [000.003]: starting[on_success] pending, fulfilled [000.003]: finished in 0.000 s[on_success] pending, fulfilled, f raises [000.003]: starting[on_success] pending, fulfilled, f raises [000.003]: finished in 0.000 s[on_success] pending, rejected [000.003]: starting[on_success] pending, rejected [000.003]: finished in 0.000 s[on_failure] fulfilled [000.003]: starting[on_failure] fulfilled [000.003]: finished in 0.000 s[on_failure] rejected [000.003]: starting[on_failure] rejected [000.003]: finished in 0.000 s[on_failure] rejected, f raises [000.003]: starting[on_failure] rejected, f raises [000.003]: finished in 0.000 s[on_failure] pending [000.003]: starting[on_failure] pending [000.003]: finished in 0.000 s[on_failure] pending, fulfilled [000.003]: starting[on_failure] pending, fulfilled [000.003]: finished in 0.000 s[on_failure] pending, rejected [000.003]: starting[on_failure] pending, rejected [000.003]: finished in 0.000 s[on_failure] pending, rejected, f raises [000.003]: starting[on_failure] pending, rejected, f raises [000.003]: finished in 0.000 s[on_termination] fulfilled [000.003]: starting[on_termination] fulfilled [000.003]: finished in 0.000 s[on_termination] fulfilled, f raises [000.003]: starting[on_termination] fulfilled, f raises [000.003]: finished in 0.000 s[on_termination] rejected [000.003]: starting[on_termination] rejected [000.003]: finished in 0.000 s[on_termination] rejected, f raises [000.003]: starting[on_termination] rejected, f raises [000.003]: finished in 0.000 s[on_termination] pending [000.003]: starting[on_termination] pending [000.003]: finished in 0.000 s[on_termination] pending, fulfilled [000.003]: starting[on_termination] pending, fulfilled [000.003]: finished in 0.000 s[on_termination] pending, fulfilled, f raises [000.003]: starting[on_termination] pending, fulfilled, f raises [000.003]: finished in 0.000 s[on_termination] pending, rejected [000.003]: starting[on_termination] pending, rejected [000.003]: finished in 0.000 s[on_termination] pending, rejected, f raises [000.003]: starting[on_termination] pending, rejected, f raises [000.003]: finished in 0.000 s[on_any] fulfilled [000.003]: starting[on_any] fulfilled [000.003]: finished in 0.000 s[on_any] fulfilled, f raises [000.003]: starting[on_any] fulfilled, f raises [000.003]: finished in 0.000 s[on_any] rejected [000.004]: starting[on_any] rejected [000.004]: finished in 0.000 s[on_any] rejected, f raises [000.004]: starting[on_any] rejected, f raises [000.004]: finished in 0.000 s[on_any] pending [000.004]: starting[on_any] pending [000.004]: finished in 0.000 s[on_any] pending, fulfilled [000.004]: starting[on_any] pending, fulfilled [000.004]: finished in 0.000 s[on_any] pending, fulfilled, f raises [000.004]: starting[on_any] pending, fulfilled, f raises [000.004]: finished in 0.000 s[on_any] pending, rejected [000.004]: starting[on_any] pending, rejected [000.004]: finished in 0.000 s[on_any] pending, rejected, g raises [000.004]: starting[on_any] pending, rejected, g raises [000.004]: finished in 0.000 s[async] fulfilled [000.004]: starting[async] fulfilled [000.004]: finished in 0.000 s[async] f raises [000.004]: starting[async] f raises [000.004]: finished in 0.000 s[async] rejected [000.004]: starting[async] rejected [000.004]: finished in 0.000 s[async] pending, fulfilled [000.004]: starting[async] pending, fulfilled [000.004]: finished in 0.000 s[async] pending, rejected [000.004]: starting[async] pending, rejected [000.004]: finished in 0.000 s[dont_wait] fulfilled [000.004]: starting[dont_wait] fulfilled [000.004]: finished in 0.000 s[dont_wait] f raises [000.004]: starting[dont_wait] f raises [000.004]: finished in 0.000 s[dont_wait] rejected [000.004]: starting[dont_wait] rejected [000.004]: finished in 0.000 s[dont_wait] pending, fulfilled [000.004]: starting[dont_wait] pending, fulfilled [000.004]: finished in 0.000 s[dont_wait] pending, rejected [000.004]: starting[dont_wait] pending, rejected [000.004]: finished in 0.000 s[ignore_result] fulfilled [000.004]: starting[ignore_result] fulfilled [000.004]: finished in 0.000 s[ignore_result] rejected [000.004]: starting[ignore_result] rejected [000.004]: finished in 0.000 s[ignore_result] pending, fulfilled [000.004]: starting[ignore_result] pending, fulfilled [000.004]: finished in 0.000 s[ignore_result] pending, rejected [000.004]: starting[ignore_result] pending, rejected [000.004]: finished in 0.000 s[join] empty [000.004]: starting[join] empty [000.004]: finished in 0.000 s[join] all fulfilled [000.004]: starting[join] all fulfilled [000.004]: finished in 0.000 s[join] all rejected [000.004]: starting[join] all rejected [000.004]: finished in 0.000 s[join] fulfilled and pending, fulfilled [000.004]: starting[join] fulfilled and pending, fulfilled [000.004]: finished in 0.000 s[join] rejected and pending, fulfilled [000.004]: starting[join] rejected and pending, fulfilled [000.004]: finished in 0.000 s[join] fulfilled and pending, rejected [000.004]: starting[join] fulfilled and pending, rejected [000.004]: finished in 0.000 s[join] rejected and pending, rejected [000.004]: starting[join] rejected and pending, rejected [000.004]: finished in 0.000 s[join] diamond [000.004]: starting[join] diamond [000.004]: finished in 0.000 s[all] empty [000.004]: starting[all] empty [000.004]: finished in 0.000 s[all] all fulfilled (one) [000.004]: starting[all] all fulfilled (one) [000.004]: finished in 0.000 s[all] all fulfilled (two) [000.004]: starting[all] all fulfilled (two) [000.004]: finished in 0.000 s[all] all fulfilled (three) [000.004]: starting[all] all fulfilled (three) [000.004]: finished in 0.000 s[all] all fulfilled (long) [000.004]: starting[all] all fulfilled (long) [000.004]: finished in 0.000 s[all] all rejected [000.004]: starting[all] all rejected [000.004]: finished in 0.000 s[all] fulfilled and pending, fulfilled [000.004]: starting[all] fulfilled and pending, fulfilled [000.004]: finished in 0.000 s[all] pending twice physically equal, fulfilled [000.004]: starting[all] pending twice physically equal, fulfilled [000.004]: finished in 0.000 s[all] pending twice physically equal twice, fulfilled [000.004]: starting[all] pending twice physically equal twice, fulfilled [000.004]: finished in 0.000 s[all] fulfilled and pending and fulfilled, fulfilled [000.004]: starting[all] fulfilled and pending and fulfilled, fulfilled [000.004]: finished in 0.000 s[all] fulfilled and pending, fulfilled (long) [000.004]: starting[all] fulfilled and pending, fulfilled (long) [000.004]: finished in 0.000 s[all] rejected and pending, fulfilled [000.004]: starting[all] rejected and pending, fulfilled [000.004]: finished in 0.000 s[all] fulfilled and pending, rejected [000.004]: starting[all] fulfilled and pending, rejected [000.004]: finished in 0.000 s[all] rejected and pending, rejected [000.004]: starting[all] rejected and pending, rejected [000.004]: finished in 0.000 s[all] diamond [000.004]: starting[all] diamond [000.005]: finished in 0.000 s[both] both fulfilled [000.005]: starting[both] both fulfilled [000.005]: finished in 0.000 s[both] both rejected [000.005]: starting[both] both rejected [000.005]: finished in 0.000 s[both] rejected, fulfilled [000.005]: starting[both] rejected, fulfilled [000.005]: finished in 0.000 s[both] fulfilled, rejected [000.005]: starting[both] fulfilled, rejected [000.005]: finished in 0.000 s[both] both pending [000.005]: starting[both] both pending [000.005]: finished in 0.000 s[both] pending, fulfilled [000.005]: starting[both] pending, fulfilled [000.005]: finished in 0.000 s[both] pending, rejected [000.005]: starting[both] pending, rejected [000.005]: finished in 0.000 s[both] fulfilled, pending [000.005]: starting[both] fulfilled, pending [000.005]: finished in 0.000 s[both] rejected, pending [000.005]: starting[both] rejected, pending [000.005]: finished in 0.000 s[both] pending, fulfilled, then fulfilled [000.005]: starting[both] pending, fulfilled, then fulfilled [000.005]: finished in 0.000 s[both] pending, rejected, then fulfilled [000.005]: starting[both] pending, rejected, then fulfilled [000.005]: finished in 0.000 s[both] pending, fulfilled, then rejected [000.005]: starting[both] pending, fulfilled, then rejected [000.005]: finished in 0.000 s[both] pending, rejected, then rejected [000.005]: starting[both] pending, rejected, then rejected [000.005]: finished in 0.000 s[both] fulfilled, pending, then fulfilled [000.005]: starting[both] fulfilled, pending, then fulfilled [000.005]: finished in 0.000 s[both] rejected, pending, then fulfilled [000.005]: starting[both] rejected, pending, then fulfilled [000.005]: finished in 0.000 s[both] fulfilled, pending, then rejected [000.005]: starting[both] fulfilled, pending, then rejected [000.005]: finished in 0.000 s[both] rejected, pending, then rejected [000.005]: starting[both] rejected, pending, then rejected [000.005]: finished in 0.000 s[both] pending, then first fulfilled [000.005]: starting[both] pending, then first fulfilled [000.005]: finished in 0.000 s[both] pending, then first rejected [000.005]: starting[both] pending, then first rejected [000.005]: finished in 0.000 s[both] pending, then second fulfilled [000.005]: starting[both] pending, then second fulfilled [000.005]: finished in 0.000 s[both] pending, then second rejected [000.005]: starting[both] pending, then second rejected [000.005]: finished in 0.000 s[both] pending, then first fulfilled, then fulfilled [000.005]: starting[both] pending, then first fulfilled, then fulfilled [000.005]: finished in 0.000 s[both] pending, then first fulfilled, then rejected [000.005]: starting[both] pending, then first fulfilled, then rejected [000.005]: finished in 0.000 s[both] pending, then first rejected, then fulfilled [000.005]: starting[both] pending, then first rejected, then fulfilled [000.005]: finished in 0.000 s[both] pending, then first rejected, then rejected [000.005]: starting[both] pending, then first rejected, then rejected [000.005]: finished in 0.000 s[both] pending, then second fulfilled, then fulfilled [000.005]: starting[both] pending, then second fulfilled, then fulfilled [000.005]: finished in 0.000 s[both] pending, then second fulfilled, then rejected [000.005]: starting[both] pending, then second fulfilled, then rejected [000.005]: finished in 0.000 s[both] pending, then second rejected, then fulfilled [000.005]: starting[both] pending, then second rejected, then fulfilled [000.005]: finished in 0.000 s[both] pending, then second rejected, then rejected [000.005]: starting[both] pending, then second rejected, then rejected [000.005]: finished in 0.000 s[both] diamond [000.005]: starting[both] diamond [000.005]: finished in 0.000 s[choose] empty [000.005]: starting[choose] empty [000.005]: finished in 0.000 s[choose] fulfilled [000.005]: starting[choose] fulfilled [000.005]: finished in 0.000 s[choose] rejected [000.005]: starting[choose] rejected [000.005]: finished in 0.000 s[choose] multiple resolved [000.005]: starting[choose] multiple resolved [000.005]: finished in 0.000 s[choose] pending [000.005]: starting[choose] pending [000.005]: finished in 0.000 s[choose] pending, fulfilled [000.005]: starting[choose] pending, fulfilled [000.005]: finished in 0.000 s[choose] diamond [000.005]: starting[choose] diamond [000.006]: finished in 0.000 s[nchoose] empty [000.006]: starting[nchoose] empty [000.006]: finished in 0.000 s[nchoose] all fulfilled [000.006]: starting[nchoose] all fulfilled [000.006]: finished in 0.000 s[nchoose] fulfilled, rejected [000.006]: starting[nchoose] fulfilled, rejected [000.006]: finished in 0.000 s[nchoose] rejected, fulfilled [000.006]: starting[nchoose] rejected, fulfilled [000.006]: finished in 0.000 s[nchoose] some pending [000.006]: starting[nchoose] some pending [000.006]: finished in 0.000 s[nchoose] pending, fulfilled [000.006]: starting[nchoose] pending, fulfilled [000.006]: finished in 0.000 s[nchoose] pending, rejected [000.006]: starting[nchoose] pending, rejected [000.006]: finished in 0.000 s[nchoose] diamond [000.006]: starting[nchoose] diamond [000.006]: finished in 0.000 s[nchoose] diamond, rejected [000.006]: starting[nchoose] diamond, rejected [000.006]: finished in 0.000 s[nchoose_split] empty [000.006]: starting[nchoose_split] empty [000.006]: finished in 0.000 s[nchoose_split] some fulfilled [000.006]: starting[nchoose_split] some fulfilled [000.006]: finished in 0.001 s[nchoose_split] fulfilled, rejected [000.006]: starting[nchoose_split] fulfilled, rejected [000.006]: finished in 0.000 s[nchoose_split] rejected, fulfilled [000.006]: starting[nchoose_split] rejected, fulfilled [000.006]: finished in 0.000 s[nchoose_split] pending, rejected [000.006]: starting[nchoose_split] pending, rejected [000.006]: finished in 0.000 s[nchoose_split] pending, fulfilled [000.006]: starting[nchoose_split] pending, fulfilled [000.006]: finished in 0.000 s[nchoose_split] pending, rejected 2 [000.006]: starting[nchoose_split] pending, rejected 2 [000.006]: finished in 0.000 s[nchoose_split] diamond [000.006]: starting[nchoose_split] diamond [000.006]: finished in 0.000 s[nchoose_split] diamond, rejected [000.006]: starting[nchoose_split] diamond, rejected [000.006]: finished in 0.000 s[state query] is_sleeping: fulfilled [000.006]: starting[state query] is_sleeping: fulfilled [000.006]: finished in 0.000 s[state query] is_sleeping: rejected [000.006]: starting[state query] is_sleeping: rejected [000.006]: finished in 0.000 s[state query] is_sleeping: pending [000.006]: starting[state query] is_sleeping: pending [000.006]: finished in 0.000 s[state query] is_sleeping: proxy [000.006]: starting[state query] is_sleeping: proxy [000.006]: finished in 0.000 s[state query] poll: fulfilled [000.006]: starting[state query] poll: fulfilled [000.007]: finished in 0.000 s[state query] poll: rejected [000.007]: starting[state query] poll: rejected [000.007]: finished in 0.000 s[state query] poll: pending [000.007]: starting[state query] poll: pending [000.007]: finished in 0.000 s[state query] poll: proxy [000.007]: starting[state query] poll: proxy [000.007]: finished in 0.000 s[wakeup] wakeup_result: nested [000.007]: starting[wakeup] wakeup_result: nested [000.007]: finished in 0.000 s[wakeup_later] wakeup_later_result: immediate [000.007]: starting[wakeup_later] wakeup_later_result: immediate [000.007]: finished in 0.000 s[wakeup_later] wakeup_later: double use on wait [000.007]: starting[wakeup_later] wakeup_later: double use on wait [000.007]: finished in 0.000 s[wakeup_later] wakeup_later: double use on task [000.007]: starting[wakeup_later] wakeup_later: double use on task [000.007]: finished in 0.000 s[wakeup_later] wakeup_later_result: double use on wait [000.007]: starting[wakeup_later] wakeup_later_result: double use on wait [000.007]: finished in 0.000 s[wakeup_later] wakeup_later_result: double use on task [000.007]: starting[wakeup_later] wakeup_later_result: double use on task [000.007]: finished in 0.000 s[wakeup_later] wakeup_later_exn: double use on wait [000.007]: starting[wakeup_later] wakeup_later_exn: double use on wait [000.007]: finished in 0.000 s[wakeup_later] wakeup_later_exn: double use on task [000.007]: starting[wakeup_later] wakeup_later_exn: double use on task [000.007]: finished in 0.000 s[wakeup_later] wakeup_later_result: nested [000.007]: starting[wakeup_later] wakeup_later_result: nested [000.007]: finished in 0.000 s[wakeup_later] wakeup_later: basic [000.007]: starting[wakeup_later] wakeup_later: basic [000.007]: finished in 0.000 s[wakeup_later] wakeup_later_exn: basic [000.007]: starting[wakeup_later] wakeup_later_exn: basic [000.007]: finished in 0.000 s[cancel] fulfilled [000.007]: starting[cancel] fulfilled [000.007]: finished in 0.000 s[cancel] rejected [000.007]: starting[cancel] rejected [000.007]: finished in 0.000 s[cancel] wait [000.007]: starting[cancel] wait [000.007]: finished in 0.000 s[cancel] task [000.007]: starting[cancel] task [000.007]: finished in 0.000 s[cancel] callback [000.007]: starting[cancel] callback [000.007]: finished in 0.000 s[cancel] nested [000.007]: starting[cancel] nested [000.007]: finished in 0.000 s[on_cancel] pending [000.007]: starting[on_cancel] pending [000.007]: finished in 0.000 s[on_cancel] multiple [000.007]: starting[on_cancel] multiple [000.007]: finished in 0.000 s[on_cancel] ordering [000.007]: starting[on_cancel] ordering [000.007]: finished in 0.000 s[on_cancel] fulfilled [000.007]: starting[on_cancel] fulfilled [000.007]: finished in 0.000 s[on_cancel] rejected [000.007]: starting[on_cancel] rejected [000.007]: finished in 0.000 s[on_cancel] already canceled [000.007]: starting[on_cancel] already canceled [000.007]: finished in 0.000 s[on_cancel] reject with Canceled [000.007]: starting[on_cancel] reject with Canceled [000.007]: finished in 0.000 s[protected] fulfilled [000.007]: starting[protected] fulfilled [000.007]: finished in 0.000 s[protected] rejected [000.007]: starting[protected] rejected [000.007]: finished in 0.000 s[protected] pending [000.007]: starting[protected] pending [000.007]: finished in 0.000 s[protected] pending, fulfilled [000.007]: starting[protected] pending, fulfilled [000.007]: finished in 0.000 s[protected] pending, canceled [000.007]: starting[protected] pending, canceled [000.007]: finished in 0.000 s[protected] pending, canceled, fulfilled [000.007]: starting[protected] pending, canceled, fulfilled [000.007]: finished in 0.000 s[protected] pending, proxy [000.007]: starting[protected] pending, proxy [000.007]: finished in 0.000 s[wrap_in_cancelable] fulfilled [000.007]: starting[wrap_in_cancelable] fulfilled [000.007]: finished in 0.000 s[wrap_in_cancelable] rejected [000.007]: starting[wrap_in_cancelable] rejected [000.007]: finished in 0.000 s[wrap_in_cancelable] pending(task) [000.007]: starting[wrap_in_cancelable] pending(task) [000.007]: finished in 0.000 s[wrap_in_cancelable] pending(task), fulfilled [000.007]: starting[wrap_in_cancelable] pending(task), fulfilled [000.007]: finished in 0.000 s[wrap_in_cancelable] pending(task), canceled [000.007]: starting[wrap_in_cancelable] pending(task), canceled [000.007]: finished in 0.000 s[wrap_in_cancelable] pending(wait) [000.007]: starting[wrap_in_cancelable] pending(wait) [000.007]: finished in 0.000 s[wrap_in_cancelable] pending(wait), fulfilled [000.007]: starting[wrap_in_cancelable] pending(wait), fulfilled [000.007]: finished in 0.000 s[wrap_in_cancelable] pending(wait), canceled [000.007]: starting[wrap_in_cancelable] pending(wait), canceled [000.007]: finished in 0.000 s[wrap_in_cancelable] pending(task), canceled, fulfilled [000.007]: starting[wrap_in_cancelable] pending(task), canceled, fulfilled [000.007]: finished in 0.000 s[wrap_in_cancelable] pending(wait), canceled, fulfilled [000.007]: starting[wrap_in_cancelable] pending(wait), canceled, fulfilled [000.007]: finished in 0.000 s[wrap_in_cancelable] pending, proxy [000.007]: starting[wrap_in_cancelable] pending, proxy [000.007]: finished in 0.000 s[no_cancel] fulfilled [000.007]: starting[no_cancel] fulfilled [000.007]: finished in 0.000 s[no_cancel] rejected [000.007]: starting[no_cancel] rejected [000.007]: finished in 0.000 s[no_cancel] pending [000.007]: starting[no_cancel] pending [000.007]: finished in 0.000 s[no_cancel] pending, fulfilled [000.007]: starting[no_cancel] pending, fulfilled [000.007]: finished in 0.000 s[no_cancel] pending, cancel attempt [000.007]: starting[no_cancel] pending, cancel attempt [000.007]: finished in 0.000 s[resolve canceled] wakeup: canceled [000.007]: starting[resolve canceled] wakeup: canceled [000.007]: finished in 0.000 s[resolve canceled] wakeup_later: canceled [000.007]: starting[resolve canceled] wakeup_later: canceled [000.007]: finished in 0.000 s[pick] empty [000.007]: starting[pick] empty [000.007]: finished in 0.000 s[pick] fulfilled [000.007]: starting[pick] fulfilled [000.007]: finished in 0.000 s[pick] rejected [000.007]: starting[pick] rejected [000.007]: finished in 0.000 s[pick] multiple resolved [000.007]: starting[pick] multiple resolved [000.008]: finished in 0.000 s[pick] pending [000.008]: starting[pick] pending [000.008]: finished in 0.000 s[pick] pending, fulfilled [000.008]: starting[pick] pending, fulfilled [000.008]: finished in 0.000 s[pick] diamond [000.008]: starting[pick] diamond [000.008]: finished in 0.000 s[pick] pending, canceled [000.008]: starting[pick] pending, canceled [000.008]: finished in 0.000 s[pick] cancellation/resolution order [000.008]: starting[pick] cancellation/resolution order [000.008]: finished in 0.000 s[npick] empty [000.008]: starting[npick] empty [000.008]: finished in 0.000 s[npick] all fulfilled [000.008]: starting[npick] all fulfilled [000.008]: finished in 0.000 s[npick] fulfilled, rejected [000.008]: starting[npick] fulfilled, rejected [000.008]: finished in 0.000 s[npick] rejected, fulfilled [000.008]: starting[npick] rejected, fulfilled [000.008]: finished in 0.000 s[npick] some pending [000.008]: starting[npick] some pending [000.008]: finished in 0.000 s[npick] pending [000.008]: starting[npick] pending [000.008]: finished in 0.000 s[npick] pending, fulfilled [000.008]: starting[npick] pending, fulfilled [000.008]: finished in 0.000 s[npick] pending, rejected [000.008]: starting[npick] pending, rejected [000.008]: finished in 0.000 s[npick] diamond [000.008]: starting[npick] diamond [000.008]: finished in 0.000 s[npick] diamond, rejected [000.008]: starting[npick] diamond, rejected [000.008]: finished in 0.000 s[npick] pending, canceled [000.008]: starting[npick] pending, canceled [000.008]: finished in 0.000 s[npick] cancellation/resolution order [000.008]: starting[npick] cancellation/resolution order [000.008]: finished in 0.000 s[cancel bind] wait, pending, canceled [000.008]: starting[cancel bind] wait, pending, canceled [000.008]: finished in 0.000 s[cancel bind] task, pending, canceled [000.008]: starting[cancel bind] task, pending, canceled [000.008]: finished in 0.000 s[cancel bind] pending, wait, canceled [000.008]: starting[cancel bind] pending, wait, canceled [000.008]: finished in 0.000 s[cancel bind] pending, task, canceled [000.008]: starting[cancel bind] pending, task, canceled [000.008]: finished in 0.000 s[cancel bind] pending, task, canceled, chain [000.008]: starting[cancel bind] pending, task, canceled, chain [000.008]: finished in 0.000 s[cancel bind] pending, on_cancel callbacks [000.008]: starting[cancel bind] pending, on_cancel callbacks [000.008]: finished in 0.000 s[cancel bind] pending, fulfilled, on_cancel callbacks [000.008]: starting[cancel bind] pending, fulfilled, on_cancel callbacks [000.008]: finished in 0.000 s[cancel map] wait, pending, canceled [000.008]: starting[cancel map] wait, pending, canceled [000.008]: finished in 0.000 s[cancel map] task, pending, canceled [000.008]: starting[cancel map] task, pending, canceled [000.008]: finished in 0.000 s[cancel catch] wait, pending, canceled [000.008]: starting[cancel catch] wait, pending, canceled [000.008]: finished in 0.000 s[cancel catch] task, pending, canceled [000.008]: starting[cancel catch] task, pending, canceled [000.008]: finished in 0.000 s[cancel catch] task, pending, canceled, on_cancel, intercepted [000.008]: starting[cancel catch] task, pending, canceled, on_cancel, intercepted [000.008]: finished in 0.000 s[cancel catch] task, pending, canceled, on_cancel, forwarded [000.008]: starting[cancel catch] task, pending, canceled, on_cancel, forwarded [000.008]: finished in 0.000 s[cancel catch] pending, wait, canceled [000.008]: starting[cancel catch] pending, wait, canceled [000.008]: finished in 0.000 s[cancel catch] pending, task, canceled [000.008]: starting[cancel catch] pending, task, canceled [000.008]: finished in 0.000 s[cancel try_bind] wait, pending, canceled [000.008]: starting[cancel try_bind] wait, pending, canceled [000.008]: finished in 0.000 s[cancel try_bind] task, pending, canceled [000.008]: starting[cancel try_bind] task, pending, canceled [000.008]: finished in 0.000 s[cancel try_bind] pending, fulfilled, wait, canceled [000.008]: starting[cancel try_bind] pending, fulfilled, wait, canceled [000.008]: finished in 0.000 s[cancel try_bind] pending, fulfilled, task, canceled [000.008]: starting[cancel try_bind] pending, fulfilled, task, canceled [000.008]: finished in 0.000 s[cancel try_bind] pending, rejected, wait, canceled [000.008]: starting[cancel try_bind] pending, rejected, wait, canceled [000.008]: finished in 0.000 s[cancel try_bind] pending, rejected, task, canceled [000.008]: starting[cancel try_bind] pending, rejected, task, canceled [000.008]: finished in 0.000 s[cancel finalize] wait, pending, canceled [000.008]: starting[cancel finalize] wait, pending, canceled [000.008]: finished in 0.000 s[cancel finalize] task, pending, canceled [000.008]: starting[cancel finalize] task, pending, canceled [000.008]: finished in 0.000 s[cancel finalize] task, canceled, cancel exception replaced [000.008]: starting[cancel finalize] task, canceled, cancel exception replaced [000.008]: finished in 0.000 s[cancel finalize] pending, wait, canceled [000.008]: starting[cancel finalize] pending, wait, canceled [000.008]: finished in 0.000 s[cancel finalize] pending, task, canceled [000.008]: starting[cancel finalize] pending, task, canceled [000.008]: finished in 0.000 s[cancel with direct handler] on_success: pending, canceled [000.008]: starting[cancel with direct handler] on_success: pending, canceled [000.008]: finished in 0.000 s[cancel with direct handler] on_failure: pending, canceled [000.008]: starting[cancel with direct handler] on_failure: pending, canceled [000.008]: finished in 0.000 s[cancel with direct handler] on_termination: pending, canceled [000.008]: starting[cancel with direct handler] on_termination: pending, canceled [000.008]: finished in 0.000 s[cancel with direct handler] on_any: pending, canceled [000.008]: starting[cancel with direct handler] on_any: pending, canceled [000.008]: finished in 0.000 s[cancel join] wait, pending, cancel [000.008]: starting[cancel join] wait, pending, cancel [000.008]: finished in 0.000 s[cancel join] task, pending, cancel [000.008]: starting[cancel join] task, pending, cancel [000.008]: finished in 0.000 s[cancel join] wait and task, pending, cancel [000.008]: starting[cancel join] wait and task, pending, cancel [000.008]: finished in 0.000 s[cancel join] cancel diamond [000.008]: starting[cancel join] cancel diamond [000.008]: finished in 0.000 s[cancel choose] wait, pending, cancel [000.008]: starting[cancel choose] wait, pending, cancel [000.008]: finished in 0.000 s[cancel choose] wait and task, pending, cancel [000.008]: starting[cancel choose] wait and task, pending, cancel [000.008]: finished in 0.000 s[cancel pick] wait, pending, cancel [000.008]: starting[cancel pick] wait, pending, cancel [000.009]: finished in 0.000 s[cancel pick] wait and task, pending, cancel [000.009]: starting[cancel pick] wait and task, pending, cancel [000.009]: finished in 0.000 s[cancel nchoose] wait, pending, cancel [000.009]: starting[cancel nchoose] wait, pending, cancel [000.009]: finished in 0.000 s[cancel nchoose] wait and task, pending, cancel [000.009]: starting[cancel nchoose] wait and task, pending, cancel [000.009]: finished in 0.000 s[cancel npick] wait, pending, cancel [000.009]: starting[cancel npick] wait, pending, cancel [000.009]: finished in 0.000 s[cancel npick] wait and task, pending, cancel [000.009]: starting[cancel npick] wait and task, pending, cancel [000.009]: finished in 0.000 s[cancel nchoose_split] wait, pending, cancel [000.009]: starting[cancel nchoose_split] wait, pending, cancel [000.009]: finished in 0.000 s[cancel nchoose_split] wait and task, pending, cancel [000.009]: starting[cancel nchoose_split] wait and task, pending, cancel [000.009]: finished in 0.000 s[storage] initial [000.009]: starting[storage] initial [000.009]: finished in 0.000 s[storage] store, retrieve [000.009]: starting[storage] store, retrieve [000.009]: finished in 0.000 s[storage] store, restore [000.009]: starting[storage] store, restore [000.009]: finished in 0.000 s[storage] store, f raises, restore [000.009]: starting[storage] store, f raises, restore [000.009]: finished in 0.000 s[storage] store, overwrite, retrieve [000.009]: starting[storage] store, overwrite, retrieve [000.009]: finished in 0.000 s[storage] store, blank, retrieve [000.009]: starting[storage] store, blank, retrieve [000.009]: finished in 0.000 s[storage] distinct keys [000.009]: starting[storage] distinct keys [000.009]: finished in 0.000 s[storage] bind [000.009]: starting[storage] bind [000.009]: finished in 0.000 s[storage] map [000.009]: starting[storage] map [000.009]: finished in 0.000 s[storage] catch [000.009]: starting[storage] catch [000.009]: finished in 0.000 s[storage] try_bind, fulfilled [000.009]: starting[storage] try_bind, fulfilled [000.009]: finished in 0.000 s[storage] try_bind, rejected [000.009]: starting[storage] try_bind, rejected [000.009]: finished in 0.000 s[storage] finalize [000.009]: starting[storage] finalize [000.009]: finished in 0.000 s[storage] on_success [000.009]: starting[storage] on_success [000.009]: finished in 0.000 s[storage] on_failure [000.009]: starting[storage] on_failure [000.009]: finished in 0.000 s[storage] on_termination, fulfilled [000.009]: starting[storage] on_termination, fulfilled [000.009]: finished in 0.000 s[storage] on_termination, rejected [000.009]: starting[storage] on_termination, rejected [000.009]: finished in 0.000 s[storage] on_any, fulfilled [000.009]: starting[storage] on_any, fulfilled [000.009]: finished in 0.000 s[storage] on_any, rejected [000.009]: starting[storage] on_any, rejected [000.009]: finished in 0.000 s[storage] on_cancel [000.009]: starting[storage] on_cancel [000.009]: finished in 0.000 s[infix operators] >>= [000.009]: starting[infix operators] >>= [000.009]: finished in 0.000 s[infix operators] =<< [000.009]: starting[infix operators] =<< [000.009]: finished in 0.000 s[infix operators] >|= [000.009]: starting[infix operators] >|= [000.009]: finished in 0.000 s[infix operators] =|< [000.009]: starting[infix operators] =|< [000.009]: finished in 0.000 s[infix operators] <&> [000.009]: starting[infix operators] <&> [000.009]: finished in 0.000 s[infix operators] <?> [000.009]: starting[infix operators] <?> [000.009]: finished in 0.000 s[ppx_let] return [000.009]: starting[ppx_let] return [000.009]: finished in 0.000 s[ppx_let] map [000.009]: starting[ppx_let] map [000.009]: finished in 0.000 s[ppx_let] bind [000.009]: starting[ppx_let] bind [000.009]: finished in 0.000 s[ppx_let] both [000.009]: starting[ppx_let] both [000.009]: finished in 0.000 s[ppx_let] Open_on_rhs [000.009]: starting[ppx_let] Open_on_rhs [000.009]: finished in 0.000 s[let syntax] let* [000.009]: starting[let syntax] let* [000.009]: finished in 0.000 s[let syntax] and* [000.009]: starting[let syntax] and* [000.009]: finished in 0.000 s[let syntax] let+/and+ [000.009]: starting[let syntax] let+/and+ [000.009]: finished in 0.000 s[add_task_l and add_task_r] add_task_r [000.009]: starting[add_task_l and add_task_r] add_task_r [000.009]: finished in 0.000 s[add_task_l and add_task_r] add_task_l [000.009]: starting[add_task_l and add_task_r] add_task_l [000.009]: finished in 0.000 s[pause] initial state [000.009]: starting[pause] initial state [000.009]: finished in 0.000 s[pause] one promise [000.009]: starting[pause] one promise [000.009]: finished in 0.000 s[pause] multiple promises [000.009]: starting[pause] multiple promises [000.009]: finished in 0.000 s[pause] wakeup with no promises [000.009]: starting[pause] wakeup with no promises [000.009]: finished in 0.000 s[pause] pause notifier [000.009]: starting[pause] pause notifier [000.009]: finished in 0.000 s[pause] pause in unpause [000.009]: starting[pause] pause in unpause [000.009]: finished in 0.000 s[pause] recursive pause in notifier [000.009]: starting[pause] recursive pause in notifier [000.009]: finished in 0.000 s[pause] unpause in pause [000.009]: starting[pause] unpause in pause [000.009]: finished in 0.000 s[apply and wrap] apply [000.009]: starting[apply and wrap] apply [000.009]: finished in 0.000 s[apply and wrap] apply: raises [000.009]: starting[apply and wrap] apply: raises [000.009]: finished in 0.000 s[apply and wrap] wrap [000.009]: starting[apply and wrap] wrap [000.009]: finished in 0.000 s[apply and wrap] wrap: raises [000.010]: starting[apply and wrap] wrap: raises [000.010]: finished in 0.000 s[apply and wrap] wrap1 [000.010]: starting[apply and wrap] wrap1 [000.010]: finished in 0.000 s[apply and wrap] wrap1: raises [000.010]: starting[apply and wrap] wrap1: raises [000.010]: finished in 0.000 s[apply and wrap] wrap2 [000.010]: starting[apply and wrap] wrap2 [000.010]: finished in 0.000 s[apply and wrap] wrap2: raises [000.010]: starting[apply and wrap] wrap2: raises [000.010]: finished in 0.000 s[apply and wrap] wrap3 [000.010]: starting[apply and wrap] wrap3 [000.010]: finished in 0.000 s[apply and wrap] wrap3: raises [000.010]: starting[apply and wrap] wrap3: raises [000.010]: finished in 0.000 s[apply and wrap] wrap4 [000.010]: starting[apply and wrap] wrap4 [000.010]: finished in 0.000 s[apply and wrap] wrap4: raises [000.010]: starting[apply and wrap] wrap4: raises [000.010]: finished in 0.000 s[apply and wrap] wrap5 [000.010]: starting[apply and wrap] wrap5 [000.010]: finished in 0.000 s[apply and wrap] wrap5: raises [000.010]: starting[apply and wrap] wrap5: raises [000.010]: finished in 0.000 s[apply and wrap] wrap6 [000.010]: starting[apply and wrap] wrap6 [000.010]: finished in 0.000 s[apply and wrap] wrap6: raises [000.010]: starting[apply and wrap] wrap6: raises [000.010]: finished in 0.000 s[apply and wrap] wrap7 [000.010]: starting[apply and wrap] wrap7 [000.010]: finished in 0.000 s[apply and wrap] wrap7: raises [000.010]: starting[apply and wrap] wrap7: raises [000.010]: finished in 0.000 s[callback cleanup] choose [000.010]: starting[callback cleanup] choose [000.010]: finished in 0.000 s[callback cleanup] bind [000.010]: starting[callback cleanup] bind [000.010]: finished in 0.000 s[tailrec] tailrec [000.010]: starting[tailrec] tailrec [000.332]: finished in 0.322 s[lwt_stream] from [000.332]: starting[lwt_stream] from [000.332]: finished in 0.000 s[lwt_stream] return [000.332]: starting[lwt_stream] return [000.332]: finished in 0.000 s[lwt_stream] return_lwt [000.332]: starting[lwt_stream] return_lwt [000.332]: finished in 0.000 s[lwt_stream] return_lwt_with_pause [000.332]: starting[lwt_stream] return_lwt_with_pause [000.332]: finished in 0.000 s[lwt_stream] return_lwt_with_fail [000.332]: starting[lwt_stream] return_lwt_with_fail [000.332]: finished in 0.000 s[lwt_stream] of_seq [000.332]: starting[lwt_stream] of_seq [000.332]: finished in 0.000 s[lwt_stream] of_lwt_seq [000.332]: starting[lwt_stream] of_lwt_seq [000.332]: finished in 0.000 s[lwt_stream] of_list [000.332]: starting[lwt_stream] of_list [000.332]: finished in 0.000 s[lwt_stream] clone [000.332]: starting[lwt_stream] clone [000.332]: finished in 0.000 s[lwt_stream] clone 2 [000.332]: starting[lwt_stream] clone 2 [000.332]: finished in 0.000 s[lwt_stream] create [000.332]: starting[lwt_stream] create [000.332]: finished in 0.000 s[lwt_stream] create 2 [000.332]: starting[lwt_stream] create 2 [000.332]: finished in 0.000 s[lwt_stream] create_bounded [000.332]: starting[lwt_stream] create_bounded [000.332]: finished in 0.000 s[lwt_stream] create_bounded close [000.332]: starting[lwt_stream] create_bounded close [000.332]: finished in 0.000 s[lwt_stream] get_while [000.332]: starting[lwt_stream] get_while [000.332]: finished in 0.000 s[lwt_stream] peek [000.332]: starting[lwt_stream] peek [000.332]: finished in 0.000 s[lwt_stream] npeek [000.332]: starting[lwt_stream] npeek [000.332]: finished in 0.000 s[lwt_stream] get_available [000.332]: starting[lwt_stream] get_available [000.332]: finished in 0.000 s[lwt_stream] get_available_up_to [000.332]: starting[lwt_stream] get_available_up_to [000.332]: finished in 0.000 s[lwt_stream] filter [000.332]: starting[lwt_stream] filter [000.332]: finished in 0.000 s[lwt_stream] filter_map [000.332]: starting[lwt_stream] filter_map [000.332]: finished in 0.000 s[lwt_stream] last_new [000.332]: starting[lwt_stream] last_new [000.332]: finished in 0.000 s[lwt_stream] junk_available [000.332]: starting[lwt_stream] junk_available [000.332]: finished in 0.000 s[lwt_stream] junk_old [000.332]: starting[lwt_stream] junk_old [000.332]: finished in 0.000 s[lwt_stream] cancel push stream 1 [000.332]: starting[lwt_stream] cancel push stream 1 [000.332]: finished in 0.000 s[lwt_stream] cancel push stream 2 [000.332]: starting[lwt_stream] cancel push stream 2 [000.332]: finished in 0.000 s[lwt_stream] cancel push stream 3 [000.332]: starting[lwt_stream] cancel push stream 3 [000.332]: finished in 0.000 s[lwt_stream] push and GC [000.332]: starting[lwt_stream] push and GC [000.335]: finished in 0.002 s[lwt_stream] map_exn [000.335]: starting[lwt_stream] map_exn [000.335]: finished in 0.000 s[lwt_stream] is_closed [000.335]: starting[lwt_stream] is_closed [000.335]: finished in 0.000 s[lwt_stream] closed(bind) [000.335]: starting[lwt_stream] closed(bind) [000.335]: finished in 0.000 s[lwt_stream] closed(on_termination) [000.335]: starting[lwt_stream] closed(on_termination) [000.335]: finished in 0.000 s[lwt_stream] closed when closed [000.335]: starting[lwt_stream] closed when closed [000.335]: finished in 0.000 s[lwt_stream] choose_exhausted [000.335]: starting[lwt_stream] choose_exhausted [000.335]: finished in 0.000 s[lwt_stream] exception passing: basic, from [000.335]: starting[lwt_stream] exception passing: basic, from [000.335]: finished in 0.000 s[lwt_stream] exception passing: basic, from_direct [000.335]: starting[lwt_stream] exception passing: basic, from_direct [000.335]: finished in 0.000 s[lwt_stream] exception passing: to_list [000.335]: starting[lwt_stream] exception passing: to_list [000.335]: finished in 0.000 s[lwt_stream] exception passing: mapped [000.335]: starting[lwt_stream] exception passing: mapped [000.335]: finished in 0.000 s[lwt_stream] exception passing: resume, not closed, from [000.335]: starting[lwt_stream] exception passing: resume, not closed, from [000.335]: finished in 0.000 s[lwt_stream] exception passing: resume, not closed, from_direct [000.335]: starting[lwt_stream] exception passing: resume, not closed, from_direct [000.335]: finished in 0.000 s[lwt_list] iter_p [000.335]: starting[lwt_list] iter_p [000.335]: finished in 0.000 s[lwt_list] iter_s [000.335]: starting[lwt_list] iter_s [000.335]: finished in 0.000 s[lwt_list] map_p [000.335]: starting[lwt_list] map_p [000.335]: finished in 0.000 s[lwt_list] map_s [000.335]: starting[lwt_list] map_s [000.335]: finished in 0.000 s[lwt_list] fold_left_s [000.335]: starting[lwt_list] fold_left_s [000.335]: finished in 0.000 s[lwt_list] for_all_s [000.335]: starting[lwt_list] for_all_s [000.335]: finished in 0.000 s[lwt_list] for_all_p [000.335]: starting[lwt_list] for_all_p [000.335]: finished in 0.000 s[lwt_list] exists_s true [000.335]: starting[lwt_list] exists_s true [000.335]: finished in 0.000 s[lwt_list] exists_p true [000.335]: starting[lwt_list] exists_p true [000.335]: finished in 0.000 s[lwt_list] exists_s false [000.335]: starting[lwt_list] exists_s false [000.335]: finished in 0.000 s[lwt_list] exists_p false [000.335]: starting[lwt_list] exists_p false [000.335]: finished in 0.000 s[lwt_list] filter_s [000.335]: starting[lwt_list] filter_s [000.335]: finished in 0.000 s[lwt_list] filter_p [000.335]: starting[lwt_list] filter_p [000.335]: finished in 0.000 s[lwt_list] partition_p [000.335]: starting[lwt_list] partition_p [000.335]: finished in 0.000 s[lwt_list] partition_s [000.335]: starting[lwt_list] partition_s [000.335]: finished in 0.000 s[lwt_list] filter_map_p [000.335]: starting[lwt_list] filter_map_p [000.335]: finished in 0.000 s[lwt_list] filter_map_s [000.335]: starting[lwt_list] filter_map_s [000.335]: finished in 0.000 s[lwt_list] iteri_p [000.335]: starting[lwt_list] iteri_p [000.335]: finished in 0.000 s[lwt_list] iteri_s [000.335]: starting[lwt_list] iteri_s [000.335]: finished in 0.000 s[lwt_list] mapi_p [000.335]: starting[lwt_list] mapi_p [000.335]: finished in 0.000 s[lwt_list] mapi_s [000.335]: starting[lwt_list] mapi_s [000.335]: finished in 0.000 s[lwt_list] find_s existing [000.335]: starting[lwt_list] find_s existing [000.335]: finished in 0.000 s[lwt_list] find_s missing [000.335]: starting[lwt_list] find_s missing [000.335]: finished in 0.000 s[lwt_list] rev_map_p [000.335]: starting[lwt_list] rev_map_p [000.335]: finished in 0.000 s[lwt_list] rev_map_s [000.335]: starting[lwt_list] rev_map_s [000.335]: finished in 0.000 s[lwt_list] fold_right_s [000.335]: starting[lwt_list] fold_right_s [000.335]: finished in 0.000 s[lwt_list] iteri_p exception [000.335]: starting[lwt_list] iteri_p exception [000.335]: finished in 0.000 s[lwt_list] iteri_s exception [000.335]: starting[lwt_list] iteri_s exception [000.335]: finished in 0.000 s[lwt_list] map_s exception [000.335]: starting[lwt_list] map_s exception [000.335]: finished in 0.000 s[lwt_list] map_p exception [000.335]: starting[lwt_list] map_p exception [000.335]: finished in 0.000 s[lwt_list] mapi_s exception [000.335]: starting[lwt_list] mapi_s exception [000.335]: finished in 0.000 s[lwt_list] mapi_p exception [000.335]: starting[lwt_list] mapi_p exception [000.335]: finished in 0.000 s[lwt_list] rev_map_s exception [000.335]: starting[lwt_list] rev_map_s exception [000.335]: finished in 0.000 s[lwt_list] rev_map_p exception [000.335]: starting[lwt_list] rev_map_p exception [000.335]: finished in 0.000 s[lwt_list] fold_left_s exception [000.335]: starting[lwt_list] fold_left_s exception [000.335]: finished in 0.000 s[lwt_list] fold_right_s exception [000.335]: starting[lwt_list] fold_right_s exception [000.335]: finished in 0.000 s[lwt_list] for_all_p exception [000.335]: starting[lwt_list] for_all_p exception [000.335]: finished in 0.000 s[lwt_list] for_all_s exception [000.335]: starting[lwt_list] for_all_s exception [000.335]: finished in 0.000 s[lwt_list] exists_p exception [000.336]: starting[lwt_list] exists_p exception [000.336]: finished in 0.000 s[lwt_list] exists_s exception [000.336]: starting[lwt_list] exists_s exception [000.336]: finished in 0.000 s[lwt_list] find_s exception [000.336]: starting[lwt_list] find_s exception [000.336]: finished in 0.000 s[lwt_list] filter_p exception [000.336]: starting[lwt_list] filter_p exception [000.336]: finished in 0.000 s[lwt_list] filter_s exception [000.336]: starting[lwt_list] filter_s exception [000.336]: finished in 0.000 s[lwt_list] filter_map_p exception [000.336]: starting[lwt_list] filter_map_p exception [000.336]: finished in 0.000 s[lwt_list] filter_map_s exception [000.336]: starting[lwt_list] filter_map_s exception [000.336]: finished in 0.000 s[lwt_list] partition_p exception [000.336]: starting[lwt_list] partition_p exception [000.336]: finished in 0.000 s[lwt_list] partition_s exception [000.336]: starting[lwt_list] partition_s exception [000.336]: finished in 0.000 s[lwt_list] iter_p parallelism [000.336]: starting[lwt_list] iter_p parallelism [000.336]: finished in 0.000 s[lwt_list] iter_s serialization [000.336]: starting[lwt_list] iter_s serialization [000.336]: finished in 0.000 s[lwt_list] iteri_p parallelism [000.336]: starting[lwt_list] iteri_p parallelism [000.336]: finished in 0.000 s[lwt_list] iteri_s serialization [000.336]: starting[lwt_list] iteri_s serialization [000.336]: finished in 0.000 s[lwt_list] map_p parallelism [000.336]: starting[lwt_list] map_p parallelism [000.336]: finished in 0.000 s[lwt_list] map_s serialization [000.336]: starting[lwt_list] map_s serialization [000.336]: finished in 0.000 s[lwt_list] mapi_p parallelism [000.336]: starting[lwt_list] mapi_p parallelism [000.336]: finished in 0.000 s[lwt_list] mapi_s serialization [000.336]: starting[lwt_list] mapi_s serialization [000.336]: finished in 0.000 s[lwt_list] rev_map_p parallelism [000.336]: starting[lwt_list] rev_map_p parallelism [000.336]: finished in 0.000 s[lwt_list] rev_map_s serialization [000.336]: starting[lwt_list] rev_map_s serialization [000.336]: finished in 0.000 s[lwt_list] fold_left_s serialization [000.336]: starting[lwt_list] fold_left_s serialization [000.336]: finished in 0.000 s[lwt_list] fold_right_s serialization [000.336]: starting[lwt_list] fold_right_s serialization [000.336]: finished in 0.000 s[lwt_list] filter_map_p parallelism [000.336]: starting[lwt_list] filter_map_p parallelism [000.336]: finished in 0.000 s[lwt_list] filter_map_s serlialism [000.336]: starting[lwt_list] filter_map_s serlialism [000.336]: finished in 0.000 s[lwt_list] for_all_p parallelism [000.336]: starting[lwt_list] for_all_p parallelism [000.336]: finished in 0.000 s[lwt_list] for_all_s serialization [000.336]: starting[lwt_list] for_all_s serialization [000.336]: finished in 0.000 s[lwt_list] exists_p parallelism [000.336]: starting[lwt_list] exists_p parallelism [000.336]: finished in 0.000 s[lwt_list] exists_s serialization [000.336]: starting[lwt_list] exists_s serialization [000.336]: finished in 0.000 s[lwt_list] find_s serialization [000.336]: starting[lwt_list] find_s serialization [000.336]: finished in 0.000 s[lwt_list] filter_p parallelism [000.336]: starting[lwt_list] filter_p parallelism [000.336]: finished in 0.000 s[lwt_list] filter_s serialization [000.336]: starting[lwt_list] filter_s serialization [000.336]: finished in 0.000 s[lwt_list] filter_map_s serialization [000.336]: starting[lwt_list] filter_map_s serialization [000.336]: finished in 0.000 s[lwt_list] partition_p parallelism [000.336]: starting[lwt_list] partition_p parallelism [000.336]: finished in 0.000 s[lwt_list] partition_s serialization [000.336]: starting[lwt_list] partition_s serialization [000.336]: finished in 0.000 slwt_list big lists [000.336]: skipping[lwt_switch] turn_off, add_hook [000.336]: starting[lwt_switch] turn_off, add_hook [000.336]: finished in 0.000 s[lwt_switch] turn_off: hook exception [000.336]: starting[lwt_switch] turn_off: hook exception [000.336]: finished in 0.000 s[lwt_switch] with_switch: regular exit [000.336]: starting[lwt_switch] with_switch: regular exit [000.336]: finished in 0.000 s[lwt_switch] with_switch: exception [000.336]: starting[lwt_switch] with_switch: exception [000.336]: finished in 0.000 s[lwt_switch] check [000.336]: starting[lwt_switch] check [000.336]: finished in 0.000 s[lwt_switch] is_on [000.336]: starting[lwt_switch] is_on [000.336]: finished in 0.000 s[lwt_switch] add_hook_or_exec [000.336]: starting[lwt_switch] add_hook_or_exec [000.336]: finished in 0.000 s[lwt_switch] turn_off waits for hooks: regular exit [000.336]: starting[lwt_switch] turn_off waits for hooks: regular exit [000.336]: finished in 0.000 s[lwt_switch] turn_off waits for hooks: hook exception [000.336]: starting[lwt_switch] turn_off waits for hooks: hook exception [000.336]: finished in 0.000 s[lwt_mutex] cancel [000.336]: starting[lwt_mutex] cancel [000.336]: finished in 0.000 s[lwt_mutex] cancel while queued by unlock [000.336]: starting[lwt_mutex] cancel while queued by unlock [000.336]: finished in 0.000 s[lwt_result] maps [000.336]: starting[lwt_result] maps [000.336]: finished in 0.000 s[lwt_result] >|= is a variant of map [000.337]: starting[lwt_result] >|= is a variant of map [000.337]: finished in 0.000 s[lwt_result] map, error case [000.337]: starting[lwt_result] map, error case [000.337]: finished in 0.000 s[lwt_result] map_error [000.337]: starting[lwt_result] map_error [000.337]: finished in 0.000 s[lwt_result] map_error, error case [000.337]: starting[lwt_result] map_error, error case [000.337]: finished in 0.000 s[lwt_result] bind [000.337]: starting[lwt_result] bind [000.337]: finished in 0.000 s[lwt_result] bind, error case [000.337]: starting[lwt_result] bind, error case [000.337]: finished in 0.000 s[lwt_result] bind_error [000.337]: starting[lwt_result] bind_error [000.337]: finished in 0.000 s[lwt_result] bind_error, error case [000.337]: starting[lwt_result] bind_error, error case [000.337]: finished in 0.000 s[lwt_result] ok [000.337]: starting[lwt_result] ok [000.337]: finished in 0.000 s[lwt_result] error [000.337]: starting[lwt_result] error [000.337]: finished in 0.000 s[lwt_result] catch [000.337]: starting[lwt_result] catch [000.337]: finished in 0.000 s[lwt_result] catch, error case [000.337]: starting[lwt_result] catch, error case [000.337]: finished in 0.000 s[lwt_result] catch, bound raise [000.337]: starting[lwt_result] catch, bound raise [000.337]: finished in 0.000 s[lwt_result] catch, immediate raise [000.337]: starting[lwt_result] catch, immediate raise [000.337]: finished in 0.000 s[lwt_result] get_exn [000.337]: starting[lwt_result] get_exn [000.337]: finished in 0.000 s[lwt_result] get_exn, error case [000.337]: starting[lwt_result] get_exn, error case [000.337]: finished in 0.000 s[lwt_result] bind_lwt [000.337]: starting[lwt_result] bind_lwt [000.337]: finished in 0.000 s[lwt_result] bind_lwt, error case [000.337]: starting[lwt_result] bind_lwt, error case [000.337]: finished in 0.000 s[lwt_result] bind_lwt_error [000.337]: starting[lwt_result] bind_lwt_error [000.337]: finished in 0.000 s[lwt_result] bind_lwt_error, error case [000.337]: starting[lwt_result] bind_lwt_error, error case [000.337]: finished in 0.000 s[lwt_result] bind_result [000.337]: starting[lwt_result] bind_result [000.337]: finished in 0.000 s[lwt_result] bind_result, error case [000.337]: starting[lwt_result] bind_result, error case [000.337]: finished in 0.000 s[lwt_result] both ok [000.337]: starting[lwt_result] both ok [000.337]: finished in 0.000 s[lwt_result] both only fst error [000.337]: starting[lwt_result] both only fst error [000.337]: finished in 0.000 s[lwt_result] both only snd error [000.337]: starting[lwt_result] both only snd error [000.337]: finished in 0.000 s[lwt_result] both error, fst [000.337]: starting[lwt_result] both error, fst [000.337]: finished in 0.000 s[lwt_result] both error, snd [000.337]: starting[lwt_result] both error, snd [000.337]: finished in 0.000 s[lwt_result] iter [000.337]: starting[lwt_result] iter [000.337]: finished in 0.000 s[lwt_result] iter, error case [000.337]: starting[lwt_result] iter, error case [000.337]: finished in 0.000 s[lwt_result] iter_error [000.337]: starting[lwt_result] iter_error [000.337]: finished in 0.000 s[lwt_result] iter_error, success case [000.337]: starting[lwt_result] iter_error, success case [000.337]: finished in 0.000 s[lwt_result] let* [000.337]: starting[lwt_result] let* [000.337]: finished in 0.000 s[lwt_result] and* [000.337]: starting[lwt_result] and* [000.337]: finished in 0.000 s[lwt_result] let+/and+ [000.337]: starting[lwt_result] let+/and+ [000.337]: finished in 0.000 s[lwt_mvar] basic take [000.337]: starting[lwt_mvar] basic take [000.337]: finished in 0.000 s[lwt_mvar] take_available (full) [000.337]: starting[lwt_mvar] take_available (full) [000.337]: finished in 0.000 s[lwt_mvar] take_available (empty) [000.337]: starting[lwt_mvar] take_available (empty) [000.337]: finished in 0.000 s[lwt_mvar] take_available (twice) [000.337]: starting[lwt_mvar] take_available (twice) [000.337]: finished in 0.000 s[lwt_mvar] is_empty (full) [000.337]: starting[lwt_mvar] is_empty (full) [000.337]: finished in 0.000 s[lwt_mvar] is_empty (empty) [000.337]: starting[lwt_mvar] is_empty (empty) [000.337]: finished in 0.000 s[lwt_mvar] blocking put [000.337]: starting[lwt_mvar] blocking put [000.337]: finished in 0.000 s[lwt_mvar] put-take [000.337]: starting[lwt_mvar] put-take [000.337]: finished in 0.000 s[lwt_mvar] take-put [000.337]: starting[lwt_mvar] take-put [000.337]: finished in 0.000 s[lwt_mvar] enqueued writer [000.337]: starting[lwt_mvar] enqueued writer [000.337]: finished in 0.000 s[lwt_mvar] writer cancellation [000.337]: starting[lwt_mvar] writer cancellation [000.337]: finished in 0.000 s[lwt_condition] basic wait [000.337]: starting[lwt_condition] basic wait [000.337]: finished in 0.000 s[lwt_condition] mutex unlocked during wait [000.337]: starting[lwt_condition] mutex unlocked during wait [000.337]: finished in 0.000 s[lwt_condition] mutex relocked after wait [000.337]: starting[lwt_condition] mutex relocked after wait [000.337]: finished in 0.000 s[lwt_condition] signal is not sticky [000.337]: starting[lwt_condition] signal is not sticky [000.337]: finished in 0.000 s[lwt_condition] broadcast [000.337]: starting[lwt_condition] broadcast [000.337]: finished in 0.000 s[lwt_condition] broadcast exception [000.337]: starting[lwt_condition] broadcast exception [000.337]: finished in 0.000 s[lwt_pool] basic create-use [000.337]: starting[lwt_pool] basic create-use [000.337]: finished in 0.000 s[lwt_pool] creator exception [000.337]: starting[lwt_pool] creator exception [000.337]: finished in 0.000 s[lwt_pool] pool elements are reused [000.337]: starting[lwt_pool] pool elements are reused [000.337]: finished in 0.000 s[lwt_pool] pool elements are validated when returned [000.337]: starting[lwt_pool] pool elements are validated when returned [000.338]: finished in 0.000 s[lwt_pool] validation exceptions are propagated to users [000.338]: starting[lwt_pool] validation exceptions are propagated to users [000.338]: finished in 0.000 s[lwt_pool] multiple creation [000.338]: starting[lwt_pool] multiple creation [000.338]: finished in 0.000 s[lwt_pool] users of an empty pool will wait [000.338]: starting[lwt_pool] users of an empty pool will wait [000.338]: finished in 0.000 s[lwt_pool] on check, good elements are retained [000.338]: starting[lwt_pool] on check, good elements are retained [000.338]: finished in 0.000 s[lwt_pool] on check, bad elements are disposed of and replaced [000.338]: starting[lwt_pool] on check, bad elements are disposed of and replaced [000.338]: finished in 0.000 s[lwt_pool] clear disposes of all elements [000.338]: starting[lwt_pool] clear disposes of all elements [000.338]: finished in 0.000 s[lwt_pool] waiter are notified on replacement [000.338]: starting[lwt_pool] waiter are notified on replacement [000.338]: finished in 0.000 s[lwt_pool] waiter are notified on replacement exception [000.338]: starting[lwt_pool] waiter are notified on replacement exception [000.338]: finished in 0.000 s[lwt_pool] check and validate can be used together [000.338]: starting[lwt_pool] check and validate can be used together [000.338]: finished in 0.000 s[lwt_pool] verify default check behavior [000.338]: starting[lwt_pool] verify default check behavior [000.338]: finished in 0.000 s[lwt_sequence] create [000.338]: starting[lwt_sequence] create [000.338]: finished in 0.000 s[lwt_sequence] add_l [000.338]: starting[lwt_sequence] add_l [000.338]: finished in 0.000 s[lwt_sequence] add_r [000.338]: starting[lwt_sequence] add_r [000.338]: finished in 0.000 s[lwt_sequence] take_l Empty [000.338]: starting[lwt_sequence] take_l Empty [000.338]: finished in 0.000 s[lwt_sequence] take_l [000.338]: starting[lwt_sequence] take_l [000.338]: finished in 0.000 s[lwt_sequence] take_r Empty [000.338]: starting[lwt_sequence] take_r Empty [000.338]: finished in 0.000 s[lwt_sequence] take_r [000.338]: starting[lwt_sequence] take_r [000.338]: finished in 0.000 s[lwt_sequence] take_opt_l Empty [000.338]: starting[lwt_sequence] take_opt_l Empty [000.338]: finished in 0.000 s[lwt_sequence] take_opt_l [000.338]: starting[lwt_sequence] take_opt_l [000.338]: finished in 0.000 s[lwt_sequence] take_opt_r Empty [000.338]: starting[lwt_sequence] take_opt_r Empty [000.338]: finished in 0.000 s[lwt_sequence] take_opt_r [000.338]: starting[lwt_sequence] take_opt_r [000.338]: finished in 0.000 s[lwt_sequence] transfer_l Empty [000.338]: starting[lwt_sequence] transfer_l Empty [000.338]: finished in 0.000 s[lwt_sequence] transfer_l [000.338]: starting[lwt_sequence] transfer_l [000.338]: finished in 0.000 s[lwt_sequence] transfer_r Empty [000.338]: starting[lwt_sequence] transfer_r Empty [000.338]: finished in 0.000 s[lwt_sequence] transfer_r [000.338]: starting[lwt_sequence] transfer_r [000.338]: finished in 0.000 s[lwt_sequence] iter_l Empty [000.338]: starting[lwt_sequence] iter_l Empty [000.338]: finished in 0.000 s[lwt_sequence] iter_l [000.338]: starting[lwt_sequence] iter_l [000.338]: finished in 0.000 s[lwt_sequence] iter_r Empty [000.338]: starting[lwt_sequence] iter_r Empty [000.338]: finished in 0.000 s[lwt_sequence] iter_r [000.338]: starting[lwt_sequence] iter_r [000.338]: finished in 0.000 s[lwt_sequence] iter_node_l Empty [000.338]: starting[lwt_sequence] iter_node_l Empty [000.338]: finished in 0.000 s[lwt_sequence] iter_node_l [000.338]: starting[lwt_sequence] iter_node_l [000.338]: finished in 0.000 s[lwt_sequence] iter_node_r Empty [000.338]: starting[lwt_sequence] iter_node_r Empty [000.338]: finished in 0.000 s[lwt_sequence] iter_node_r [000.338]: starting[lwt_sequence] iter_node_r [000.338]: finished in 0.000 s[lwt_sequence] iter_node_l with removal [000.338]: starting[lwt_sequence] iter_node_l with removal [000.338]: finished in 0.000 s[lwt_sequence] iter_node_r with removal [000.338]: starting[lwt_sequence] iter_node_r with removal [000.338]: finished in 0.000 s[lwt_sequence] fold_l [000.338]: starting[lwt_sequence] fold_l [000.338]: finished in 0.000 s[lwt_sequence] fold_l Empty [000.338]: starting[lwt_sequence] fold_l Empty [000.338]: finished in 0.000 s[lwt_sequence] fold_r [000.338]: starting[lwt_sequence] fold_r [000.338]: finished in 0.000 s[lwt_sequence] fold_r Empty [000.338]: starting[lwt_sequence] fold_r Empty [000.338]: finished in 0.000 s[lwt_sequence] find_node_opt_l Empty [000.338]: starting[lwt_sequence] find_node_opt_l Empty [000.338]: finished in 0.000 s[lwt_sequence] find_node_opt_l not found [000.338]: starting[lwt_sequence] find_node_opt_l not found [000.338]: finished in 0.000 s[lwt_sequence] find_node_opt_l [000.338]: starting[lwt_sequence] find_node_opt_l [000.338]: finished in 0.000 s[lwt_sequence] find_node_opt_r Empty [000.338]: starting[lwt_sequence] find_node_opt_r Empty [000.338]: finished in 0.000 s[lwt_sequence] find_node_opt_r not found [000.338]: starting[lwt_sequence] find_node_opt_r not found [000.338]: finished in 0.000 s[lwt_sequence] find_node_opt_r [000.338]: starting[lwt_sequence] find_node_opt_r [000.338]: finished in 0.000 s[lwt_sequence] find_node_l Empty [000.338]: starting[lwt_sequence] find_node_l Empty [000.338]: finished in 0.000 s[lwt_sequence] find_node_l [000.338]: starting[lwt_sequence] find_node_l [000.338]: finished in 0.000 s[lwt_sequence] find_node_r Empty [000.338]: starting[lwt_sequence] find_node_r Empty [000.338]: finished in 0.000 s[lwt_sequence] find_node_r [000.338]: starting[lwt_sequence] find_node_r [000.338]: finished in 0.000 s[lwt_sequence] set [000.338]: starting[lwt_sequence] set [000.338]: finished in 0.000 s[lwt_sequence] fold_r with multiple removal [000.338]: starting[lwt_sequence] fold_r with multiple removal [000.338]: finished in 0.000 s[lwt_sequence] fold_l multiple removal [000.338]: starting[lwt_sequence] fold_l multiple removal [000.338]: finished in 0.000 s[lwt_sequence] find_node_r with multiple removal [000.338]: starting[lwt_sequence] find_node_r with multiple removal [000.338]: finished in 0.000 s[lwt_sequence] find_node_l with multiple removal [000.338]: starting[lwt_sequence] find_node_l with multiple removal [000.339]: finished in 0.000 s[lwt_seq] fold_left [000.339]: starting[lwt_seq] fold_left [000.339]: finished in 0.000 s[lwt_seq] fold_left_s [000.339]: starting[lwt_seq] fold_left_s [000.339]: finished in 0.000 s[lwt_seq] map [000.339]: starting[lwt_seq] map [000.339]: finished in 0.000 s[lwt_seq] map_s [000.339]: starting[lwt_seq] map_s [000.339]: finished in 0.000 s[lwt_seq] filter [000.339]: starting[lwt_seq] filter [000.339]: finished in 0.000 s[lwt_seq] filter_s [000.339]: starting[lwt_seq] filter_s [000.339]: finished in 0.000 s[lwt_seq] iter_n(1) [000.339]: starting[lwt_seq] iter_n(1) [000.339]: finished in 0.000 s[lwt_seq] iter_n(2) [000.339]: starting[lwt_seq] iter_n(2) [000.339]: finished in 0.000 s[lwt_seq] iter_n(100) [000.339]: starting[lwt_seq] iter_n(100) [000.339]: finished in 0.000 s[lwt_seq] filter_map [000.339]: starting[lwt_seq] filter_map [000.339]: finished in 0.000 s[lwt_seq] filter_map_s [000.339]: starting[lwt_seq] filter_map_s [000.339]: finished in 0.000 s[lwt_seq] unfold [000.339]: starting[lwt_seq] unfold [000.339]: finished in 0.000 s[lwt_seq] unfold_lwt [000.339]: starting[lwt_seq] unfold_lwt [000.339]: finished in 0.000 s[lwt_seq] fold-into-exception-from-of-seq [000.339]: starting[lwt_seq] fold-into-exception-from-of-seq [000.339]: finished in 0.000 s[lwt_seq] fold-into-immediate-exception-from-of-seq [000.339]: starting[lwt_seq] fold-into-immediate-exception-from-of-seq [000.339]: finished in 0.000 s[lwt_seq] fold-into-exception-from-of-seq-lwt [000.339]: starting[lwt_seq] fold-into-exception-from-of-seq-lwt [000.339]: finished in 0.000 s[lwt_seq] fold-into-immediate-exception-from-of-seq-lwt [000.339]: starting[lwt_seq] fold-into-immediate-exception-from-of-seq-lwt [000.339]: finished in 0.000 s[lwt_seq(pseudo-fuzzing)] map [000.339]: starting[lwt_seq(pseudo-fuzzing)] map [000.340]: finished in 0.000 s[lwt_seq(pseudo-fuzzing)] map_s [000.340]: startinglwt_seq(pseudo-fuzzing)] map_s [000.340]: finished in 0.000 s[lwt_seq(pseudo-fuzzing)] iter [000.340]: starting[lwt_seq(pseudo-fuzzing)] iter [000.340]: finished in 0.000 s[lwt_seq(pseudo-fuzzing)] iter_s [000.340]: starting[lwt_seq(pseudo-fuzzing)] iter_s [000.341]: finished in 0.000 s[lwt_seq(pseudo-fuzzing)] iter_p [000.341]: starting[lwt_seq(pseudo-fuzzing)] iter_p [000.341]: finished in 0.000 s[lwt_seq(pseudo-fuzzing)] iter_p (pause) [000.341]: starting[lwt_seq(pseudo-fuzzing)] iter_p (pause) [000.345]: finished in 0.004 s[lwt_seq(pseudo-fuzzing)] iter_n [000.345]: starting[lwt_seq(pseudo-fuzzing)] iter_n [000.347]: finished in 0.002 s[lwt_seq(pseudo-fuzzing)] iter_n (pause) [000.347]: starting[lwt_seq(pseudo-fuzzing)] iter_n (pause) [000.382]: finished in 0.035 s(cd _build/default/test/ppx && ./main.exe)Testing library 'ppx'......................Ok. 19 tests ran, 0 tests skipped in 1.20 seconds[ppx] let [000.000]: starting[ppx] let [000.000]: finished in 0.000 s[ppx] nested let [000.000]: starting[ppx] nested let [000.000]: finished in 0.000 s[ppx] and let [000.000]: starting[ppx] and let [000.000]: finished in 0.000 s[ppx] match [000.000]: starting[ppx] match [000.000]: finished in 0.000 s[ppx] match-exn [000.000]: starting[ppx] match-exn [000.000]: finished in 0.000 s[ppx] if [000.000]: starting[ppx] if [000.000]: finished in 0.000 s[ppx] for [000.000]: starting[ppx] for [000.601]: finished in 0.601 s[ppx] while [000.601]: starting[ppx] while [001.202]: finished in 0.601 s[ppx] assert [001.202]: starting[ppx] assert [001.202]: finished in 0.000 s[ppx] try [001.202]: starting[ppx] try [001.202]: finished in 0.000 s[ppx] try raise [001.202]: starting[ppx] try raise [001.202]: finished in 0.000 s[ppx] try fallback [001.202]: starting[ppx] try fallback [001.202]: finished in 0.000 s[ppx] finally body [001.202]: starting[ppx] finally body [001.202]: finished in 0.000 s[ppx] finally exn [001.202]: starting[ppx] finally exn [001.202]: finished in 0.000 s[ppx] finally exn default [001.202]: starting[ppx] finally exn default [001.202]: finished in 0.000 s[ppx] structure let [001.202]: starting[ppx] structure let [001.202]: finished in 0.000 s[ppx] 1085-int [001.202]: starting[ppx] 1085-int [001.202]: finished in 0.000 s[ppx] 1085-int-again [001.202]: starting[ppx] 1085-int-again [001.202]: finished in 0.000 s[ppx] 1085-any [001.202]: starting[ppx] 1085-any [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.50 seconds[lwt_event] to_stream [000.000]: starting[lwt_event] to_stream [000.000]: finished in 0.000 s[lwt_event] to_stream 2 [000.000]: starting[lwt_event] to_stream 2 [000.000]: finished in 0.000 s[lwt_event] map_s [000.000]: starting[lwt_event] map_s [000.000]: finished in 0.000 s[lwt_event] map_p [000.000]: starting[lwt_event] map_p [000.000]: finished in 0.000 s[lwt_event] limit_race [000.000]: starting[lwt_event] limit_race [002.501]: finished in 2.500 s[lwt_event] of_stream [002.501]: starting[lwt_event] of_stream [002.501]: finished in 0.000 s[lwt_event] limit [002.501]: starting[lwt_event] limit [002.501]: finished in 0.000 s[lwt_event] with_finaliser lifetime [002.501]: starting[lwt_event] with_finaliser lifetime [002.502]: finished in 0.001 s[lwt_signal] limit [002.502]: starting[lwt_signal] limit [002.502]: finished in 0.000 s[lwt_signal] limit race condition [002.502]: starting[lwt_signal] limit race condition [004.503]: finished in 2.001 s[lwt_signal] with_finaliser lifetime [004.503]: starting[lwt_signal] with_finaliser lifetime [004.504]: finished in 0.001 s(cd _build/default/test/unix && ./main.exe)Testing library 'unix'................................SSSSS..............................................................................................................SSSS.................................................................................Ok. 220 tests ran, 9 tests skipped in 6.37 seconds[lwt_unix] basic pread blocking [000.000]: starting[lwt_unix] basic pread blocking [000.020]: finished in 0.020 s[lwt_unix] basic pwrite blocking [000.020]: starting[lwt_unix] basic pwrite blocking [000.044]: finished in 0.024 s[lwt_unix] remove file blocking [000.044]: starting[lwt_unix] remove file blocking [000.044]: finished in 0.000 s[lwt_unix] basic pread nonblocking [000.044]: starting[lwt_unix] basic pread nonblocking [000.056]: finished in 0.012 s[lwt_unix] basic pwrite nonblocking [000.056]: starting[lwt_unix] basic pwrite nonblocking [000.076]: finished in 0.020 s[lwt_unix] remove file nonblocking [000.076]: starting[lwt_unix] remove file nonblocking [000.076]: finished in 0.000 s[lwt_unix] dup on socket blocking [000.076]: starting[lwt_unix] dup on socket blocking [000.076]: finished in 0.000 s[lwt_unix] dup on file blocking [000.076]: starting[lwt_unix] dup on file blocking [000.077]: finished in 0.000 s[lwt_unix] dup on socket nonblocking [000.077]: starting[lwt_unix] dup on socket nonblocking [000.077]: finished in 0.001 s[lwt_unix] dup on file nonblocking [000.077]: starting[lwt_unix] dup on file nonblocking [000.077]: finished in 0.000 s[lwt_io] auto-flush [000.077]: starting[lwt_io] auto-flush [000.180]: finished in 0.102 s[lwt_io] auto-flush in atomic [000.180]: starting[lwt_io] auto-flush in atomic [000.280]: finished in 0.100 s[lwt_io] establish_server: implicit close on exception [000.280]: starting[lwt_io] establish_server: implicit close on exception [000.332]: finished in 0.051 s[lwt_io] with_temp_dir [000.332]: starting[lwt_io] with_temp_dir [000.337]: finished in 0.005 s[lwt_io non blocking io] file does not exist [000.337]: starting[lwt_io non blocking io] file does not exist [000.337]: finished in 0.000 s[lwt_io non blocking io] file does not exist (invalid path) [000.337]: starting[lwt_io non blocking io] file does not exist (invalid path) [000.337]: finished in 0.000 s[lwt_io non blocking io] file does not exist (LargeFile) [000.337]: starting[lwt_io non blocking io] file does not exist (LargeFile) [000.337]: finished in 0.000 s[lwt_io non blocking io] file does not exist (LargeFile, invalid path) [000.337]: starting[lwt_io non blocking io] file does not exist (LargeFile, invalid path) [000.337]: finished in 0.000 s[lwt_io non blocking io] create file [000.337]: starting[lwt_io non blocking io] create file [000.338]: finished in 0.000 s[lwt_io non blocking io] file exists [000.338]: starting[lwt_io non blocking io] file exists [000.338]: finished in 0.000 s[lwt_io non blocking io] file exists (LargeFile) [000.338]: starting[lwt_io non blocking io] file exists (LargeFile) [000.338]: finished in 0.000 s[lwt_io non blocking io] read file [000.338]: starting[lwt_io non blocking io] read file [000.338]: finished in 0.000 s[lwt_io non blocking io] remove file [000.338]: starting[lwt_io non blocking io] remove file [000.338]: finished in 0.000 s[lwt_engine] Lwt_main.run: nested call [000.338]: starting[lwt_engine] Lwt_main.run: nested call [000.338]: finished in 0.000 s[lwt_engine] Lwt_engine.id gives default [000.338]: starting[lwt_engine] Lwt_engine.id gives default [000.338]: finished in 0.000 s[Lwt_timeout] exception in action [000.338]: starting[Lwt_timeout] exception in action [002.340]: finished in 2.002 s[lwt_unix] utimes: basic [002.340]: starting[lwt_unix] utimes: current time [002.340]: starting[lwt_unix] utimes: missing file [002.340]: starting[lwt_unix] readdir: basic [002.340]: starting[lwt_unix] readdir: rewinddir [002.340]: starting[lwt_unix] readdir: readdir_n [002.340]: starting[lwt_unix] readdir: files_of_directory [002.341]: starting[lwt_unix] readdir: already closed [002.341]: starting[lwt_unix] io_vector_byte_count: basic [002.341]: starting[lwt_unix] io_vector_byte_count: basic [002.341]: finished in 0.000 s[lwt_unix] io_vector_byte_count: offsets, partials [002.341]: starting[lwt_unix] io_vector_byte_count: offsets, partials [002.341]: finished in 0.000 s[lwt_unix] io_vector_byte_count: drops [002.341]: starting[lwt_unix] io_vector_byte_count: drops [002.341]: finished in 0.000 s[lwt_unix] readv: basic non-blocking [002.341]: starting[lwt_unix] readv: basic blocking [002.341]: starting[lwt_unix] readv: drop [002.341]: starting[lwt_unix] readv: iovecs exceeding limit [002.341]: starting[lwt_unix] readv: windows [002.341]: skipping[lwt_unix] writev: basic non-blocking [002.341]: starting[lwt_unix] writev: basic blocking [002.343]: starting[lwt_unix] writev: slices [002.345]: starting[lwt_unix] writev: drop, is_empty [002.347]: starting[lwt_unix] writev: degenerate vectors [002.349]: starting[lwt_unix] writev: bad iovec [002.351]: starting[lwt_unix] writev: iovecs exceeding limit [002.351]: starting[lwt_unix] writev: negative drop [002.353]: starting[lwt_unix] writev: windows [002.355]: skipping[lwt_unix] send_msg, recv_msg [002.355]: starting[lwt_unix] send_msg, recv_msg (Lwt_bytes, old) [002.355]: starting[lwt_unix] bind: basic [002.355]: starting[lwt_unix] bind: Unix domain [002.355]: starting[lwt_unix] bind: closed [002.355]: starting[lwt_unix] bind: aborted [002.355]: starting[lwt_unix] getcwd [002.355]: starting[lwt_unix] getcwd and chdir [002.355]: starting[lwt_unix] getcwd and Unix.getcwd [002.355]: starting[lwt_unix] run_in_main [002.355]: starting[lwt_unix] run_in_main_dont_wait [002.356]: starting[lwt_unix] run_in_main_dont_wait_fail [002.356]: starting[lwt_unix] run_in_main_with_dont_wait [002.356]: starting[lwt_unix] getlogin and Unix.getlogin [002.356]: skipping[lwt_unix] getpwnam and Unix.getpwnam [002.356]: skipping[lwt_unix] getpwuid and Unix.getpwuid [002.356]: skipping[lwt_unix] getgrgid and Unix.getgrgid [002.356]: starting[lwt_unix] getgrnam and Unix.getgrnam [002.356]: starting[lwt_io] establish_server_1: shutdown: client closes first [002.356]: starting[lwt_io] open_connection: shutdown: server closes first [002.356]: starting[lwt_io] establish_server: implicit close [002.357]: starting[lwt_io] establish_server: explicit close [002.357]: starting[lwt_io] with_connection [002.357]: starting[lwt_io] with_close_connection: no duplicate exceptions [002.357]: starting[lwt_io] open_temp_file [002.357]: starting[lwt_io] with_temp_filename [002.357]: starting[lwt_io] with_temp_filename close handle [002.357]: starting[lwt_io] create_temp_dir [002.357]: starting[lwt_io] file_length on directory [002.357]: starting[lwt_io] input channel of_bytes initial position [002.357]: starting[lwt_io] input channel of_bytes initial position [002.357]: finished in 0.000 s[lwt_io] input channel of_bytes position after read [002.357]: starting[lwt_io] input channel of_bytes position after read [002.357]: finished in 0.000 s[lwt_io] input channel of_bytes position after set_position [002.357]: starting[lwt_io] input channel of_bytes position after set_position [002.357]: finished in 0.000 s[lwt_io] output channel of_bytes initial position [002.357]: starting[lwt_io] output channel of_bytes initial position [002.357]: finished in 0.000 s[lwt_io] output channel of_bytes position after read [002.357]: starting[lwt_io] output channel of_bytes position after read [002.357]: finished in 0.000 s[lwt_io] output channel of_bytes position after set_position [002.357]: starting[lwt_io] output channel of_bytes position after set_position [002.357]: finished in 0.000 s[lwt_io] NumberIO.LE.read_int [002.357]: starting[lwt_io] NumberIO.LE.read_int [002.357]: finished in 0.000 s[lwt_io] NumberIO.BE.read_int [002.357]: starting[lwt_io] NumberIO.BE.read_int [002.357]: finished in 0.000 s[lwt_io] NumberIO.LE.read_int16 [002.357]: starting[lwt_io] NumberIO.LE.read_int16 [002.357]: finished in 0.000 s[lwt_io] NumberIO.BE.read_int16 [002.357]: starting[lwt_io] NumberIO.BE.read_int16 [002.357]: finished in 0.000 s[lwt_io] NumberIO.LE.read_int16, negative [002.357]: starting[lwt_io] NumberIO.LE.read_int16, negative [002.357]: finished in 0.000 s[lwt_io] NumberIO.BE.read_int16, negative [002.357]: starting[lwt_io] NumberIO.BE.read_int16, negative [002.357]: finished in 0.000 s[lwt_io] NumberIO.LE.read_int32 [002.357]: starting[lwt_io] NumberIO.LE.read_int32 [002.357]: finished in 0.000 s[lwt_io] NumberIO.BE.read_int32 [002.357]: starting[lwt_io] NumberIO.BE.read_int32 [002.357]: finished in 0.000 s[lwt_io] NumberIO.LE.read_int64 [002.357]: starting[lwt_io] NumberIO.LE.read_int64 [002.357]: finished in 0.000 s[lwt_io] NumberIO.BE.read_int64 [002.357]: starting[lwt_io] NumberIO.BE.read_int64 [002.357]: finished in 0.000 s[lwt_io] NumberIO.LE.read_float32 [002.357]: starting[lwt_io] NumberIO.LE.read_float32 [002.357]: finished in 0.000 s[lwt_io] NumberIO.BE.read_float32 [002.357]: starting[lwt_io] NumberIO.BE.read_float32 [002.357]: finished in 0.000 s[lwt_io] NumberIO.LE.read_float64 [002.357]: starting[lwt_io] NumberIO.LE.read_float64 [002.357]: finished in 0.000 s[lwt_io] NumberIO.BE.read_float64 [002.357]: starting[lwt_io] NumberIO.BE.read_float64 [002.357]: finished in 0.000 s[lwt_io] NumberIO.LE.write_int [002.357]: starting[lwt_io] NumberIO.LE.write_int [002.357]: finished in 0.000 s[lwt_io] NumberIO.BE.write_int [002.357]: starting[lwt_io] NumberIO.BE.write_int [002.357]: finished in 0.000 s[lwt_io] NumberIO.LE.write_int16 [002.358]: starting[lwt_io] NumberIO.LE.write_int16 [002.358]: finished in 0.000 s[lwt_io] NumberIO.BE.write_int16 [002.358]: starting[lwt_io] NumberIO.BE.write_int16 [002.358]: finished in 0.000 s[lwt_io] NumberIO.LE.write_int32 [002.358]: starting[lwt_io] NumberIO.LE.write_int32 [002.358]: finished in 0.000 s[lwt_io] NumberIO.BE.write_int32 [002.358]: starting[lwt_io] NumberIO.BE.write_int32 [002.358]: finished in 0.000 s[lwt_io] NumberIO.LE.write_int64 [002.358]: starting[lwt_io] NumberIO.LE.write_int64 [002.358]: finished in 0.000 s[lwt_io] NumberIO.BE.write_int64 [002.358]: starting[lwt_io] NumberIO.BE.write_int64 [002.358]: finished in 0.000 s[lwt_io] NumberIO.LE.write_float32 [002.358]: starting[lwt_io] NumberIO.LE.write_float32 [002.358]: finished in 0.000 s[lwt_io] NumberIO.BE.write_float32 [002.358]: starting[lwt_io] NumberIO.BE.write_float32 [002.358]: finished in 0.000 s[lwt_io] NumberIO.LE.write_float64 [002.358]: starting[lwt_io] NumberIO.LE.write_float64 [002.358]: finished in 0.000 s[lwt_io] NumberIO.BE.write_float64 [002.358]: starting[lwt_io] NumberIO.BE.write_float64 [002.358]: finished in 0.000 s[lwt_io] Write from Lwt_bytes [002.358]: starting[lwt_io] Write from Lwt_bytes [002.358]: finished in 0.000 s[lwt_io] Read from Lwt_bytes [002.358]: starting[lwt_io] Read from Lwt_bytes [002.358]: finished in 0.000 s[lwt_process] lazy_undefined [002.358]: starting[lwt_process] subproc stdout can be redirected to null [002.358]: starting[lwt_process] subproc stderr can be redirected to null [002.359]: starting[lwt_process] subproc cannot write on closed stdout [002.359]: starting[lwt_process] subproc cannot write on closed stderr [002.360]: starting[lwt_process] can write to subproc stdin [002.360]: starting[lwt_process] can read from subproc stdout [002.361]: starting[lwt_process] can read from subproc stderr [002.361]: starting[lwt_process] overrides env [002.362]: starting[lwt_process] passes env [002.362]: starting[lwt_process] inherits env [002.363]: starting[lwt_engine] libev: timer delays are not too short [002.363]: starting[unix_mcast] mcast-join-loop [003.364]: starting[unix_mcast] mcast-nojoin-loop [003.364]: starting[unix_mcast] mcast-join-noloop [003.364]: starting[unix_mcast] mcast-nojoin-noloop [003.364]: starting[lwt_fmt] flushing [003.364]: starting[lwt_fmt] flushing [003.364]: finished in 0.000 s[lwt_fmt] with combinator [003.364]: starting[lwt_fmt] with combinator [003.364]: finished in 0.000 s[lwt_fmt] box [003.364]: starting[lwt_fmt] box [003.364]: finished in 0.000 s[lwt_fmt] boxsplit [003.364]: starting[lwt_fmt] boxsplit [003.364]: finished in 0.000 s[lwt_fmt] box close with flush [003.364]: starting[lwt_fmt] box close with flush [003.364]: finished in 0.000 s[lwt_fmt] stream [003.364]: starting[lwt_fmt] stream [003.364]: finished in 0.000 s[Lwt_timeout] basic [003.364]: starting[Lwt_timeout] not started [003.364]: starting[Lwt_timeout] double start [003.364]: starting[Lwt_timeout] restart [003.364]: starting[Lwt_timeout] stop [003.364]: starting[Lwt_timeout] stop when not stopped [003.364]: starting[Lwt_timeout] stop when not stopped [003.364]: finished in 0.000 s[Lwt_timeout] invalid delay [003.364]: starting[Lwt_timeout] invalid delay [003.364]: finished in 0.000 s[Lwt_timeout] change [003.364]: starting[Lwt_timeout] change does not start [003.364]: starting[Lwt_timeout] change after start [003.364]: starting[Lwt_timeout] change: invalid delay [003.364]: starting[Lwt_timeout] change: invalid delay [003.364]: finished in 0.000 s[Lwt_timeout] set_exn_handler [003.364]: starting[Lwt_timeout] two [003.364]: starting[Lwt_timeout] simultaneous [003.364]: starting[Lwt_timeout] two, first stopped [003.364]: starting[lwt_bytes] create [003.364]: starting[lwt_bytes] create [003.364]: finished in 0.000 s[lwt_bytes] get/set [003.364]: starting[lwt_bytes] get/set [003.364]: finished in 0.000 s[lwt_bytes] get out of bounds : lower limit [003.364]: starting[lwt_bytes] get out of bounds : lower limit [003.364]: finished in 0.000 s[lwt_bytes] get out of bounds : upper limit [003.364]: starting[lwt_bytes] get out of bounds : upper limit [003.364]: finished in 0.000 s[lwt_bytes] set out of bounds : lower limit [003.364]: starting[lwt_bytes] set out of bounds : lower limit [003.364]: finished in 0.000 s[lwt_bytes] set out of bounds : upper limit [003.364]: starting[lwt_bytes] set out of bounds : upper limit [003.364]: finished in 0.000 s[lwt_bytes] unsafe_get/unsafe_set [003.364]: starting[lwt_bytes] unsafe_get/unsafe_set [003.364]: finished in 0.000 s[lwt_bytes] of bytes [003.364]: starting[lwt_bytes] of bytes [003.364]: finished in 0.000 s[lwt_bytes] of string [003.364]: starting[lwt_bytes] of string [003.364]: finished in 0.000 s[lwt_bytes] to bytes [003.364]: starting[lwt_bytes] to bytes [003.364]: finished in 0.000 s[lwt_bytes] to string [003.364]: starting[lwt_bytes] to string [003.364]: finished in 0.000 s[lwt_bytes] blit [003.365]: starting[lwt_bytes] blit [003.365]: finished in 0.000 s[lwt_bytes] blit source out of bounds: lower limit [003.365]: starting[lwt_bytes] blit source out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] blit source out of bounds: upper limit [003.365]: starting[lwt_bytes] blit source out of bounds: upper limit [003.365]: finished in 0.000 s[lwt_bytes] blit destination out of bounds: lower limit [003.365]: starting[lwt_bytes] blit destination out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] blit destination out of bounds: upper limit [003.365]: starting[lwt_bytes] blit destination out of bounds: upper limit [003.365]: finished in 0.000 s[lwt_bytes] blit length out of bounds: lower limit [003.365]: starting[lwt_bytes] blit length out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] blit from bytes [003.365]: starting[lwt_bytes] blit from bytes [003.365]: finished in 0.000 s[lwt_bytes] blit from bytes source out of bounds: lower limit [003.365]: starting[lwt_bytes] blit from bytes source out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] blit from bytes source out of bounds: upper limit [003.365]: starting[lwt_bytes] blit from bytes source out of bounds: upper limit [003.365]: finished in 0.000 s[lwt_bytes] blit from bytes destination out of bounds: lower limit [003.365]: starting[lwt_bytes] blit from bytes destination out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] blit from bytes destination out of bounds: upper limit [003.365]: starting[lwt_bytes] blit from bytes destination out of bounds: upper limit [003.365]: finished in 0.000 s[lwt_bytes] blit from bytes length out of bounds: lower limit [003.365]: starting[lwt_bytes] blit from bytes length out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] blit from string [003.365]: starting[lwt_bytes] blit from string [003.365]: finished in 0.000 s[lwt_bytes] blit from string source out of bounds: lower limit [003.365]: starting[lwt_bytes] blit from string source out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] blit from string source out of bounds: upper limit [003.365]: starting[lwt_bytes] blit from string source out of bounds: upper limit [003.365]: finished in 0.000 s[lwt_bytes] blit from string destination out of bounds: lower limit [003.365]: starting[lwt_bytes] blit from string destination out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] blit from string destination out of bounds: upper limit [003.365]: starting[lwt_bytes] blit from string destination out of bounds: upper limit [003.365]: finished in 0.000 s[lwt_bytes] blit from string length out of bounds: lower limit [003.365]: starting[lwt_bytes] blit from string length out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] blit from string length out of bounds: upper limit [003.365]: starting[lwt_bytes] blit from string length out of bounds: upper limit [003.365]: finished in 0.000 s[lwt_bytes] blit to bytes [003.365]: starting[lwt_bytes] blit to bytes [003.365]: finished in 0.000 s[lwt_bytes] blit to bytes source out of bounds: lower limit [003.365]: starting[lwt_bytes] blit to bytes source out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] blit to bytes source out of bounds: upper limit [003.365]: starting[lwt_bytes] blit to bytes source out of bounds: upper limit [003.365]: finished in 0.000 s[lwt_bytes] blit to bytes destination out of bounds: lower limit [003.365]: starting[lwt_bytes] blit to bytes destination out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] blit to bytes destination out of bounds: upper limit [003.365]: starting[lwt_bytes] blit to bytes destination out of bounds: upper limit [003.365]: finished in 0.000 s[lwt_bytes] blit to bytes length out of bounds: lower limit [003.365]: starting[lwt_bytes] blit to bytes length out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] unsafe blit [003.365]: starting[lwt_bytes] unsafe blit [003.365]: finished in 0.000 s[lwt_bytes] unsafe blit from bytes [003.365]: starting[lwt_bytes] unsafe blit from bytes [003.365]: finished in 0.000 s[lwt_bytes] unsafe blit from string [003.365]: starting[lwt_bytes] unsafe blit from string [003.365]: finished in 0.000 s[lwt_bytes] unsafe blit to bytes [003.365]: starting[lwt_bytes] unsafe blit to bytes [003.365]: finished in 0.000 s[lwt_bytes] proxy [003.365]: starting[lwt_bytes] proxy [003.365]: finished in 0.000 s[lwt_bytes] proxy offset out of bounds: lower limit [003.365]: starting[lwt_bytes] proxy offset out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] proxy offset out of bounds: upper limit [003.365]: starting[lwt_bytes] proxy offset out of bounds: upper limit [003.365]: finished in 0.000 s[lwt_bytes] proxy length out of bounds: lower limit [003.365]: starting[lwt_bytes] proxy length out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] extract [003.365]: starting[lwt_bytes] extract [003.365]: finished in 0.000 s[lwt_bytes] extract offset out of bounds: lower limit [003.365]: starting[lwt_bytes] extract offset out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] extract offset out of bounds: upper limit [003.365]: starting[lwt_bytes] extract offset out of bounds: upper limit [003.365]: finished in 0.000 s[lwt_bytes] extract length out of bounds: lower limit [003.365]: starting[lwt_bytes] extract length out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] copy [003.365]: starting[lwt_bytes] copy [003.365]: finished in 0.000 s[lwt_bytes] fill [003.365]: starting[lwt_bytes] fill [003.365]: finished in 0.000 s[lwt_bytes] fill offset out of bounds: lower limit [003.365]: starting[lwt_bytes] fill offset out of bounds: lower limit [003.365]: finished in 0.000 s[lwt_bytes] fill offset out of bounds: upper limit [003.365]: starting[lwt_bytes] fill offset out of bounds: upper limit [003.365]: finished in 0.000 s[lwt_bytes] fill length out of bounds lower limit [003.365]: starting[lwt_bytes] fill length out of bounds lower limit [003.365]: finished in 0.000 s[lwt_bytes] unsafe fill [003.365]: starting[lwt_bytes] unsafe fill [003.365]: finished in 0.000 s[lwt_bytes] bytes read [003.365]: starting[lwt_bytes] bytes write [003.365]: starting[lwt_bytes] bytes recv [003.365]: starting[lwt_bytes] bytes send [003.365]: starting[lwt_bytes] bytes recvfrom [003.365]: starting[lwt_bytes] bytes sendto [003.365]: starting[lwt_bytes] bytes recv_msg [003.452]: starting[lwt_bytes] bytes send_msg [003.452]: starting[lwt_bytes] send_msgto [003.452]: starting[lwt_bytes] map_file [003.452]: starting[lwt_bytes] map_file [003.452]: finished in 0.000 s[lwt_bytes] page_size [003.452]: starting[lwt_bytes] page_size [003.452]: finished in 0.000 s[lwt_bytes] mincore buffer length = page_size * 2, n_states = 1 [003.452]: starting[lwt_bytes] mincore buffer length = page_size * 2, n_states = 2 [003.452]: starting[lwt_bytes] mincore buffer length = page_size * 2 + 1, n_states = 2 [003.452]: starting[lwt_bytes] mincore buffer length = page_size , n_states = 0 [003.452]: starting[lwt_bytes] wait_mincore correct bounds [003.452]: starting[lwt_bytes] wait_mincore offset < 0 [003.452]: starting[lwt_bytes] wait_mincore offset > buffer length [003.452]: starting[Lwt_unix sleep and timeout] sleep [003.452]: starting[Lwt_unix sleep and timeout] timeout [003.452]: starting[Lwt_unix sleep and timeout] with_timeout : no timeout [003.452]: starting[Lwt_unix sleep and timeout] with_timeout : timeout [003.452]: starting[Lwt_unix sleep and timeout] pause [003.452]: starting[Lwt_unix sleep and timeout] pause [003.452]: finished in 0.000 s[Lwt_unix sleep and timeout] auto_pause [003.452]: starting[lwt_unix] utimes: basic [003.452]: finished in 1.113 s[lwt_unix] utimes: missing file [003.452]: finished in 1.112 s[lwt_unix] bind: basic [003.461]: finished in 1.106 s[lwt_unix] bind: closed [003.461]: finished in 1.106 s[lwt_unix] bind: aborted [003.461]: finished in 1.106 s[lwt_unix] getcwd [003.462]: finished in 1.106 s[lwt_unix] getcwd and Unix.getcwd [003.462]: finished in 1.106 s[lwt_unix] getgrgid and Unix.getgrgid [003.462]: finished in 1.105 s[lwt_unix] getgrnam and Unix.getgrnam [003.462]: finished in 1.105 s[lwt_io] file_length on directory [003.462]: finished in 1.105 s[unix_mcast] mcast-join-loop [003.462]: finished in 0.098 s[unix_mcast] mcast-nojoin-loop [003.462]: finished in 0.098 s[unix_mcast] mcast-join-noloop [003.462]: finished in 0.098 s[unix_mcast] mcast-nojoin-noloop [003.462]: finished in 0.098 s[Lwt_unix sleep and timeout] with_timeout : no timeout [003.462]: finished in 0.009 s[lwt_process] can write to subproc stdin [003.462]: finished in 1.102 s[lwt_process] subproc cannot write on closed stderr [003.462]: finished in 1.102 s[lwt_process] subproc cannot write on closed stdout [003.462]: finished in 1.103 s[lwt_process] subproc stderr can be redirected to null [003.462]: finished in 1.103 s[lwt_process] subproc stdout can be redirected to null [003.462]: finished in 1.104 s[lwt_process] can read from subproc stdout [003.462]: finished in 1.102 s[lwt_process] can read from subproc stderr [003.462]: finished in 1.101 s[lwt_process] overrides env [003.462]: finished in 1.101 s[lwt_process] passes env [003.462]: finished in 1.100 s[lwt_process] inherits env [003.462]: finished in 1.100 s[lwt_unix] utimes: current time [003.463]: finished in 1.123 s[lwt_unix] readdir: already closed [003.463]: finished in 1.122 s[lwt_unix] readv: basic non-blocking [003.463]: finished in 1.122 s[lwt_unix] readv: drop [003.463]: finished in 1.122 s[lwt_unix] readv: iovecs exceeding limit [003.463]: finished in 1.122 s[lwt_unix] writev: basic non-blocking [003.463]: finished in 1.122 s[lwt_unix] writev: slices [003.463]: finished in 1.118 s[lwt_unix] writev: drop, is_empty [003.463]: finished in 1.116 s[lwt_unix] writev: degenerate vectors [003.463]: finished in 1.114 s[lwt_unix] writev: bad iovec [003.463]: finished in 1.112 s[lwt_unix] writev: iovecs exceeding limit [003.463]: finished in 1.112 s[lwt_unix] writev: negative drop [003.463]: finished in 1.110 s[lwt_unix] bind: Unix domain [003.463]: finished in 1.108 s[lwt_io] with_close_connection: no duplicate exceptions [003.463]: finished in 1.106 s[lwt_io] create_temp_dir [003.463]: finished in 1.106 s[lwt_bytes] bytes recvfrom [003.463]: finished in 0.098 s[lwt_bytes] bytes sendto [003.463]: finished in 0.098 s[lwt_bytes] bytes recv_msg [003.463]: finished in 0.011 s[lwt_bytes] send_msgto [003.463]: finished in 0.011 s[lwt_bytes] bytes send_msg [003.463]: finished in 0.011 s[lwt_process] lazy_undefined [003.463]: finished in 1.106 s[lwt_unix] readdir: files_of_directory [003.464]: finished in 1.123 s[lwt_unix] readv: basic blocking [003.464]: finished in 1.123 s[lwt_unix] writev: basic blocking [003.464]: finished in 1.120 s[lwt_unix] getcwd and chdir [003.464]: finished in 1.108 s[lwt_io] open_temp_file [003.464]: finished in 1.107 s[lwt_bytes] bytes read [003.464]: finished in 0.098 s[lwt_bytes] mincore buffer length = page_size * 2, n_states = 1 [003.464]: finished in 0.011 s[lwt_bytes] mincore buffer length = page_size * 2, n_states = 2 [003.464]: finished in 0.012 s[lwt_bytes] mincore buffer length = page_size * 2 + 1, n_states = 2 [003.464]: finished in 0.012 s[lwt_bytes] mincore buffer length = page_size , n_states = 0 [003.464]: finished in 0.012 s[lwt_bytes] wait_mincore correct bounds [003.464]: finished in 0.012 s[lwt_bytes] wait_mincore offset < 0 [003.464]: finished in 0.012 s[lwt_bytes] wait_mincore offset > buffer length [003.464]: finished in 0.011 s[lwt_unix] run_in_main_dont_wait [003.464]: finished in 1.108 s[lwt_unix] run_in_main_dont_wait_fail [003.464]: finished in 1.108 s[lwt_unix] run_in_main_with_dont_wait [003.464]: finished in 1.108 s[lwt_bytes] bytes recv [003.464]: finished in 0.099 s[lwt_bytes] bytes send [003.464]: finished in 0.099 s[lwt_io] establish_server_1: shutdown: client closes first [003.464]: finished in 1.108 s[lwt_io] with_connection [003.464]: finished in 1.107 s[lwt_unix] readdir: readdir_n [003.464]: finished in 1.124 s[lwt_unix] send_msg, recv_msg [003.464]: finished in 1.109 s[lwt_unix] send_msg, recv_msg (Lwt_bytes, old) [003.464]: finished in 1.109 s[lwt_io] with_temp_filename close handle [003.464]: finished in 1.107 s[lwt_io] open_connection: shutdown: server closes first [003.464]: finished in 1.108 s[lwt_io] establish_server: explicit close [003.464]: finished in 1.107 s[lwt_bytes] bytes write [003.464]: finished in 0.099 s[lwt_io] with_temp_filename [003.464]: finished in 1.107 s[lwt_unix] readdir: basic [003.464]: finished in 1.124 s[lwt_unix] readdir: rewinddir [003.464]: finished in 1.124 s[lwt_unix] run_in_main [003.472]: finished in 1.117 s[lwt_io] establish_server: implicit close [003.514]: finished in 1.158 s[lwt_engine] libev: timer delays are not too short [003.864]: finished in 1.501 s[Lwt_unix sleep and timeout] sleep [004.453]: finished in 1.000 s[Lwt_unix sleep and timeout] timeout [004.453]: finished in 1.000 s[Lwt_unix sleep and timeout] with_timeout : timeout [004.453]: finished in 1.000 s[Lwt_unix sleep and timeout] auto_pause [004.453]: finished in 1.000 s[Lwt_timeout] simultaneous [005.366]: finished in 2.001 s[Lwt_timeout] set_exn_handler [005.366]: finished in 2.002 s[Lwt_timeout] change after start [005.366]: finished in 2.002 s[Lwt_timeout] change [005.366]: finished in 2.002 s[Lwt_timeout] basic [005.366]: finished in 2.002 s[Lwt_timeout] not started [006.365]: finished in 3.001 s[Lwt_timeout] double start [006.365]: finished in 3.001 s[Lwt_timeout] stop [006.365]: finished in 3.001 s[Lwt_timeout] change does not start [006.365]: finished in 3.001 s[Lwt_timeout] two, first stopped [006.366]: finished in 3.002 s[Lwt_timeout] two [006.366]: finished in 3.002 s[Lwt_timeout] restart [006.366]: finished in 3.002 s2026-01-26 09:07.42 ---> saved as "1123180104046b1d29976146c9b588e0283f691d3979da996c7f67df287eccc4"Job succeeded2026-01-26 09:07.42: Job succeeded