Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

error executing the statement: database is locked #38

Closed
kalbasit opened this issue Dec 6, 2024 · 2 comments · Fixed by #41
Closed

error executing the statement: database is locked #38

kalbasit opened this issue Dec 6, 2024 · 2 comments · Fixed by #41

Comments

@kalbasit
Copy link
Owner

kalbasit commented Dec 6, 2024

I got this error:

at 16:32:21 ❯ nix copy --to https://nix-cache.cluster.ifcsn0.nasreddine.com $(readlink -f result)
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/n6wnf7gar5yjyvdmy418xg0vzz3qwlwl.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 293 ms

And in the logs, I see

[
  {
    "cmd": "serve",
    "error": "error performing the request: Get \"https://cache.nixos.org/n6wnf7gar5yjyvdmy418xg0vzz3qwlwl.narinfo\": context canceled",
    "hostname": "cache.nixos.org",
    "lvl": "eror",
    "msg": "error fetching the narInfo from upstream",
    "t": "2024-12-06T00:32:43.158167435Z"
  },
  {
    "cmd": "serve",
    "error": "error performing the request: Get \"https://nix-community.cachix.org/n6wnf7gar5yjyvdmy418xg0vzz3qwlwl.narinfo\": context canceled",
    "hostname": "nix-community.cachix.org",
    "lvl": "eror",
    "msg": "error fetching the narInfo from upstream",
    "t": "2024-12-06T00:32:43.160973587Z"
  },
  {
    "bytes": 9,
    "cmd": "serve",
    "elapsed": "82.643148ms",
    "from": "10.42.3.0",
    "lvl": "info",
    "msg": "GET /n6wnf7gar5yjyvdmy418xg0vzz3qwlwl.narinfo",
    "reqID": "nix-cache-0/MAcw76sx15-001204",
    "status": 404,
    "t": "2024-12-06T00:32:43.164670327Z"
  },
  {
    "cmd": "serve",
    "hash": "n6wnf7gar5yjyvdmy418xg0vzz3qwlwl",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/1ym5z90xngy9546rf0j4bzdj9fklibd0l0fvff6rijj1nms0kr06.nar.xz",
    "t": "2024-12-06T00:36:21.691651729Z"
  },
  {
    "cmd": "serve",
    "error": "error inserting the narinfo record for hash \"n6wnf7gar5yjyvdmy418xg0vzz3qwlwl\" in the database: error executing the statement: database is locked",
    "hash": "n6wnf7gar5yjyvdmy418xg0vzz3qwlwl",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T00:36:26.695536611Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.647965883s",
    "from": "10.42.0.0",
    "lvl": "info",
    "msg": "GET /n6wnf7gar5yjyvdmy418xg0vzz3qwlwl.narinfo",
    "reqID": "nix-cache-0/MAcw76sx15-002314",
    "status": 500,
    "t": "2024-12-06T00:36:26.695559501Z"
  },
  {
    "bytes": 736,
    "cmd": "serve",
    "elapsed": "955.77µs",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /n6wnf7gar5yjyvdmy418xg0vzz3qwlwl.narinfo",
    "reqID": "nix-cache-0/MAcw76sx15-002658",
    "status": 200,
    "t": "2024-12-06T00:36:27.013952932Z"
  }
]
@kalbasit

This comment was marked as outdated.

@kalbasit
Copy link
Owner Author

kalbasit commented Dec 6, 2024

Setting db.SetMaxOpenConns(1) in #39 did not help, I still see errors.

