1. 0.02 s $ /nix/store/vzx1mi9c0xfadmsm9dhd83d005cb1qs9-coreutils-9.8/bin/timeout --kill-after=15s 7200s /nix/store/99b1z08awpxj8b6mzggn59gp1shljnff-nix-2.34.5/bin/nix --extra-experimental-features nix-command --extra-experimental-features flakes --log-format internal-json build --no-link git+https://github.com/NorfairKing/hatrace?ref=your-first-flake&rev=42f2702f07cb916df5a000f8c3696a16c70dcb97#packages.x86_64-linux.hatrace --print-build-logs
  2. 0.07 s
  3. 0.68 s Downloading cached glibc-2.42-61-static from https://cache.staging.nix-ci.com
  4. 0.68 s Downloading cached HUnit-1.6.2.0-doc from https://cache.staging.nix-ci.com
  5. 0.68 s Downloading cached haskell-lexer-1.2.1-doc from https://cache.staging.nix-ci.com
  6. 0.68 s Downloading cached hspec-discover-2.11.17-doc from https://cache.staging.nix-ci.com
  7. 0.74 s Progress: 0 of 3 built, 1 of 15 downloaded from cache (3 downloading)
  8. 0.74 s Downloading cached hspec-discover from https://cache.staging.nix-ci.com
  9. 0.75 s Progress: 0 of 3 built, 2 of 15 downloaded from cache (3 downloading)
  10. 0.75 s Downloading cached haskell-lexer from https://cache.staging.nix-ci.com
  11. 0.75 s Progress: 0 of 3 built, 3 of 15 downloaded from cache (3 downloading)
  12. 0.75 s Downloading cached HUnit from https://cache.staging.nix-ci.com
  13. 0.75 s Downloading cached hspec-expectations-0.8.4-doc from https://cache.staging.nix-ci.com
  14. 0.75 s Downloading cached quickcheck-io-0.2.0-doc from https://cache.staging.nix-ci.com
  15. 0.80 s Progress: 0 of 3 built, 4 of 15 downloaded from cache (5 downloading)
  16. 0.82 s Progress: 0 of 3 built, 5 of 15 downloaded from cache (4 downloading)
  17. 0.82 s Progress: 0 of 2 built, 6 of 15 downloaded from cache (3 downloading)
  18. 0.82 s Downloading cached hspec-core-2.11.17-doc from https://cache.staging.nix-ci.com
  19. 0.83 s Progress: 0 of 2 built, 7 of 15 downloaded from cache (3 downloading)
  20. 0.83 s Progress: 0 of 2 built, 8 of 15 downloaded from cache (2 downloading)
  21. 0.83 s Downloading cached hspec-expectations from https://cache.staging.nix-ci.com
  22. 0.83 s Downloading cached quickcheck-io from https://cache.staging.nix-ci.com
  23. 0.87 s Progress: 0 of 2 built, 9 of 15 downloaded from cache (3 downloading)
  24. 0.87 s Progress: 0 of 2 built, 10 of 15 downloaded from cache (2 downloading)
  25. 0.88 s Progress: 0 of 2 built, 11 of 15 downloaded from cache (1 downloading)
  26. 0.88 s Downloading cached hspec-2.11.17-doc from https://cache.staging.nix-ci.com
  27. 0.88 s Progress: 0 of 2 built, 12 of 15 downloaded from cache (1 downloading)
  28. 0.88 s Downloading cached hspec-core from https://cache.staging.nix-ci.com
  29. 0.91 s Progress: 0 of 2 built, 13 of 15 downloaded from cache (1 downloading)
  30. 1.04 s Progress: 0 of 2 built, 14 of 15 downloaded from cache
  31. 1.04 s Downloading cached hspec from https://cache.staging.nix-ci.com
  32. 1.07 s Progress: 0 of 2 built, 15 of 15 downloaded from cache
  33. 1.18 s Building hatrace
  34. 1.39 s [hatrace] Phase: setupCompilerEnvironmentPhase
  35. 1.44 s [hatrace] Build with /nix/store/k75mq7in58dhfx8n2prlq73pnnzm9cgd-ghc-9.10.3.
  36. 1.69 s [hatrace] Phase: unpackPhase
  37. 1.70 s [hatrace] unpacking source archive /nix/store/2qmjzdkjwdw685dzl75dd3p4ly7hynxa-hatrace-source
  38. 1.71 s [hatrace] source root is hatrace-source
  39. 1.72 s [hatrace] Phase: patchPhase
  40. 1.75 s [hatrace] Phase: compileBuildDriverPhase
  41. 1.76 s [hatrace] setupCompileFlags: -package-db=/build/tmp.9gV4hzP2hM/setup-package.conf.d -threaded
  42. 1.80 s [hatrace] [1 of 2] Compiling Main ( Setup.hs, /build/tmp.9gV4hzP2hM/Main.o )
  43. 1.89 s [hatrace] [2 of 2] Linking Setup
  44. 2.95 s [hatrace] Phase: updateAutotoolsGnuConfigScriptsPhase
  45. 2.96 s [hatrace] Phase: configurePhase
  46. 2.97 s [hatrace] configureFlags: --verbose --prefix=/nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0 --libdir=$prefix/lib/$compiler/lib --libsubdir=$abi/$libname --with-gcc=gcc --package-db=/build/tmp.9gV4hzP2hM/package.conf.d --ghc-option=-j16 --ghc-option=+RTS --ghc-option=-A64M --ghc-option=-RTS --disable-library-profiling --disable-profiling --enable-shared --disable-coverage --enable-static --disable-executable-dynamic --enable-tests --disable-benchmarks --enable-library-vanilla --disable-library-for-ghci --enable-split-sections --enable-library-stripping --enable-executable-stripping --ghc-option=-Wno-incomplete-uni-patterns --extra-lib-dirs=/nix/store/2iaawa9vbqas51lgpn4cjnnfdv74x8fn-ncurses-6.6/lib --extra-lib-dirs=/nix/store/hyai3q7gvdfppw4ky7s2mvhxvfyp5bh7-libffi-3.5.2/lib --extra-lib-dirs=/nix/store/qph045w6z4ss6l0awl1sh228gdx98xns-elfutils-0.194/lib --extra-lib-dirs=/nix/store/1agm3iac17cykdmabxck60vwk5sjk4wc-gmp-with-cxx-6.3.0/lib --extra-lib-dirs=/nix/store/8xlwd35bpmj7n6bzjwfnr6vidpwicjdd-numactl-2.0.18/lib
  47. 2.98 s [hatrace] Using Parsec parser
  48. 2.98 s [hatrace] Configuring hatrace-0.1.0.0...
  49. 3.49 s [hatrace] Dependency aeson: using aeson-2.2.3.0
  50. 3.49 s [hatrace] Dependency base >=4.7 && <5: using base-4.20.2.0
  51. 3.49 s [hatrace] Dependency bytestring: using bytestring-0.12.2.0
  52. 3.49 s [hatrace] Dependency conduit: using conduit-1.3.6.1
  53. 3.49 s [hatrace] Dependency containers: using containers-0.7
  54. 3.49 s [hatrace] Dependency directory: using directory-1.3.8.5
  55. 3.49 s [hatrace] Dependency filepath: using filepath-1.5.4.0
  56. 3.49 s [hatrace] Dependency linux-ptrace: using linux-ptrace-0.1.2.1
  57. 3.49 s [hatrace] Dependency optparse-applicative: using optparse-applicative-0.18.1.0
  58. 3.49 s [hatrace] Dependency posix-waitpid: using posix-waitpid-0.1.1.0
  59. 3.49 s [hatrace] Dependency process: using process-1.6.26.1
  60. 3.49 s [hatrace] Dependency template-haskell: using template-haskell-2.22.0.0
  61. 3.49 s [hatrace] Dependency text: using text-2.1.3
  62. 3.49 s [hatrace] Dependency th-abstraction: using th-abstraction-0.7.2.0
  63. 3.49 s [hatrace] Dependency time: using time-1.12.2
  64. 3.49 s [hatrace] Dependency unix: using unix-2.8.7.0
  65. 3.49 s [hatrace] Dependency unliftio: using unliftio-0.2.25.1
  66. 3.49 s [hatrace] Dependency unliftio-core: using unliftio-core-0.2.1.0
  67. 3.49 s [hatrace] Dependency base: using base-4.20.2.0
  68. 3.49 s [hatrace] Dependency hatrace: using hatrace-0.1.0.0
  69. 3.49 s [hatrace] Dependency base: using base-4.20.2.0
  70. 3.49 s [hatrace] Dependency bytestring: using bytestring-0.12.2.0
  71. 3.49 s [hatrace] Dependency conduit: using conduit-1.3.6.1
  72. 3.49 s [hatrace] Dependency containers: using containers-0.7
  73. 3.49 s [hatrace] Dependency directory: using directory-1.3.8.5
  74. 3.49 s [hatrace] Dependency filepath: using filepath-1.5.4.0
  75. 3.49 s [hatrace] Dependency hatrace: using hatrace-0.1.0.0
  76. 3.49 s [hatrace] Dependency hspec: using hspec-2.11.17
  77. 3.49 s [hatrace] Dependency process: using process-1.6.26.1
  78. 3.49 s [hatrace] Dependency temporary: using temporary-1.3
  79. 3.49 s [hatrace] Dependency text: using text-2.1.3
  80. 3.49 s [hatrace] Dependency unix: using unix-2.8.7.0
  81. 3.49 s [hatrace] Dependency unliftio: using unliftio-0.2.25.1
  82. 3.49 s [hatrace] Dependency unliftio-core: using unliftio-core-0.2.1.0
  83. 3.49 s [hatrace] Source component graph:
  84. 3.49 s [hatrace] component lib
  85. 3.49 s [hatrace] component exe:hatrace dependency lib
  86. 3.49 s [hatrace] component test:hatrace-test dependency lib
  87. 3.49 s [hatrace] Configured component graph:
  88. 3.49 s [hatrace] component hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp
  89. 3.49 s [hatrace] include aeson-2.2.3.0-6yo1KFncTLSHL1P5blSuk2
  90. 3.49 s [hatrace] include base-4.20.2.0-026d
  91. 3.49 s [hatrace] include bytestring-0.12.2.0-b36e
  92. 3.49 s [hatrace] include conduit-1.3.6.1-DHMGfsOsphXFOSgyZNnbql
  93. 3.49 s [hatrace] include containers-0.7-8412
  94. 3.49 s [hatrace] include directory-1.3.8.5-08c4
  95. 3.49 s [hatrace] include filepath-1.5.4.0-150e
  96. 3.49 s [hatrace] include linux-ptrace-0.1.2.1-IKZvVE0WW3II8dZePcpB6z
  97. 3.49 s [hatrace] include optparse-applicative-0.18.1.0-GLp2kUXQay0IdnlzYiXVF8
  98. 3.49 s [hatrace] include posix-waitpid-0.1.1.0-D2wWOIrlgAQ2X3DHfw4v7t
  99. 3.49 s [hatrace] include process-1.6.26.1-262a
  100. 3.49 s [hatrace] include template-haskell-2.22.0.0-d01f
  101. 3.49 s [hatrace] include text-2.1.3-8afe
  102. 3.49 s [hatrace] include th-abstraction-0.7.2.0-2sm6zK3TXV58dSbsZx2D1R
  103. 3.49 s [hatrace] include time-1.12.2-6cc2
  104. 3.49 s [hatrace] include unix-2.8.7.0-c12e
  105. 3.49 s [hatrace] include unliftio-0.2.25.1-ICXNqH4TtyoCtg89coTUbL
  106. 3.49 s [hatrace] include unliftio-core-0.2.1.0-JEtIeDE5tmH4DYTXnj7oJx
  107. 3.49 s [hatrace] component hatrace-0.1.0.0-A94zhUZ9CpWLHuj8XUMQxy-hatrace
  108. 3.49 s [hatrace] include base-4.20.2.0-026d
  109. 3.49 s [hatrace] include hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp
  110. 3.49 s [hatrace] component hatrace-0.1.0.0-DI6Qxzgr4v6nGVu3Di6Wg-hatrace-test
  111. 3.49 s [hatrace] include base-4.20.2.0-026d
  112. 3.49 s [hatrace] include bytestring-0.12.2.0-b36e
  113. 3.49 s [hatrace] include conduit-1.3.6.1-DHMGfsOsphXFOSgyZNnbql
  114. 3.49 s [hatrace] include containers-0.7-8412
  115. 3.49 s [hatrace] include directory-1.3.8.5-08c4
  116. 3.49 s [hatrace] include filepath-1.5.4.0-150e
  117. 3.49 s [hatrace] include hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp
  118. 3.49 s [hatrace] include hspec-2.11.17-E5TzN1xEEw8FFUEZem0tM4
  119. 3.49 s [hatrace] include process-1.6.26.1-262a
  120. 3.49 s [hatrace] include temporary-1.3-Cgab7CTjPKZCTNDEscZYTO
  121. 3.49 s [hatrace] include text-2.1.3-8afe
  122. 3.49 s [hatrace] include unix-2.8.7.0-c12e
  123. 3.49 s [hatrace] include unliftio-0.2.25.1-ICXNqH4TtyoCtg89coTUbL
  124. 3.49 s [hatrace] include unliftio-core-0.2.1.0-JEtIeDE5tmH4DYTXnj7oJx
  125. 3.49 s [hatrace] Linked component graph:
  126. 3.49 s [hatrace] unit hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp
  127. 3.49 s [hatrace] include aeson-2.2.3.0-6yo1KFncTLSHL1P5blSuk2
  128. 3.49 s [hatrace] include base-4.20.2.0-026d
  129. 3.49 s [hatrace] include bytestring-0.12.2.0-b36e
  130. 3.49 s [hatrace] include conduit-1.3.6.1-DHMGfsOsphXFOSgyZNnbql
  131. 3.49 s [hatrace] include containers-0.7-8412
  132. 3.49 s [hatrace] include directory-1.3.8.5-08c4
  133. 3.49 s [hatrace] include filepath-1.5.4.0-150e
  134. 3.49 s [hatrace] include linux-ptrace-0.1.2.1-IKZvVE0WW3II8dZePcpB6z
  135. 3.49 s [hatrace] include optparse-applicative-0.18.1.0-GLp2kUXQay0IdnlzYiXVF8
  136. 3.49 s [hatrace] include posix-waitpid-0.1.1.0-D2wWOIrlgAQ2X3DHfw4v7t
  137. 3.49 s [hatrace] include process-1.6.26.1-262a
  138. 3.49 s [hatrace] include template-haskell-2.22.0.0-d01f
  139. 3.49 s [hatrace] include text-2.1.3-8afe
  140. 3.49 s [hatrace] include th-abstraction-0.7.2.0-2sm6zK3TXV58dSbsZx2D1R
  141. 3.49 s [hatrace] include time-1.12.2-6cc2
  142. 3.49 s [hatrace] include unix-2.8.7.0-c12e
  143. 3.49 s [hatrace] include unliftio-0.2.25.1-ICXNqH4TtyoCtg89coTUbL
  144. 3.49 s [hatrace] include unliftio-core-0.2.1.0-JEtIeDE5tmH4DYTXnj7oJx
  145. 3.49 s [hatrace] System.Hatrace=hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp:System.Hatrace,System.Hatrace.Format=hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp:System.Hatrace.Format,System.Hatrace.Main=hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp:System.Hatrace.Main,System.Hatrace.Signals=hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp:System.Hatrace.Signals,System.Hatrace.SyscallTables=hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp:System.Hatrace.SyscallTables,System.Hatrace.SyscallTables.Generated=hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp:System.Hatrace.SyscallTables.Generated,System.Hatrace.SyscallTables.Util=hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp:System.Hatrace.SyscallTables.Util,System.Hatrace.Types=hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp:System.Hatrace.Types,System.Hatrace.Types.Internal=hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp:System.Hatrace.Types.Internal,System.Hatrace.Types.TH=hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp:System.Hatrace.Types.TH
  146. 3.49 s [hatrace] unit hatrace-0.1.0.0-A94zhUZ9CpWLHuj8XUMQxy-hatrace
  147. 3.49 s [hatrace] include base-4.20.2.0-026d
  148. 3.49 s [hatrace] include hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp
  149. 3.49 s [hatrace] unit hatrace-0.1.0.0-DI6Qxzgr4v6nGVu3Di6Wg-hatrace-test
  150. 3.49 s [hatrace] include base-4.20.2.0-026d
  151. 3.49 s [hatrace] include bytestring-0.12.2.0-b36e
  152. 3.49 s [hatrace] include conduit-1.3.6.1-DHMGfsOsphXFOSgyZNnbql
  153. 3.49 s [hatrace] include containers-0.7-8412
  154. 3.49 s [hatrace] include directory-1.3.8.5-08c4
  155. 3.49 s [hatrace] include filepath-1.5.4.0-150e
  156. 3.49 s [hatrace] include hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp
  157. 3.49 s [hatrace] include hspec-2.11.17-E5TzN1xEEw8FFUEZem0tM4
  158. 3.49 s [hatrace] include process-1.6.26.1-262a
  159. 3.49 s [hatrace] include temporary-1.3-Cgab7CTjPKZCTNDEscZYTO
  160. 3.49 s [hatrace] include text-2.1.3-8afe
  161. 3.49 s [hatrace] include unix-2.8.7.0-c12e
  162. 3.49 s [hatrace] include unliftio-0.2.25.1-ICXNqH4TtyoCtg89coTUbL
  163. 3.49 s [hatrace] include unliftio-core-0.2.1.0-JEtIeDE5tmH4DYTXnj7oJx
  164. 3.49 s [hatrace] Ready component graph:
  165. 3.49 s [hatrace] definite hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp
  166. 3.49 s [hatrace] depends aeson-2.2.3.0-6yo1KFncTLSHL1P5blSuk2
  167. 3.49 s [hatrace] depends base-4.20.2.0-026d
  168. 3.49 s [hatrace] depends bytestring-0.12.2.0-b36e
  169. 3.49 s [hatrace] depends conduit-1.3.6.1-DHMGfsOsphXFOSgyZNnbql
  170. 3.49 s [hatrace] depends containers-0.7-8412
  171. 3.49 s [hatrace] depends directory-1.3.8.5-08c4
  172. 3.49 s [hatrace] depends filepath-1.5.4.0-150e
  173. 3.49 s [hatrace] depends linux-ptrace-0.1.2.1-IKZvVE0WW3II8dZePcpB6z
  174. 3.49 s [hatrace] depends optparse-applicative-0.18.1.0-GLp2kUXQay0IdnlzYiXVF8
  175. 3.49 s [hatrace] depends posix-waitpid-0.1.1.0-D2wWOIrlgAQ2X3DHfw4v7t
  176. 3.49 s [hatrace] depends process-1.6.26.1-262a
  177. 3.49 s [hatrace] depends template-haskell-2.22.0.0-d01f
  178. 3.49 s [hatrace] depends text-2.1.3-8afe
  179. 3.49 s [hatrace] depends th-abstraction-0.7.2.0-2sm6zK3TXV58dSbsZx2D1R
  180. 3.49 s [hatrace] depends time-1.12.2-6cc2
  181. 3.49 s [hatrace] depends unix-2.8.7.0-c12e
  182. 3.49 s [hatrace] depends unliftio-0.2.25.1-ICXNqH4TtyoCtg89coTUbL
  183. 3.49 s [hatrace] depends unliftio-core-0.2.1.0-JEtIeDE5tmH4DYTXnj7oJx
  184. 3.49 s [hatrace] definite hatrace-0.1.0.0-DI6Qxzgr4v6nGVu3Di6Wg-hatrace-test
  185. 3.49 s [hatrace] depends base-4.20.2.0-026d
  186. 3.49 s [hatrace] depends bytestring-0.12.2.0-b36e
  187. 3.49 s [hatrace] depends conduit-1.3.6.1-DHMGfsOsphXFOSgyZNnbql
  188. 3.49 s [hatrace] depends containers-0.7-8412
  189. 3.49 s [hatrace] depends directory-1.3.8.5-08c4
  190. 3.49 s [hatrace] depends filepath-1.5.4.0-150e
  191. 3.49 s [hatrace] depends hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp
  192. 3.49 s [hatrace] depends hspec-2.11.17-E5TzN1xEEw8FFUEZem0tM4
  193. 3.49 s [hatrace] depends process-1.6.26.1-262a
  194. 3.49 s [hatrace] depends temporary-1.3-Cgab7CTjPKZCTNDEscZYTO
  195. 3.49 s [hatrace] depends text-2.1.3-8afe
  196. 3.49 s [hatrace] depends unix-2.8.7.0-c12e
  197. 3.49 s [hatrace] depends unliftio-0.2.25.1-ICXNqH4TtyoCtg89coTUbL
  198. 3.49 s [hatrace] depends unliftio-core-0.2.1.0-JEtIeDE5tmH4DYTXnj7oJx
  199. 3.49 s [hatrace] definite hatrace-0.1.0.0-A94zhUZ9CpWLHuj8XUMQxy-hatrace
  200. 3.49 s [hatrace] depends base-4.20.2.0-026d
  201. 3.49 s [hatrace] depends hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp
  202. 3.49 s [hatrace] Using Cabal-3.12.1.0 compiled by ghc-9.10
  203. 3.49 s [hatrace] Using compiler: ghc-9.10.3
  204. 3.49 s [hatrace] Using install prefix:
  205. 3.49 s [hatrace] /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0
  206. 3.49 s [hatrace] Executables installed in:
  207. 3.49 s [hatrace] /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/bin
  208. 3.49 s [hatrace] Libraries installed in:
  209. 3.51 s [hatrace] /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/lib/ghc-9.10.3/lib/x86_64-linux-ghc-9.10.3-c0c3/hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp
  210. 3.51 s [hatrace] Dynamic Libraries installed in:
  211. 3.51 s [hatrace] /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/lib/ghc-9.10.3/lib/x86_64-linux-ghc-9.10.3-c0c3
  212. 3.51 s [hatrace] Private executables installed in:
  213. 3.51 s [hatrace] /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/libexec/x86_64-linux-ghc-9.10.3-c0c3/hatrace-0.1.0.0
  214. 3.51 s [hatrace] Data files installed in:
  215. 3.51 s [hatrace] /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/share/x86_64-linux-ghc-9.10.3-c0c3/hatrace-0.1.0.0
  216. 3.51 s [hatrace] Documentation installed in:
  217. 3.51 s [hatrace] /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/share/doc/x86_64-linux-ghc-9.10.3-c0c3/hatrace-0.1.0.0
  218. 3.51 s [hatrace] Configuration files installed in:
  219. 3.51 s [hatrace] /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/etc
  220. 3.51 s [hatrace] No alex found
  221. 3.51 s [hatrace] Using ar found on system at:
  222. 3.51 s [hatrace] /nix/store/kfwagnh6i1mysf7vxq679rzh30z9zj3g-binutils-wrapper-2.46/bin/ar
  223. 3.51 s [hatrace] No c2hs found
  224. 3.51 s [hatrace] No cpphs found
  225. 3.51 s [hatrace] No doctest found
  226. 3.51 s [hatrace] Using gcc version 15.2.0 given by user at:
  227. 3.51 s [hatrace] /nix/store/qd70v8g0561vm8m33kmnp79z00cgyi5n-gcc-wrapper-15.2.0/bin/gcc
  228. 3.51 s [hatrace] Using ghc version 9.10.3 found on system at:
  229. 3.51 s [hatrace] /nix/store/k75mq7in58dhfx8n2prlq73pnnzm9cgd-ghc-9.10.3/bin/ghc
  230. 3.51 s [hatrace] Using ghc-pkg version 9.10.3 found on system at:
  231. 3.51 s [hatrace] /nix/store/k75mq7in58dhfx8n2prlq73pnnzm9cgd-ghc-9.10.3/bin/ghc-pkg-9.10.3
  232. 3.51 s [hatrace] No ghcjs found
  233. 3.51 s [hatrace] No ghcjs-pkg found
  234. 3.51 s [hatrace] No greencard found
  235. 3.51 s [hatrace] Using haddock version 2.31.1 found on system at:
  236. 3.51 s [hatrace] /nix/store/k75mq7in58dhfx8n2prlq73pnnzm9cgd-ghc-9.10.3/bin/haddock-ghc-9.10.3
  237. 3.51 s [hatrace] No happy found
  238. 3.51 s [hatrace] Using haskell-suite found on system at: haskell-suite-dummy-location
  239. 3.51 s [hatrace] Using haskell-suite-pkg found on system at: haskell-suite-pkg-dummy-location
  240. 3.51 s [hatrace] No hmake found
  241. 3.51 s [hatrace] Using hpc version 0.69 found on system at:
  242. 3.51 s [hatrace] /nix/store/k75mq7in58dhfx8n2prlq73pnnzm9cgd-ghc-9.10.3/bin/hpc-ghc-9.10.3
  243. 3.51 s [hatrace] Using hsc2hs version 0.68.10 found on system at:
  244. 3.51 s [hatrace] /nix/store/k75mq7in58dhfx8n2prlq73pnnzm9cgd-ghc-9.10.3/bin/hsc2hs-ghc-9.10.3
  245. 3.51 s [hatrace] No hscolour found
  246. 3.51 s [hatrace] No jhc found
  247. 3.51 s [hatrace] Using ld found on system at:
  248. 3.51 s [hatrace] /nix/store/qd70v8g0561vm8m33kmnp79z00cgyi5n-gcc-wrapper-15.2.0/bin/ld
  249. 3.51 s [hatrace] No pkg-config found
  250. 3.51 s [hatrace] Using runghc version 9.10.3 found on system at:
  251. 3.51 s [hatrace] /nix/store/k75mq7in58dhfx8n2prlq73pnnzm9cgd-ghc-9.10.3/bin/runghc-9.10.3
  252. 3.51 s [hatrace] Using strip version 2.46 found on system at:
  253. 3.51 s [hatrace] /nix/store/qd70v8g0561vm8m33kmnp79z00cgyi5n-gcc-wrapper-15.2.0/bin/strip
  254. 3.51 s [hatrace] Using tar found on system at:
  255. 3.51 s [hatrace] /nix/store/rnvb7bvp53v2dw7pcwh9xb89x5z4rjib-gnutar-1.35/bin/tar
  256. 3.51 s [hatrace] No uhc found
  257. 3.80 s [hatrace] Phase: buildPhase
  258. 3.85 s [hatrace] Preprocessing library for hatrace-0.1.0.0...
  259. 5.85 s [hatrace] Building library for hatrace-0.1.0.0...
  260. 5.99 s [hatrace] [ 1 of 10] Compiling System.Hatrace.Format ( src/System/Hatrace/Format.hs, dist/build/System/Hatrace/Format.o, dist/build/System/Hatrace/Format.dyn_o )
  261. 6.83 s [hatrace] [ 2 of 10] Compiling System.Hatrace.Signals ( src/System/Hatrace/Signals.hs, dist/build/System/Hatrace/Signals.o, dist/build/System/Hatrace/Signals.dyn_o )
  262. 6.83 s [hatrace] [ 3 of 10] Compiling System.Hatrace.SyscallTables ( src/System/Hatrace/SyscallTables.hs, dist/build/System/Hatrace/SyscallTables.o, dist/build/System/Hatrace/SyscallTables.dyn_o )
  263. 6.83 s [hatrace] [ 4 of 10] Compiling System.Hatrace.SyscallTables.Util ( src/System/Hatrace/SyscallTables/Util.hs, dist/build/System/Hatrace/SyscallTables/Util.o, dist/build/System/Hatrace/SyscallTables/Util.dyn_o )
  264. 6.83 s [hatrace] [ 5 of 10] Compiling System.Hatrace.SyscallTables.Generated ( src/System/Hatrace/SyscallTables/Generated.hs, dist/build/System/Hatrace/SyscallTables/Generated.o, dist/build/System/Hatrace/SyscallTables/Generated.dyn_o )
  265. 8.04 s [hatrace] [ 6 of 10] Compiling System.Hatrace.Types.Internal ( src/System/Hatrace/Types/Internal.hs, dist/build/System/Hatrace/Types/Internal.o, dist/build/System/Hatrace/Types/Internal.dyn_o )
  266. 8.04 s [hatrace] [ 7 of 10] Compiling System.Hatrace.Types.TH ( src/System/Hatrace/Types/TH.hs, dist/build/System/Hatrace/Types/TH.o, dist/build/System/Hatrace/Types/TH.dyn_o )
  267. 8.04 s [hatrace] [ 8 of 10] Compiling System.Hatrace.Types ( dist/build/System/Hatrace/Types.hs, dist/build/System/Hatrace/Types.o, dist/build/System/Hatrace/Types.dyn_o )
  268. 11.80 s [hatrace] [ 9 of 10] Compiling System.Hatrace ( src/System/Hatrace.hs, dist/build/System/Hatrace.o, dist/build/System/Hatrace.dyn_o )
  269. 12.23 s [hatrace] src/System/Hatrace.hs:284:11: warning: [GHC-53633] [-Woverlapping-patterns]
  270. 12.23 s [hatrace] Pattern match is redundant
  271. 12.23 s [hatrace] In a multi-way if alternative: | False -> ...
  272. 12.23 s [hatrace] |
  273. 12.23 s [hatrace] 284 | | False -> -- set this to True to get caller source code lines for failures
  274. 12.23 s [hatrace] | ^^^^^
  275. 12.23 s [hatrace]
  276. 27.69 s [hatrace] [10 of 10] Compiling System.Hatrace.Main ( src/System/Hatrace/Main.hs, dist/build/System/Hatrace/Main.o, dist/build/System/Hatrace/Main.dyn_o )
  277. 27.71 s [hatrace] src/System/Hatrace/Main.hs:112:13: warning: [GHC-63394] [-Wx-partial]
  278. 27.71 s [hatrace] In the use of ‘head’
  279. 27.71 s [hatrace] (imported from Prelude, but defined in GHC.Internal.List):
  280. 27.71 s [hatrace] "This is a partial function, it throws an error on empty lists. Use pattern matching, 'Data.List.uncons' or 'Data.Maybe.listToMaybe' instead. Consider refactoring to use "Data.List.NonEmpty"."
  281. 27.71 s [hatrace] |
  282. 27.71 s [hatrace] 112 | head (splitPath fromRoot) `elem` ["proc/", "dev/", "sys/"]
  283. 27.71 s [hatrace] | ^^^^
  284. 27.71 s [hatrace]
  285. 30.98 s [hatrace] Preprocessing test suite 'hatrace-test' for hatrace-0.1.0.0...
  286. 30.98 s [hatrace] Building test suite 'hatrace-test' for hatrace-0.1.0.0...
  287. 31.08 s [hatrace] [1 of 2] Compiling HatraceSpec ( test/HatraceSpec.hs, dist/build/hatrace-test/hatrace-test-tmp/HatraceSpec.o )
  288. 31.43 s [hatrace] test/HatraceSpec.hs:715:22: warning: [GHC-63394] [-Wx-partial]
  289. 31.43 s [hatrace] In the use of ‘head’
  290. 31.43 s [hatrace] (imported from Prelude, but defined in GHC.Internal.List):
  291. 31.43 s [hatrace] "This is a partial function, it throws an error on empty lists. Use pattern matching, 'Data.List.uncons' or 'Data.Maybe.listToMaybe' instead. Consider refactoring to use "Data.List.NonEmpty"."
  292. 31.43 s [hatrace] |
  293. 31.43 s [hatrace] 715 | testClosed (head dup3Arguments)
  294. 31.43 s [hatrace] | ^^^^
  295. 31.43 s [hatrace]
  296. 31.43 s [hatrace] test/HatraceSpec.hs:1005:32: warning: [GHC-63394] [-Wx-partial]
  297. 31.43 s [hatrace] In the use of ‘head’
  298. 31.43 s [hatrace] (imported from Prelude, but defined in GHC.Internal.List):
  299. 31.43 s [hatrace] "This is a partial function, it throws an error on empty lists. Use pattern matching, 'Data.List.uncons' or 'Data.Maybe.listToMaybe' instead. Consider refactoring to use "Data.List.NonEmpty"."
  300. 31.43 s [hatrace] |
  301. 31.43 s [hatrace] 1005 | let (nfds, fdsValue) = head pollResult
  302. 31.43 s [hatrace] | ^^^^
  303. 31.43 s [hatrace]
  304. 31.43 s [hatrace] test/HatraceSpec.hs:1008:38: warning: [GHC-63394] [-Wx-partial]
  305. 31.43 s [hatrace] In the use of ‘head’
  306. 31.43 s [hatrace] (imported from Prelude, but defined in GHC.Internal.List):
  307. 31.43 s [hatrace] "This is a partial function, it throws an error on empty lists. Use pattern matching, 'Data.List.uncons' or 'Data.Maybe.listToMaybe' instead. Consider refactoring to use "Data.List.NonEmpty"."
  308. 31.43 s [hatrace] |
  309. 31.43 s [hatrace] 1008 | System.Hatrace.Types.events (head fdsValue) `shouldSatisfy` ( \case
  310. 31.43 s [hatrace] | ^^^^
  311. 31.43 s [hatrace]
  312. 31.43 s [hatrace] test/HatraceSpec.hs:1033:42: warning: [GHC-63394] [-Wx-partial]
  313. 31.43 s [hatrace] In the use of ‘head’
  314. 31.43 s [hatrace] (imported from Prelude, but defined in GHC.Internal.List):
  315. 31.43 s [hatrace] "This is a partial function, it throws an error on empty lists. Use pattern matching, 'Data.List.uncons' or 'Data.Maybe.listToMaybe' instead. Consider refactoring to use "Data.List.NonEmpty"."
  316. 31.43 s [hatrace] |
  317. 31.43 s [hatrace] 1033 | let (fdsValue, SigSet sigmask) = head pollResult
  318. 31.43 s [hatrace] | ^^^^
  319. 31.43 s [hatrace]
  320. 35.00 s [hatrace] [2 of 2] Compiling Main ( test/Spec.hs, dist/build/hatrace-test/hatrace-test-tmp/Main.o )
  321. 35.32 s [hatrace] [3 of 3] Linking dist/build/hatrace-test/hatrace-test
  322. 36.56 s [hatrace] Preprocessing executable 'hatrace' for hatrace-0.1.0.0...
  323. 36.56 s [hatrace] Building executable 'hatrace' for hatrace-0.1.0.0...
  324. 36.62 s [hatrace] [1 of 1] Compiling Main ( app/Main.hs, dist/build/hatrace/hatrace-tmp/Main.o )
  325. 36.76 s [hatrace] [2 of 2] Linking dist/build/hatrace/hatrace
  326. 37.81 s [hatrace] buildPhase completed in 34 seconds
  327. 37.81 s [hatrace] Phase: checkPhase
  328. 38.08 s [hatrace] Running 1 test suites...
  329. 38.08 s [hatrace] Test suite hatrace-test: RUNNING...
  330. 38.08 s [hatrace]
  331. 38.09 s [hatrace] Hatrace
  332. 38.09 s [hatrace] traceCreateProcess
  333. 38.10 s [hatrace] hello
  334. 38.10 s [hatrace] does not crash for this echo process [✔]
  335. 38.10 s [hatrace] example-programs/hello-linux-i386.asm:20: warning: 32-bit absolute section-crossing relocation [-w+reloc-abs-dword]
  336. 38.14 s [hatrace] Hello, world!
  337. 38.14 s [hatrace] does not crash for hello.asm with 32-bit API [✔]
  338. 38.15 s [hatrace] example-programs/hello-linux-i386.asm:20: warning: 32-bit absolute section-crossing relocation [-w+reloc-abs-dword]
  339. 38.18 s [hatrace] Hello, world!
  340. 38.18 s [hatrace] does not crash for hello.asm real 32-bit [✔]
  341. 38.19 s [hatrace] example-programs/hello-linux-x86_64.asm:19: warning: 64-bit absolute section-crossing relocation [-w+reloc-abs-qword]
  342. 38.22 s [hatrace] Hello, world!
  343. 38.22 s [hatrace] does not crash for hello.asm with 64-bit API [✔]
  344. 38.36 s [hatrace] does not hang when the traced program segfaults [✔]
  345. 38.36 s [hatrace] sourceRawTraceForkExecvFullPathWithSink
  346. 38.36 s [hatrace] hello
  347. 38.36 s [hatrace] lets the process finish if the sink exits early [✔]
  348. 38.37 s [hatrace] Hello, world!
  349. 38.37 s [hatrace] allows obtaining all syscalls as a list for hello.asm [✔]
  350. 38.51 s [hatrace] write: Bad file descriptor
  351. 38.51 s [hatrace] shows return code and errno of a failing write() syscall [✔]
  352. 38.51 s [hatrace] subprocess tracing
  353. 38.52 s [hatrace] Hello, world!
  354. 38.52 s [hatrace] can trace 'bash -c ./hello' [✔]
  355. 38.52 s [hatrace] can handle the situation that the child doesn't wait for its children [‐]
  356. 38.52 s [hatrace] # PENDING: implement test with simple C program that doens't wait for a child
  357. 38.52 s [hatrace] can handle the situation that a child's child double-forks [‐]
  358. 38.52 s [hatrace] # PENDING: implement test with simple C program that has a child double-fork
  359. 38.52 s [hatrace] can handle Group-stop in multithreaded programs [‐]
  360. 38.52 s [hatrace] # PENDING: implement test with simple C program that uses multiple threads
  361. 38.52 s [hatrace] program inspection
  362. 38.68 s [hatrace] Writing 100 bytes atomically to example-programs-build/testfile
  363. 38.68 s [hatrace] Writing 100 bytes non-atomically to example-programs-build/testfile
  364. 38.68 s [hatrace] can point out that the difference in syscalls between atomic and non-atomic write is a rename [✔]
  365. 38.68 s [hatrace] program misbehaviour detection
  366. 38.70 s [hatrace] can show that SIGTERM at the right time results in cut-off files for non-atomically writing programs [✔]
  367. 38.78 s [hatrace] [1 of 2] Compiling Main ( example-programs/Hello.hs, example-programs-build/Main.o )
  368. 38.81 s [hatrace] [2 of 2] Linking example-programs-build/haskell-hello
  369. 39.16 s [hatrace]
  370. 39.16 s [hatrace] Running and then killing GHC; expect error messages below.
  371. 39.16 s [hatrace]
  372. 39.44 s [hatrace] [1 of 2] Compiling Main ( example-programs/Hello.hs, example-programs-build/Main.o )
  373. 39.86 s [hatrace] Observing write to relevant file: /build/hatrace-source/example-programs-build/Main.o.tmp; bytes: 342
  374. 39.86 s [hatrace] Observing write to relevant file: /build/hatrace-source/example-programs-build/Main.o.tmp; bytes: 202
  375. 39.86 s [hatrace] Observing write to relevant file: /build/hatrace-source/example-programs-build/Main.o.tmp; bytes: 267
  376. 39.86 s [hatrace] Observing write to relevant file: /build/hatrace-source/example-programs-build/Main.o.tmp; bytes: 48
  377. 39.86 s [hatrace] gcc: fatal error: Terminated signal terminated program as
  378. 39.86 s [hatrace] compilation terminated.
  379. 39.86 s [hatrace] <no location info>: error:
  380. 39.86 s [hatrace] `cc' failed in phase `Assembler'. (Exit code: 1)
  381. 39.86 s [hatrace]
  382. 39.87 s [hatrace]
  383. 39.87 s [hatrace] End of where error messages are expected.
  384. 39.87 s [hatrace]
  385. 39.94 s [hatrace] can be used to check whether GHC writes truncated object files or executables [✔]
  386. 39.94 s [hatrace] can be used to check whether programs handle EINTR correctly [‐]
  387. 39.94 s [hatrace] # PENDING: implement test that uses PTRACE_INTERRUPT in every syscall
  388. 39.95 s [hatrace] Writing 10 bytes atomically to /build/test-output1774-0
  389. 39.95 s [hatrace] observes atomic write in a program [✔]
  390. 39.97 s [hatrace] Writing 10 bytes non-atomically to /build/test-output1774-1
  391. 39.97 s [hatrace] catches non-atomic write in a program [✔]
  392. 39.97 s [hatrace] modifying syscalls
  393. 40.11 s [hatrace] hello can change syscall result to any error [✔]
  394. 40.14 s [hatrace] hello can change syscall result to any return value [✔]
  395. 40.14 s [hatrace] storable instances
  396. 40.14 s [hatrace] can correctly poke and peek SigSet [✔]
  397. 40.14 s [hatrace] deriveCIntRepresentable
  398. 40.14 s [hatrace] derived toCInt . fromCInt == id for FileAccessMode [✔]
  399. 40.14 s [hatrace] +++ OK, passed 100 tests.
  400. 40.14 s [hatrace] derived fromCInt . toCInt == id for FileAccessKnown [✔]
  401. 40.14 s [hatrace] +++ OK, passed 100 tests.
  402. 40.14 s [hatrace] memory management
  403. 40.30 s [hatrace] peekable trace source catches peeking error on enter [✔]
  404. 40.34 s [hatrace] In file included from /nix/store/fbbw928argckfii0j322346ihmllg7a7-glibc-2.42-61-dev/include/features.h:524,
  405. 40.34 s [hatrace] from /nix/store/fbbw928argckfii0j322346ihmllg7a7-glibc-2.42-61-dev/include/bits/libc-header-start.h:33,
  406. 40.34 s [hatrace] from /nix/store/fbbw928argckfii0j322346ihmllg7a7-glibc-2.42-61-dev/include/stdio.h:28,
  407. 40.34 s [hatrace] from example-programs/bad-read-pointer.c:1:
  408. 40.34 s [hatrace] In function ‘read’,
  409. 40.34 s [hatrace] inlined from ‘main’ at example-programs/bad-read-pointer.c:26:13:
  410. 40.34 s [hatrace] /nix/store/fbbw928argckfii0j322346ihmllg7a7-glibc-2.42-61-dev/include/bits/unistd.h:32:10: warning: ‘__read_alias’ writing 1 byte into a region of size 0 overflows the destination [-Wstringop-overflow=]
  411. 40.34 s [hatrace] 32 | return __glibc_fortify (read, __nbytes, sizeof (char),
  412. 40.34 s [hatrace] | ^~~~~~~~~~~~~~~
  413. 40.34 s [hatrace] In function ‘main’:
  414. 40.34 s [hatrace] cc1: note: destination object is likely at address zero
  415. 40.34 s [hatrace] /nix/store/fbbw928argckfii0j322346ihmllg7a7-glibc-2.42-61-dev/include/bits/unistd-decl.h:29:16: note: in a call to function ‘__read_alias’ declared with attribute ‘access (write_only, 2, 3)’
  416. 40.34 s [hatrace] 29 | extern ssize_t __REDIRECT_FORTIFY (__read_alias, (int __fd, void *__buf,
  417. 40.34 s [hatrace] | ^~~~~~~~~~~~~~~~~~
  418. 40.34 s [hatrace] In function ‘read’,
  419. 40.34 s [hatrace] inlined from ‘main’ at example-programs/bad-read-pointer.c:26:13:
  420. 40.34 s [hatrace] /nix/store/fbbw928argckfii0j322346ihmllg7a7-glibc-2.42-61-dev/include/bits/unistd.h:32:10: warning: ‘__read_chk’ writing 1 byte into a region of size 0 overflows the destination [-Wstringop-overflow=]
  421. 40.34 s [hatrace] 32 | return __glibc_fortify (read, __nbytes, sizeof (char),
  422. 40.34 s [hatrace] | ^~~~~~~~~~~~~~~
  423. 40.34 s [hatrace] In function ‘main’:
  424. 40.34 s [hatrace] cc1: note: destination object is likely at address zero
  425. 40.34 s [hatrace] In file included from /nix/store/fbbw928argckfii0j322346ihmllg7a7-glibc-2.42-61-dev/include/bits/unistd.h:23,
  426. 40.34 s [hatrace] from /nix/store/fbbw928argckfii0j322346ihmllg7a7-glibc-2.42-61-dev/include/unistd.h:1217,
  427. 40.34 s [hatrace] from example-programs/bad-read-pointer.c:3:
  428. 40.34 s [hatrace] /nix/store/fbbw928argckfii0j322346ihmllg7a7-glibc-2.42-61-dev/include/bits/unistd-decl.h:26:16: note: in a call to function ‘__read_chk’ declared with attribute ‘access (write_only, 2, 3)’
  429. 40.34 s [hatrace] 26 | extern ssize_t __read_chk (int __fd, void *__buf, size_t __nbytes,
  430. 40.34 s [hatrace] | ^~~~~~~~~~
  431. 40.47 s [hatrace] traceForkProcess is able to catch errors on exits [✔]
  432. 40.47 s [hatrace] per-syscall tests
  433. 40.47 s [hatrace] read
  434. 40.53 s [hatrace] has the right output for 'echo hello | cat' [✔]
  435. 40.53 s [hatrace] exit_group
  436. 40.53 s [hatrace] Syscall_exit_group is identified [✔]
  437. 40.53 s [hatrace] lseek
  438. 40.54 s [hatrace] wp : yes
  439. 40.54 s [hatrace] flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 ht syscall nx mmxext fxsr_opt pdpe1gb rdtscp lm rep_good nopl xtopology cpuid extd_apicid tsc_known_freq pni pclmulqdq ssse3 fma cx16 sse4_1 sse4_2 x2apic movbe popcnt tsc_deadline_timer aes xsave avx f16c rdrand hypervisor lahf_lm cmp_legacy svm cr8_legacy abm sse4a misalignsse 3dnowprefetch osvw perfctr_core ssbd ibrs ibpb stibp vmmcall fsgsbase tsc_adjust bmi1 avx2 smep bmi2 erms invpcid rdseed adx smap clflushopt clwb sha_ni xsaveopt xsavec xgetbv1 xsaves clzero xsaveerptr wbnoinvd arat npt lbrv nrip_save tsc_scale vmcb_clean flushbyasid pausefilter pfthreshold v_vmsave_vmload vgif umip pku ospke vaes vpclmulqdq rdpid overflow_recov succor fsrm
  440. 40.54 s [hatrace] bugs : sysret_ss_attrs spectre_v1 spectre_v2 spec_store_bypass srso ibpb_no_ret tsa
  441. 40.54 s [hatrace] bogomips : 6799.99
  442. 40.54 s [hatrace] TLB size : 1024 4K pages
  443. 40.54 s [hatrace] clflush size : 64
  444. 40.54 s [hatrace] cache_alignment : 64
  445. 40.54 s [hatrace] address sizes : 48 bits physical, 48 bits virtual
  446. 40.54 s [hatrace] power management:
  447. 40.54 s [hatrace]
  448. 40.54 s [hatrace] Syscall_lseek is identified [✔]
  449. 40.54 s [hatrace] execve
  450. 40.69 s [hatrace] Hello, world!
  451. 40.69 s [hatrace] shows the right execve results for './execve hello-linux-x86_64' [✔]
  452. 40.84 s [hatrace] Hello, world!
  453. 40.84 s [hatrace] shows the right execve results for the special case './execve-linux-null-envp hello-linux-x86_64' [✔]
  454. 40.84 s [hatrace] close
  455. 40.85 s [hatrace] seen at least for 1 file for 'cat /dev/null' [✔]
  456. 40.85 s [hatrace] openat
  457. 40.87 s [hatrace] Writing 10 bytes non-atomically to /build/test-output1774-2
  458. 40.87 s [hatrace] seen for a file we open for writing [✔]
  459. 40.87 s [hatrace] rename
  460. 40.88 s [hatrace] Writing 10 bytes atomically to /build/test-output1774-3
  461. 40.88 s [hatrace] seen for a file we do an atomic write to [✔]
  462. 40.88 s [hatrace] unlink
  463. 41.03 s [hatrace] occurs when we delete a file [✔]
  464. 41.03 s [hatrace] pipe
  465. 41.08 s [hatrace] foo
  466. 41.08 s [hatrace] seen when piping output in bash [✔]
  467. 41.08 s [hatrace] dup
  468. 41.09 s [hatrace] dup2 identified when a shell pipe gets used [✔]
  469. 41.24 s [hatrace] sees the correct dup3 arguments [✔]
  470. 41.24 s [hatrace] access
  471. 41.39 s [hatrace] seen when invoked in a program [✔]
  472. 41.39 s [hatrace] sockets
  473. 41.63 s [hatrace] seen when opening sockets [✔]
  474. 41.64 s [hatrace] seen when opening a socketpair [✔]
  475. 41.66 s [hatrace] seen when using sendto/recvfrom on a socketpair [✔]
  476. 41.66 s [hatrace] lstat
  477. 41.68 s [hatrace] File: /dev/null
  478. 41.68 s [hatrace] Size: 0 Blocks: 0 IO Block: 4096 character special file
  479. 41.68 s [hatrace] Device: 0,6 Inode: 4 Links: 1 Device type: 1,3
  480. 41.68 s [hatrace] Access: (0666/crw-rw-rw-) Uid: (65534/ nobody) Gid: (65534/ nogroup)
  481. 41.68 s [hatrace] Access: 2026-05-05 20:13:25.505000000 +0000
  482. 41.68 s [hatrace] Modify: 2026-05-05 20:13:25.505000000 +0000
  483. 41.68 s [hatrace] Change: 2026-05-05 20:13:25.505000000 +0000
  484. 41.68 s [hatrace] Birth: 2026-05-05 20:13:22.625204681 +0000
  485. 41.68 s [hatrace] seen called by stat executable [‐]
  486. 41.68 s [hatrace] # PENDING: stat uses statx for this path, which is not yet handled by hatrace
  487. 41.68 s [hatrace] mmap
  488. 41.82 s [hatrace] #include <sys/mman.h>
  489. 41.82 s [hatrace] #include <sys/stat.h>
  490. 41.82 s [hatrace] #include <sys/syscall.h>
  491. 41.82 s [hatrace] #include <stdio.h>
  492. 41.82 s [hatrace] #include <fc1
  493. 41.82 s [hatrace] sees the correct arguments [✔]
  494. 41.82 s [hatrace] munmap
  495. 41.83 s [hatrace] #include <sys/mman.h>
  496. 41.83 s [hatrace] #include <sys/stat.h>
  497. 41.83 s [hatrace] #include <sys/syscall.h>
  498. 41.83 s [hatrace] #include <stdio.h>
  499. 41.83 s [hatrace] #include <fc1
  500. 41.83 s [hatrace] sees called by mmap-syscall executable [✔]
  501. 41.83 s [hatrace] time
  502. 41.98 s [hatrace] seen called by trigger-time executable [✔]
  503. 41.98 s [hatrace] getcwd
  504. 42.13 s [hatrace] /build/hatrace-source
  505. 42.13 s [hatrace] correctly get the current directory [✔]
  506. 42.13 s [hatrace] brk
  507. 42.29 s [hatrace] has correct output after changing program break [✔]
  508. 42.29 s [hatrace] symlink
  509. 42.34 s [hatrace] seen exactly once for 'ln -s tempfile tempfilesymlink' [✔]
  510. 42.34 s [hatrace] symlinkat
  511. 42.48 s [hatrace] seen exactly once for './symlinkat [✔]
  512. 42.48 s [hatrace] poll
  513. 42.63 s [hatrace] detects correctly all events [✔]
  514. 42.63 s [hatrace] ppoll
  515. 42.79 s [hatrace] detects correctly all events and sigmask [✔]
  516. 42.79 s [hatrace] arch_prctl
  517. 42.94 s [hatrace] FS: 4bb3c0
  518. 42.94 s [hatrace] seen ARCH_GET_FS used by example executable [✔]
  519. 42.94 s [hatrace] set_tid_address
  520. 43.08 s [hatrace] TID: 2740
  521. 43.08 s [hatrace] seen set_tid_address used by example executable [✔]
  522. 43.08 s [hatrace] sysinfo
  523. 43.22 s [hatrace] load avg 1 minute: 803.47% CPU
  524. 43.22 s [hatrace] load avg 5 minutes: 603.17% CPU
  525. 43.22 s [hatrace] load avg 15 minuites: 304.64% CPU
  526. 43.22 s [hatrace] seen sysinfo used by example executable [✔]
  527. 43.22 s [hatrace] madvise
  528. 43.37 s [hatrace] seen madvise used by example executable [✔]
  529. 43.37 s [hatrace] mprotect
  530. 43.52 s [hatrace] seen mprotect used by example executable [✔]
  531. 43.52 s [hatrace] sched_yield
  532. 43.65 s [hatrace] seen sched_yield used by example executable [✔]
  533. 43.65 s [hatrace] kill
  534. 43.78 s [hatrace] seen kill used by example executable [✔]
  535. 43.78 s [hatrace] getuid
  536. 43.90 s [hatrace] getuid() => 1000
  537. 43.90 s [hatrace] getgid() => 100
  538. 43.90 s [hatrace] geteuid() => 1000
  539. 43.90 s [hatrace] getegid() => 100
  540. 43.90 s [hatrace] should return the current user id [✔]
  541. 43.90 s [hatrace] getgid
  542. 43.92 s [hatrace] getuid() => 1000
  543. 43.92 s [hatrace] getgid() => 100
  544. 43.92 s [hatrace] geteuid() => 1000
  545. 43.92 s [hatrace] getegid() => 100
  546. 43.92 s [hatrace] should return the current group id [✔]
  547. 43.92 s [hatrace] geteuid
  548. 43.93 s [hatrace] getuid() => 1000
  549. 43.93 s [hatrace] getgid() => 100
  550. 43.93 s [hatrace] geteuid() => 1000
  551. 43.93 s [hatrace] getegid() => 100
  552. 43.93 s [hatrace] should return the current effective user id [✔]
  553. 43.93 s [hatrace] getegid
  554. 43.94 s [hatrace] getuid() => 1000
  555. 43.94 s [hatrace] getgid() => 100
  556. 43.94 s [hatrace] geteuid() => 1000
  557. 43.94 s [hatrace] getegid() => 100
  558. 43.94 s [hatrace] should return the current effective group id [✔]
  559. 43.94 s [hatrace] clone
  560. 43.97 s [hatrace] seen clone from a shell command group [✔]
  561. 43.97 s [hatrace] prlimit64
  562. 44.01 s [hatrace] seen prlimit64 from a shell command ulimit [✔]
  563. 44.01 s [hatrace] chdir
  564. 44.39 s [hatrace] occurs when we change the current directory [✔]
  565. 44.39 s [hatrace] mkdir
  566. 44.39 s [hatrace] "/build/hatrace-test-mkdir-3a2b196f0db47a9e/mkdir-test"
  567. 44.52 s [hatrace] occurs when we create a directory [✔]
  568. 44.52 s [hatrace] rmdir
  569. 44.66 s [hatrace] occurs when we delete a directory [✔]
  570. 44.66 s [hatrace] truncate
  571. 44.80 s [hatrace] occurs when we truncate a file [✔]
  572. 44.80 s [hatrace] ftruncate
  573. 44.95 s [hatrace] occurs when we ftruncate a file [✔]
  574. 44.95 s [hatrace]
  575. 44.95 s [hatrace] Finished in 6.8610 seconds
  576. 44.95 s [hatrace] 69 examples, 0 failures, 5 pending
  577. 44.95 s [hatrace] Test suite hatrace-test: PASS
  578. 44.95 s [hatrace] Test suite logged to: dist/test/hatrace-0.1.0.0-hatrace-test.log
  579. 44.95 s [hatrace] 1 of 1 test suites (1 of 1 test cases) passed.
  580. 44.96 s [hatrace] Phase: haddockPhase
  581. 44.97 s [hatrace] Phase: installPhase
  582. 45.03 s [hatrace] Installing library in /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/lib/ghc-9.10.3/lib/x86_64-linux-ghc-9.10.3-c0c3/hatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp
  583. 45.34 s [hatrace] Installing executable hatrace in /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/bin
  584. 45.39 s [hatrace] Warning: The directory
  585. 45.39 s [hatrace] /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/bin is not in the
  586. 45.39 s [hatrace] system search path.
  587. 45.45 s [hatrace] Registering library for hatrace-0.1.0.0...
  588. 45.51 s [hatrace] Phase: fixupPhase
  589. 45.52 s [hatrace] shrinking RPATHs of ELF executables and libraries in /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0
  590. 45.52 s [hatrace] shrinking /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/bin/hatrace
  591. 45.53 s [hatrace] shrinking /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/lib/ghc-9.10.3/lib/x86_64-linux-ghc-9.10.3-c0c3/libHShatrace-0.1.0.0-HS9Jlu6IieEA3t29Z9Jdjp-ghc9.10.3.so
  592. 45.56 s [hatrace] checking for references to /build/ in /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0...
  593. 45.58 s [hatrace] patching script interpreter paths in /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0
  594. 45.59 s [hatrace] stripping (with command strip and flags -S -p) in /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/lib /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0/bin
  595. 46.02 s [post-build-hook] Uploading to the NixCI staging cache: /nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0
  596. 46.06 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  597. 46.07 s [post-build-hook] copying 1 paths...
  598. 46.07 s [post-build-hook] copying path '/nix/store/i8q39vc11q8cqjniq3rjm5aax65m5m1c-hatrace-0.1.0.0' to 'https://cache.staging.nix-ci.com'...
  599. 47.09 s [post-build-hook] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
  600. 47.34 s [post-build-hook] copying 1 paths...
  601. 47.34 s [post-build-hook] copying path '/nix/store/051f4pplnzcilgiwfdp51aswmcjbkqxb-hatrace-0.1.0.0.drv' to 'https://cache.staging.nix-ci.com'...
  602. 47.52 s Progress: 1 of 2 built, 15 of 15 downloaded from cache
Tail Raw Logs