Another set of errors
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/bz79drpiailx0l0mjnaxm314y3v827di.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 285 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/cb2qawi5mas118p6h2z3ypwzrlcznxhy.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 272 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/cbcg8wxyq8bmjqk188rvlq1nkikqi3xi.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 350 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/34yxqjrl57lsilmyn9fi6wd9ck5zr1ip.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 278 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/qi7r6xkqadvwk25wv29q52cb9cy7dz8j.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 348 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/sgm1mmaicnhn4n820gpvhjcypxlgxqln.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 259 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/a11xgij5z0r1zd66kvzgsg9sh230fh26.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 281 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/ahf5q9q77r9c35wccwgpdam2avsvhbfh.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 309 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/am8zvxbsgm1ada01knpzqh641jdgk72f.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 283 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/h5zcck3iip9nnslh1avha5afpawadfj2.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 305 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/ibhsr9sdpl3lyqdnjvsxlhiqgzypfpwy.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 287 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/j7fp52spki1k9vrkrj30gpr7gvdig9an.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 314 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/kilqvm54cqxn10kj2rsdlqc4iai3i5br.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 258 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/kkjxynwcxckkp290vsdilii6g2j6sqkb.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 351 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/kp08lmhjfw4qsg3n3gzkndr96v2bi09h.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 290 ms
warning: error: unable to download 'https://nix-cache.cluster.ifcsn0.nasreddine.com/n7ib7sqk88npr6lzqwyygg5sfhdniz7c.narinfo': HTTP error 500

       response body:

       Internal Server Error; retrying in 306 ms
warning: error: unable to upload 'https://nix-cache.cluster.ifcsn0.nasreddine.com/g28l15mbdbig59n102zd0ardsfisiw32.narinfo': HTTP error 500

       response body:

       Internal Server Errorerror inserting the nar record in the database: error executing the statement: UNIQUE constraint failed: nars.hash; retrying in 307 ms
warning: error: unable to upload 'https://nix-cache.cluster.ifcsn0.nasreddine.com/g28l15mbdbig59n102zd0ardsfisiw32.narinfo': Failed to open/read local data from file/application (26)

       response body:

       ; retrying in 695 ms
warning: error: unable to upload 'https://nix-cache.cluster.ifcsn0.nasreddine.com/g28l15mbdbig59n102zd0ardsfisiw32.narinfo': Failed to open/read local data from file/application (26)

       response body:

       ; retrying in 1239 ms
warning: error: unable to upload 'https://nix-cache.cluster.ifcsn0.nasreddine.com/g28l15mbdbig59n102zd0ardsfisiw32.narinfo': Failed to open/read local data from file/application (26)

       response body:

       ; retrying in 2719 ms
error: while uploading to HTTP binary cache at 'https://nix-cache.cluster.ifcsn0.nasreddine.com': error: unable to upload 'https://nix-cache.cluster.ifcsn0.nasreddine.com/g28l15mbdbig59n102zd0ardsfisiw32.narinfo': Failed to open/read local data from file/application (26)

              response body:

logs associated with the errors above

[
  {
    "cmd": "serve",
    "hash": "34yxqjrl57lsilmyn9fi6wd9ck5zr1ip",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/0q5i8swqxai1s4lxprppcigbj497kaz7r7f4fxf5dqcynr8hdn1h.nar.xz",
    "t": "2024-12-06T02:03:25.618178332Z"
  },
  {
    "bytes": 1131,
    "cmd": "serve",
    "elapsed": "636.999672ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /34yxqjrl57lsilmyn9fi6wd9ck5zr1ip.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000225",
    "status": 200,
    "t": "2024-12-06T02:03:26.131899246Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "34yxqjrl57lsilmyn9fi6wd9ck5zr1ip",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.208585601Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.121932598s",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /34yxqjrl57lsilmyn9fi6wd9ck5zr1ip.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001178",
    "status": 500,
    "t": "2024-12-06T02:06:07.208683115Z"
  },
  {
    "bytes": 1131,
    "cmd": "serve",
    "elapsed": "33.584547ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /34yxqjrl57lsilmyn9fi6wd9ck5zr1ip.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001709",
    "status": 200,
    "t": "2024-12-06T02:06:07.593013802Z"
  },
  {
    "cmd": "serve",
    "hash": "a11xgij5z0r1zd66kvzgsg9sh230fh26",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/0blb57yf2h0ak5fbc5ffzfqb1hisqwwlm94g8p5kqwd4p8rk9lih.nar.xz",
    "t": "2024-12-06T02:03:31.462261309Z"
  },
  {
    "bytes": 813,
    "cmd": "serve",
    "elapsed": "279.979217ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /a11xgij5z0r1zd66kvzgsg9sh230fh26.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000649",
    "status": 200,
    "t": "2024-12-06T02:03:31.588973185Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "a11xgij5z0r1zd66kvzgsg9sh230fh26",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.209203151Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.034836927s",
    "from": "10.42.0.0",
    "lvl": "info",
    "msg": "GET /a11xgij5z0r1zd66kvzgsg9sh230fh26.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001403",
    "status": 500,
    "t": "2024-12-06T02:06:07.209230251Z"
  },
  {
    "bytes": 813,
    "cmd": "serve",
    "elapsed": "74.098723ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /a11xgij5z0r1zd66kvzgsg9sh230fh26.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001708",
    "status": 200,
    "t": "2024-12-06T02:06:07.633520504Z"
  },
  {
    "cmd": "serve",
    "hash": "ahf5q9q77r9c35wccwgpdam2avsvhbfh",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/0b13b1rk6p18jfk6ljgx0cai1zz4g3qs6qcy55nb2v3bn4kzm3j7.nar.xz",
    "t": "2024-12-06T02:03:30.747472237Z"
  },
  {
    "bytes": 676,
    "cmd": "serve",
    "elapsed": "198.999675ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /ahf5q9q77r9c35wccwgpdam2avsvhbfh.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000616",
    "status": 200,
    "t": "2024-12-06T02:03:30.761005115Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "ahf5q9q77r9c35wccwgpdam2avsvhbfh",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.209336649Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.033504359s",
    "from": "10.42.0.0",
    "lvl": "info",
    "msg": "GET /ahf5q9q77r9c35wccwgpdam2avsvhbfh.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001408",
    "status": 500,
    "t": "2024-12-06T02:06:07.209366609Z"
  },
  {
    "bytes": 676,
    "cmd": "serve",
    "elapsed": "34.963341ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /ahf5q9q77r9c35wccwgpdam2avsvhbfh.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001715",
    "status": 200,
    "t": "2024-12-06T02:06:07.621806837Z"
  },
  {
    "cmd": "serve",
    "hash": "am8zvxbsgm1ada01knpzqh641jdgk72f",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/1a8416cmvskjz88nvwpxkwafd55mc1b6mwvjkyvicpsdbhp1266k.nar.xz",
    "t": "2024-12-06T02:03:25.032227655Z"
  },
  {
    "bytes": 1823,
    "cmd": "serve",
    "elapsed": "349.602863ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /am8zvxbsgm1ada01knpzqh641jdgk72f.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000182",
    "status": 200,
    "t": "2024-12-06T02:03:25.171082281Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "am8zvxbsgm1ada01knpzqh641jdgk72f",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.208135906Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.033825959s",
    "from": "10.42.0.0",
    "lvl": "info",
    "msg": "GET /am8zvxbsgm1ada01knpzqh641jdgk72f.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001401",
    "status": 500,
    "t": "2024-12-06T02:06:07.208211131Z"
  },
  {
    "bytes": 1823,
    "cmd": "serve",
    "elapsed": "361.403424ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /am8zvxbsgm1ada01knpzqh641jdgk72f.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001711",
    "status": 200,
    "t": "2024-12-06T02:06:07.923965569Z"
  },
  {
    "cmd": "serve",
    "hash": "bz79drpiailx0l0mjnaxm314y3v827di",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/11c7sjmh7wc1p5ab2x6v64n6nsskv82y01n9nj7vbcapqrn95ga3.nar.xz",
    "t": "2024-12-06T02:03:24.538327589Z"
  },
  {
    "bytes": 689,
    "cmd": "serve",
    "elapsed": "140.914066ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /bz79drpiailx0l0mjnaxm314y3v827di.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000148",
    "status": 200,
    "t": "2024-12-06T02:03:24.54812686Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "bz79drpiailx0l0mjnaxm314y3v827di",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.208528373Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.07518392s",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /bz79drpiailx0l0mjnaxm314y3v827di.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001248",
    "status": 500,
    "t": "2024-12-06T02:06:07.208903956Z"
  },
  {
    "bytes": 689,
    "cmd": "serve",
    "elapsed": "261.13394ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /bz79drpiailx0l0mjnaxm314y3v827di.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001710",
    "status": 200,
    "t": "2024-12-06T02:06:07.823572889Z"
  },
  {
    "cmd": "serve",
    "hash": "cb2qawi5mas118p6h2z3ypwzrlcznxhy",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/0q78pjn4cgh09q41kx9ddyc9q04w0xbl2l520ahrifkmricxny5w.nar.xz",
    "t": "2024-12-06T02:03:31.460880877Z"
  },
  {
    "bytes": 850,
    "cmd": "serve",
    "elapsed": "163.414844ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /cb2qawi5mas118p6h2z3ypwzrlcznxhy.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000651",
    "status": 200,
    "t": "2024-12-06T02:03:31.472465908Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "cb2qawi5mas118p6h2z3ypwzrlcznxhy",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.208793032Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.053344576s",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /cb2qawi5mas118p6h2z3ypwzrlcznxhy.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001356",
    "status": 500,
    "t": "2024-12-06T02:06:07.209491403Z"
  },
  {
    "bytes": 850,
    "cmd": "serve",
    "elapsed": "25.041986ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /cb2qawi5mas118p6h2z3ypwzrlcznxhy.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001707",
    "status": 200,
    "t": "2024-12-06T02:06:07.579140425Z"
  },
  {
    "cmd": "serve",
    "hash": "cbcg8wxyq8bmjqk188rvlq1nkikqi3xi",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/00d2fi8c3lpydicpw57xdslvgip9v4yj8p2apv1fylfagagbq3ml.nar.xz",
    "t": "2024-12-06T02:03:28.781160092Z"
  },
  {
    "bytes": 1001,
    "cmd": "serve",
    "elapsed": "141.583866ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /cbcg8wxyq8bmjqk188rvlq1nkikqi3xi.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000469",
    "status": 200,
    "t": "2024-12-06T02:03:28.795508455Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "cbcg8wxyq8bmjqk188rvlq1nkikqi3xi",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.209235424Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.050198031s",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /cbcg8wxyq8bmjqk188rvlq1nkikqi3xi.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001376",
    "status": 500,
    "t": "2024-12-06T02:06:07.209267292Z"
  },
  {
    "bytes": 1001,
    "cmd": "serve",
    "elapsed": "13.965134ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /cbcg8wxyq8bmjqk188rvlq1nkikqi3xi.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001720",
    "status": 200,
    "t": "2024-12-06T02:06:07.643851196Z"
  },
  {
    "bytes": 9,
    "cmd": "serve",
    "elapsed": "521.305226ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /g28l15mbdbig59n102zd0ardsfisiw32.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001530",
    "status": 404,
    "t": "2024-12-06T02:06:02.761071834Z"
  },
  {
    "cmd": "serve",
    "hash": "g28l15mbdbig59n102zd0ardsfisiw32",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/1s2r0923zn74f9rja84r4mw838p3bnaavvgka9kkks7pxf0y2jzn.nar.xz",
    "t": "2024-12-06T02:06:25.513794035Z"
  },
  {
    "cmd": "serve",
    "error": "error inserting the nar record in the database: error executing the statement: UNIQUE constraint failed: nars.hash",
    "hash": "g28l15mbdbig59n102zd0ardsfisiw32",
    "lvl": "eror",
    "msg": "error putting the NAR in cache",
    "t": "2024-12-06T02:06:25.514707197Z"
  },
  {
    "bytes": 406,
    "cmd": "serve",
    "elapsed": "1.490372ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "PUT /g28l15mbdbig59n102zd0ardsfisiw32.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001846",
    "status": 500,
    "t": "2024-12-06T02:06:25.514763521Z"
  },
  {
    "cmd": "serve",
    "hash": "h5zcck3iip9nnslh1avha5afpawadfj2",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/0f3rvhkh475llb0lkn7p2g2r7bjcf8az8a8p1114yv9ib5qyb6iq.nar.xz",
    "t": "2024-12-06T02:03:27.530727403Z"
  },
  {
    "bytes": 984,
    "cmd": "serve",
    "elapsed": "150.406405ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /h5zcck3iip9nnslh1avha5afpawadfj2.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000354",
    "status": 200,
    "t": "2024-12-06T02:03:27.535224738Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "h5zcck3iip9nnslh1avha5afpawadfj2",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.208511327Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.026424797s",
    "from": "10.42.0.0",
    "lvl": "info",
    "msg": "GET /h5zcck3iip9nnslh1avha5afpawadfj2.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001434",
    "status": 500,
    "t": "2024-12-06T02:06:07.20854762Z"
  },
  {
    "bytes": 984,
    "cmd": "serve",
    "elapsed": "18.490128ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /h5zcck3iip9nnslh1avha5afpawadfj2.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001714",
    "status": 200,
    "t": "2024-12-06T02:06:07.605348926Z"
  },
  {
    "cmd": "serve",
    "hash": "ibhsr9sdpl3lyqdnjvsxlhiqgzypfpwy",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/147l59wdsmcf854fjnnh0dzya7ijzwk6yln2ka7nkp316vjyjz1k.nar.xz",
    "t": "2024-12-06T02:03:30.69825207Z"
  },
  {
    "bytes": 803,
    "cmd": "serve",
    "elapsed": "406.175909ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /ibhsr9sdpl3lyqdnjvsxlhiqgzypfpwy.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000609",
    "status": 200,
    "t": "2024-12-06T02:03:30.930221731Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "ibhsr9sdpl3lyqdnjvsxlhiqgzypfpwy",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.209107727Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.034538125s",
    "from": "10.42.0.0",
    "lvl": "info",
    "msg": "GET /ibhsr9sdpl3lyqdnjvsxlhiqgzypfpwy.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001405",
    "status": 500,
    "t": "2024-12-06T02:06:07.209140092Z"
  },
  {
    "bytes": 803,
    "cmd": "serve",
    "elapsed": "204.354979ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /ibhsr9sdpl3lyqdnjvsxlhiqgzypfpwy.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001712",
    "status": 200,
    "t": "2024-12-06T02:06:07.772597634Z"
  },
  {
    "cmd": "serve",
    "hash": "j7fp52spki1k9vrkrj30gpr7gvdig9an",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/0nqnx7giq15kpfcdwvr2rfb3vwslc6j8jqpzhsm9jnh3dgzgcml3.nar.xz",
    "t": "2024-12-06T02:03:23.793658419Z"
  },
  {
    "bytes": 658,
    "cmd": "serve",
    "elapsed": "64.497706ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /j7fp52spki1k9vrkrj30gpr7gvdig9an.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000106",
    "status": 200,
    "t": "2024-12-06T02:03:23.800805287Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "j7fp52spki1k9vrkrj30gpr7gvdig9an",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.208958788Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.023068885s",
    "from": "10.42.0.0",
    "lvl": "info",
    "msg": "GET /j7fp52spki1k9vrkrj30gpr7gvdig9an.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001443",
    "status": 500,
    "t": "2024-12-06T02:06:07.208987968Z"
  },
  {
    "bytes": 658,
    "cmd": "serve",
    "elapsed": "115.470883ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /j7fp52spki1k9vrkrj30gpr7gvdig9an.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001716",
    "status": 200,
    "t": "2024-12-06T02:06:07.711506213Z"
  },
  {
    "cmd": "serve",
    "hash": "kilqvm54cqxn10kj2rsdlqc4iai3i5br",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/0cj34qv36xqwa7wcpz54nd8pz7n2xm771cdzbjv786sipwdwv7a9.nar.xz",
    "t": "2024-12-06T02:03:25.617769746Z"
  },
  {
    "bytes": 648,
    "cmd": "serve",
    "elapsed": "206.465746ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /kilqvm54cqxn10kj2rsdlqc4iai3i5br.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000226",
    "status": 200,
    "t": "2024-12-06T02:03:25.703059085Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "kilqvm54cqxn10kj2rsdlqc4iai3i5br",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.208253898Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.023447718s",
    "from": "10.42.0.0",
    "lvl": "info",
    "msg": "GET /kilqvm54cqxn10kj2rsdlqc4iai3i5br.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001438",
    "status": 500,
    "t": "2024-12-06T02:06:07.208285205Z"
  },
  {
    "bytes": 648,
    "cmd": "serve",
    "elapsed": "119.82806ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /kilqvm54cqxn10kj2rsdlqc4iai3i5br.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001706",
    "status": 200,
    "t": "2024-12-06T02:06:07.657546294Z"
  },
  {
    "cmd": "serve",
    "hash": "kkjxynwcxckkp290vsdilii6g2j6sqkb",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/0fbzdzyiy5lflw4vymgjfxh4i7blkryv68ixfxhndfiz3jwxjwgj.nar.xz",
    "t": "2024-12-06T02:03:29.684417795Z"
  },
  {
    "bytes": 1143,
    "cmd": "serve",
    "elapsed": "148.606939ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /kkjxynwcxckkp290vsdilii6g2j6sqkb.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000539",
    "status": 200,
    "t": "2024-12-06T02:03:29.688453988Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "kkjxynwcxckkp290vsdilii6g2j6sqkb",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.209214164Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.034907232s",
    "from": "10.42.0.0",
    "lvl": "info",
    "msg": "GET /kkjxynwcxckkp290vsdilii6g2j6sqkb.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001400",
    "status": 500,
    "t": "2024-12-06T02:06:07.209250297Z"
  },
  {
    "bytes": 1143,
    "cmd": "serve",
    "elapsed": "118.199754ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /kkjxynwcxckkp290vsdilii6g2j6sqkb.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001719",
    "status": 200,
    "t": "2024-12-06T02:06:07.748039728Z"
  },
  {
    "cmd": "serve",
    "hash": "kp08lmhjfw4qsg3n3gzkndr96v2bi09h",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/0zvb8i2jzfkqbr3kqns7zvljgw7wbbk269mqplw4p8z7h4qgq5l6.nar.xz",
    "t": "2024-12-06T02:03:28.567007762Z"
  },
  {
    "bytes": 654,
    "cmd": "serve",
    "elapsed": "140.99493ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /kp08lmhjfw4qsg3n3gzkndr96v2bi09h.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000457",
    "status": 200,
    "t": "2024-12-06T02:03:28.574199623Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "kp08lmhjfw4qsg3n3gzkndr96v2bi09h",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.208552024Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.026914968s",
    "from": "10.42.0.0",
    "lvl": "info",
    "msg": "GET /kp08lmhjfw4qsg3n3gzkndr96v2bi09h.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001433",
    "status": 500,
    "t": "2024-12-06T02:06:07.208805889Z"
  },
  {
    "bytes": 654,
    "cmd": "serve",
    "elapsed": "155.579853ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /kp08lmhjfw4qsg3n3gzkndr96v2bi09h.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001713",
    "status": 200,
    "t": "2024-12-06T02:06:07.724016423Z"
  },
  {
    "cmd": "serve",
    "hash": "n7ib7sqk88npr6lzqwyygg5sfhdniz7c",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/02ynnyjalsvfs0xpmsq0bbv0kj6zvf7im5ivim2fmpgi3wsx46qh.nar.xz",
    "t": "2024-12-06T02:03:28.447986066Z"
  },
  {
    "bytes": 1095,
    "cmd": "serve",
    "elapsed": "292.573766ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /n7ib7sqk88npr6lzqwyygg5sfhdniz7c.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000445",
    "status": 200,
    "t": "2024-12-06T02:03:28.591104009Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "n7ib7sqk88npr6lzqwyygg5sfhdniz7c",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.308177368Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.116680657s",
    "from": "10.42.0.0",
    "lvl": "info",
    "msg": "GET /n7ib7sqk88npr6lzqwyygg5sfhdniz7c.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001462",
    "status": 500,
    "t": "2024-12-06T02:06:07.30820909Z"
  },
  {
    "bytes": 1095,
    "cmd": "serve",
    "elapsed": "55.298847ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /n7ib7sqk88npr6lzqwyygg5sfhdniz7c.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001717",
    "status": 200,
    "t": "2024-12-06T02:06:07.680043709Z"
  },
  {
    "cmd": "serve",
    "hash": "qi7r6xkqadvwk25wv29q52cb9cy7dz8j",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/1zhs37awlz1mrvhbb9b7l0wik7d6qskhn68vjaq6sipx88kp6fnz.nar.xz",
    "t": "2024-12-06T02:03:26.583029884Z"
  },
  {
    "bytes": 631,
    "cmd": "serve",
    "elapsed": "176.597336ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /qi7r6xkqadvwk25wv29q52cb9cy7dz8j.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000293",
    "status": 200,
    "t": "2024-12-06T02:03:26.622916867Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "qi7r6xkqadvwk25wv29q52cb9cy7dz8j",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.208062442Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.051484548s",
    "from": "10.42.0.0",
    "lvl": "info",
    "msg": "GET /qi7r6xkqadvwk25wv29q52cb9cy7dz8j.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001362",
    "status": 500,
    "t": "2024-12-06T02:06:07.208124891Z"
  },
  {
    "bytes": 631,
    "cmd": "serve",
    "elapsed": "68.330168ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /qi7r6xkqadvwk25wv29q52cb9cy7dz8j.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001718",
    "status": 200,
    "t": "2024-12-06T02:06:07.698062461Z"
  },
  {
    "cmd": "serve",
    "hash": "sgm1mmaicnhn4n820gpvhjcypxlgxqln",
    "lvl": "info",
    "msg": "storing narinfo and nar record in the database",
    "nar-url": "nar/1n0zfy071gjl3dbblczhx28m5kip6qcp8rcnfk315fbjvz2fm0vb.nar.xz",
    "t": "2024-12-06T02:03:31.459395105Z"
  },
  {
    "bytes": 809,
    "cmd": "serve",
    "elapsed": "230.342396ms",
    "from": "10.42.1.36",
    "lvl": "info",
    "msg": "GET /sgm1mmaicnhn4n820gpvhjcypxlgxqln.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-000639",
    "status": 200,
    "t": "2024-12-06T02:03:31.538571403Z"
  },
  {
    "cmd": "serve",
    "error": "error touching the narinfo record: error executing the statement: database is locked",
    "hash": "sgm1mmaicnhn4n820gpvhjcypxlgxqln",
    "lvl": "eror",
    "msg": "error fetching the narinfo",
    "t": "2024-12-06T02:06:07.209256432Z"
  },
  {
    "bytes": 21,
    "cmd": "serve",
    "elapsed": "5.068229567s",
    "from": "10.42.0.0",
    "lvl": "info",
    "msg": "GET /sgm1mmaicnhn4n820gpvhjcypxlgxqln.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001280",
    "status": 500,
    "t": "2024-12-06T02:06:07.209289931Z"
  },
  {
    "bytes": 809,
    "cmd": "serve",
    "elapsed": "14.628724ms",
    "from": "10.42.0.1",
    "lvl": "info",
    "msg": "GET /sgm1mmaicnhn4n820gpvhjcypxlgxqln.narinfo",
    "reqID": "nix-cache-0/m6lm9fOruz-001705",
    "status": 200,
    "t": "2024-12-06T02:06:07.552355325Z"
  }
]

kalbasit added a commit that referenced this issue Dec 6, 2024
Getting a `database is locked` error. This [upstream
issue](mattn/go-sqlite3#274 (comment))
suggested limiting the number of connection to one and it seems to have
helped. It's not idea, but I need to move on.

ref #38
kalbasit added a commit that referenced this issue Dec 6, 2024
To avoid many of the `database locked` errors I am seeing, lower the
update frequency to once every 5 minutes per record.

closes #38
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

Successfully merging a pull request may close this issue.

1 participant