{"level":"warn","ts":"2026-05-11T17:59:27.861617Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T17:59:27.861701Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.238:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.238:2380","--initial-cluster=instance=https://199.19.213.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.238:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-11T17:59:27.861765Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T17:59:27.86178Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.238:2380"]} {"level":"info","ts":"2026-05-11T17:59:27.861797Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T17:59:27.862479Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"]} {"level":"info","ts":"2026-05-11T17:59:27.862555Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.238:2380"],"listen-peer-urls":["https://199.19.213.238:2380"],"advertise-client-urls":["https://199.19.213.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.238:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-11T17:59:27.866816Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.037415ms"} {"level":"info","ts":"2026-05-11T17:59:27.872654Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8cf6035374726417","cluster-id":"e837e6e114b087e2"} {"level":"info","ts":"2026-05-11T17:59:27.872726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T17:59:27.872754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became follower at term 0"} {"level":"info","ts":"2026-05-11T17:59:27.87277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8cf6035374726417 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T17:59:27.872785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became follower at term 1"} {"level":"info","ts":"2026-05-11T17:59:27.872829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=(10157309666551555095)"} {"level":"warn","ts":"2026-05-11T17:59:27.877858Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T17:59:27.879422Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T17:59:27.880765Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-11T17:59:27.883707Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8cf6035374726417","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T17:59:27.883963Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8cf6035374726417","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T17:59:27.883976Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T17:59:27.884059Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T17:59:27.884077Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T17:59:27.884806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=(10157309666551555095)"} {"level":"info","ts":"2026-05-11T17:59:27.885008Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e837e6e114b087e2","local-member-id":"8cf6035374726417","added-peer-id":"8cf6035374726417","added-peer-peer-urls":["https://199.19.213.238:2380"]} {"level":"info","ts":"2026-05-11T17:59:27.887541Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T17:59:27.887608Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.238:2380"} {"level":"info","ts":"2026-05-11T17:59:27.887658Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.238:2380"} {"level":"info","ts":"2026-05-11T17:59:27.887812Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8cf6035374726417","initial-advertise-peer-urls":["https://199.19.213.238:2380"],"listen-peer-urls":["https://199.19.213.238:2380"],"advertise-client-urls":["https://199.19.213.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T17:59:27.887845Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T17:59:28.873666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T17:59:28.873729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T17:59:28.873762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 received MsgPreVoteResp from 8cf6035374726417 at term 1"} {"level":"info","ts":"2026-05-11T17:59:28.873782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became candidate at term 2"} {"level":"info","ts":"2026-05-11T17:59:28.873794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 received MsgVoteResp from 8cf6035374726417 at term 2"} {"level":"info","ts":"2026-05-11T17:59:28.873811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became leader at term 2"} {"level":"info","ts":"2026-05-11T17:59:28.873825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8cf6035374726417 elected leader 8cf6035374726417 at term 2"} {"level":"info","ts":"2026-05-11T17:59:28.874756Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8cf6035374726417","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.238:2379]}","request-path":"/0/members/8cf6035374726417/attributes","cluster-id":"e837e6e114b087e2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T17:59:28.874792Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T17:59:28.87487Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T17:59:28.87491Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T17:59:28.875034Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T17:59:28.87511Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T17:59:28.875801Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e837e6e114b087e2","local-member-id":"8cf6035374726417","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T17:59:28.875929Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T17:59:28.875969Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T17:59:28.87731Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T17:59:28.879024Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.238:2379"} {"level":"info","ts":"2026-05-11T17:59:42.052527Z","caller":"traceutil/trace.go:171","msg":"trace[517892826] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:369; }","duration":"163.245304ms","start":"2026-05-11T17:59:41.889249Z","end":"2026-05-11T17:59:42.052495Z","steps":["trace[517892826] 'read index received' (duration: 163.070879ms)","trace[517892826] 'applied index is now lower than readState.Index' (duration: 172.985µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:59:42.052952Z","caller":"traceutil/trace.go:171","msg":"trace[1202074115] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"242.946812ms","start":"2026-05-11T17:59:41.809988Z","end":"2026-05-11T17:59:42.052935Z","steps":["trace[1202074115] 'process raft request' (duration: 242.314745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:59:42.053245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.967566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T17:59:42.05332Z","caller":"traceutil/trace.go:171","msg":"trace[1163131098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:360; }","duration":"164.10457ms","start":"2026-05-11T17:59:41.889199Z","end":"2026-05-11T17:59:42.053304Z","steps":["trace[1163131098] 'agreement among raft nodes before linearized reading' (duration: 163.966756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:59:42.053495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.054983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-11T17:59:42.053586Z","caller":"traceutil/trace.go:171","msg":"trace[1928464985] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:360; }","duration":"100.190757ms","start":"2026-05-11T17:59:41.953374Z","end":"2026-05-11T17:59:42.053565Z","steps":["trace[1928464985] 'agreement among raft nodes before linearized reading' (duration: 100.018372ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:59:42.255448Z","caller":"traceutil/trace.go:171","msg":"trace[1379714078] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"183.494846ms","start":"2026-05-11T17:59:42.071915Z","end":"2026-05-11T17:59:42.25541Z","steps":["trace[1379714078] 'process raft request' (duration: 163.730638ms)","trace[1379714078] 'compare' (duration: 19.607243ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:00:04.004526Z","caller":"traceutil/trace.go:171","msg":"trace[339168275] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"178.996728ms","start":"2026-05-11T18:00:03.825509Z","end":"2026-05-11T18:00:04.004506Z","steps":["trace[339168275] 'process raft request' (duration: 178.860724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:04.367364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.217766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T18:00:04.367455Z","caller":"traceutil/trace.go:171","msg":"trace[1793117717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"145.35729ms","start":"2026-05-11T18:00:04.222077Z","end":"2026-05-11T18:00:04.367434Z","steps":["trace[1793117717] 'range keys from in-memory index tree' (duration: 144.917868ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:00:04.370698Z","caller":"traceutil/trace.go:171","msg":"trace[1727774667] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"146.275715ms","start":"2026-05-11T18:00:04.224407Z","end":"2026-05-11T18:00:04.370683Z","steps":["trace[1727774667] 'process raft request' (duration: 146.143712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:04.370912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.607927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:00:04.370946Z","caller":"traceutil/trace.go:171","msg":"trace[1045580606] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"139.347491ms","start":"2026-05-11T18:00:04.231307Z","end":"2026-05-11T18:00:04.370655Z","steps":["trace[1045580606] 'read index received' (duration: 139.174726ms)","trace[1045580606] 'applied index is now lower than readState.Index' (duration: 172.055µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:00:04.370986Z","caller":"traceutil/trace.go:171","msg":"trace[781194899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"139.719131ms","start":"2026-05-11T18:00:04.231251Z","end":"2026-05-11T18:00:04.37097Z","steps":["trace[781194899] 'agreement among raft nodes before linearized reading' (duration: 139.595278ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:00:04.636025Z","caller":"traceutil/trace.go:171","msg":"trace[1997506891] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"115.315512ms","start":"2026-05-11T18:00:04.520659Z","end":"2026-05-11T18:00:04.635974Z","steps":["trace[1997506891] 'read index received' (duration: 90.893703ms)","trace[1997506891] 'applied index is now lower than readState.Index' (duration: 24.420769ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:00:04.636697Z","caller":"traceutil/trace.go:171","msg":"trace[1789259403] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"223.993507ms","start":"2026-05-11T18:00:04.41202Z","end":"2026-05-11T18:00:04.636013Z","steps":["trace[1789259403] 'process raft request' (duration: 199.511337ms)","trace[1789259403] 'compare' (duration: 24.322616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:00:04.63706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.367281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T18:00:04.637226Z","caller":"traceutil/trace.go:171","msg":"trace[973096579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"116.531196ms","start":"2026-05-11T18:00:04.520623Z","end":"2026-05-11T18:00:04.637154Z","steps":["trace[973096579] 'agreement among raft nodes before linearized reading' (duration: 115.472177ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:00:05.109281Z","caller":"traceutil/trace.go:171","msg":"trace[572787828] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"233.331741ms","start":"2026-05-11T18:00:04.875912Z","end":"2026-05-11T18:00:05.109244Z","steps":["trace[572787828] 'read index received' (duration: 232.442476ms)","trace[572787828] 'applied index is now lower than readState.Index' (duration: 887.825µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:00:05.109397Z","caller":"traceutil/trace.go:171","msg":"trace[607722739] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"330.794651ms","start":"2026-05-11T18:00:04.778567Z","end":"2026-05-11T18:00:05.109361Z","steps":["trace[607722739] 'process raft request' (duration: 330.086131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:05.109649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.713693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T18:00:05.109978Z","caller":"traceutil/trace.go:171","msg":"trace[115322713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"234.096183ms","start":"2026-05-11T18:00:04.875852Z","end":"2026-05-11T18:00:05.109948Z","steps":["trace[115322713] 'agreement among raft nodes before linearized reading' (duration: 233.61567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:05.110696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:00:04.778525Z","time spent":"331.191331ms","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:02:04.435779Z","caller":"traceutil/trace.go:171","msg":"trace[1798685595] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"160.900735ms","start":"2026-05-11T18:02:04.274849Z","end":"2026-05-11T18:02:04.43575Z","steps":["trace[1798685595] 'process raft request' (duration: 160.71404ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:02:25.422129Z","caller":"traceutil/trace.go:171","msg":"trace[1409019743] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"100.830251ms","start":"2026-05-11T18:02:25.321249Z","end":"2026-05-11T18:02:25.422079Z","steps":["trace[1409019743] 'process raft request' (duration: 100.553853ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:02:29.049708Z","caller":"traceutil/trace.go:171","msg":"trace[109860398] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"161.25406ms","start":"2026-05-11T18:02:28.88843Z","end":"2026-05-11T18:02:29.049684Z","steps":["trace[109860398] 'process raft request' (duration: 161.098806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:03:07.869966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.388278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T18:03:07.870117Z","caller":"traceutil/trace.go:171","msg":"trace[1461642084] linearizableReadLoop","detail":"{readStateIndex:2369; appliedIndex:2368; }","duration":"170.839392ms","start":"2026-05-11T18:03:07.699261Z","end":"2026-05-11T18:03:07.8701Z","steps":["trace[1461642084] 'read index received' (duration: 25.075524ms)","trace[1461642084] 'applied index is now lower than readState.Index' (duration: 145.762838ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:03:07.870259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.998786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T18:03:07.870295Z","caller":"traceutil/trace.go:171","msg":"trace[1543169996] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2280; }","duration":"171.062748ms","start":"2026-05-11T18:03:07.699223Z","end":"2026-05-11T18:03:07.870286Z","steps":["trace[1543169996] 'agreement among raft nodes before linearized reading' (duration: 170.927954ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:03:07.870254Z","caller":"traceutil/trace.go:171","msg":"trace[1054540707] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"211.308756ms","start":"2026-05-11T18:03:07.658908Z","end":"2026-05-11T18:03:07.870217Z","steps":["trace[1054540707] 'process raft request' (duration: 65.447456ms)","trace[1054540707] 'compare' (duration: 145.263564ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:03:07.880399Z","caller":"traceutil/trace.go:171","msg":"trace[179477155] transaction","detail":"{read_only:false; response_revision:2281; number_of_response:1; }","duration":"136.187576ms","start":"2026-05-11T18:03:07.74419Z","end":"2026-05-11T18:03:07.880377Z","steps":["trace[179477155] 'process raft request' (duration: 136.037302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:03:08.457226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.895972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T18:03:08.457465Z","caller":"traceutil/trace.go:171","msg":"trace[1212837707] transaction","detail":"{read_only:false; response_revision:2283; number_of_response:1; }","duration":"477.540781ms","start":"2026-05-11T18:03:07.979901Z","end":"2026-05-11T18:03:08.457442Z","steps":["trace[1212837707] 'process raft request' (duration: 477.455899ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:03:08.457489Z","caller":"traceutil/trace.go:171","msg":"trace[623463913] transaction","detail":"{read_only:false; response_revision:2282; number_of_response:1; }","duration":"583.048909ms","start":"2026-05-11T18:03:07.874389Z","end":"2026-05-11T18:03:08.457438Z","steps":["trace[623463913] 'process raft request' (duration: 160.829258ms)","trace[623463913] 'compare' (duration: 421.684757ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:03:08.457563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:03:07.97988Z","time spent":"477.634883ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:03:08.457704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:03:07.874368Z","time spent":"583.222343ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:03:08.457935Z","caller":"traceutil/trace.go:171","msg":"trace[554841508] linearizableReadLoop","detail":"{readStateIndex:2371; appliedIndex:2370; }","duration":"482.578248ms","start":"2026-05-11T18:03:07.975326Z","end":"2026-05-11T18:03:08.457905Z","steps":["trace[554841508] 'read index received' (duration: 59.911985ms)","trace[554841508] 'applied index is now lower than readState.Index' (duration: 422.661783ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:03:08.458202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.871445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-11T18:03:08.458276Z","caller":"traceutil/trace.go:171","msg":"trace[770384462] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2283; }","duration":"482.978728ms","start":"2026-05-11T18:03:07.975277Z","end":"2026-05-11T18:03:08.458256Z","steps":["trace[770384462] 'agreement among raft nodes before linearized reading' (duration: 482.731451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:03:08.458334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:03:07.975261Z","time spent":"483.059741ms","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-11T18:03:08.460952Z","caller":"traceutil/trace.go:171","msg":"trace[770082066] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"254.273028ms","start":"2026-05-11T18:03:08.20666Z","end":"2026-05-11T18:03:08.460933Z","steps":["trace[770082066] 'process raft request' (duration: 254.154955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:03:08.461024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.142747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T18:03:08.461059Z","caller":"traceutil/trace.go:171","msg":"trace[1430993399] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2284; }","duration":"371.179318ms","start":"2026-05-11T18:03:08.089869Z","end":"2026-05-11T18:03:08.461048Z","steps":["trace[1430993399] 'agreement among raft nodes before linearized reading' (duration: 371.021514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:03:08.461085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:03:08.089819Z","time spent":"371.26103ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T18:03:08.461162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.261039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:03:08.461287Z","caller":"traceutil/trace.go:171","msg":"trace[1684992769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2284; }","duration":"231.380023ms","start":"2026-05-11T18:03:08.229836Z","end":"2026-05-11T18:03:08.461216Z","steps":["trace[1684992769] 'agreement among raft nodes before linearized reading' (duration: 231.231719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:03:41.284662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.997026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:03:41.284758Z","caller":"traceutil/trace.go:171","msg":"trace[1375556751] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2642; }","duration":"132.156511ms","start":"2026-05-11T18:03:41.15258Z","end":"2026-05-11T18:03:41.284736Z","steps":["trace[1375556751] 'count revisions from in-memory index tree' (duration: 131.850512ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:08:14.491073Z","caller":"traceutil/trace.go:171","msg":"trace[1052880445] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"150.528216ms","start":"2026-05-11T18:08:14.340517Z","end":"2026-05-11T18:08:14.491046Z","steps":["trace[1052880445] 'process raft request' (duration: 150.292368ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:08:29.778353Z","caller":"traceutil/trace.go:171","msg":"trace[142047270] transaction","detail":"{read_only:false; response_revision:5371; number_of_response:1; }","duration":"202.964172ms","start":"2026-05-11T18:08:29.575356Z","end":"2026-05-11T18:08:29.77832Z","steps":["trace[142047270] 'process raft request' (duration: 202.819968ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:08:29.778387Z","caller":"traceutil/trace.go:171","msg":"trace[826982299] transaction","detail":"{read_only:false; response_revision:5370; number_of_response:1; }","duration":"210.990458ms","start":"2026-05-11T18:08:29.567348Z","end":"2026-05-11T18:08:29.778338Z","steps":["trace[826982299] 'process raft request' (duration: 179.521018ms)","trace[826982299] 'compare' (duration: 30.939085ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:09:28.907561Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2923} {"level":"info","ts":"2026-05-11T18:09:28.97943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2923,"took":"70.101693ms","hash":531576350,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":33423360,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-11T18:09:28.979512Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":531576350,"revision":2923,"compact-revision":-1} {"level":"info","ts":"2026-05-11T18:14:28.916977Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5982} {"level":"info","ts":"2026-05-11T18:14:29.032471Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5982,"took":"112.899679ms","hash":2423196135,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":21757952,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-11T18:14:29.03257Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2423196135,"revision":5982,"compact-revision":2923} {"level":"info","ts":"2026-05-11T18:14:51.56064Z","caller":"traceutil/trace.go:171","msg":"trace[728368669] transaction","detail":"{read_only:false; response_revision:8330; number_of_response:1; }","duration":"107.028299ms","start":"2026-05-11T18:14:51.453581Z","end":"2026-05-11T18:14:51.560609Z","steps":["trace[728368669] 'process raft request' (duration: 106.730021ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:14:53.137379Z","caller":"traceutil/trace.go:171","msg":"trace[644446651] transaction","detail":"{read_only:false; response_revision:8338; number_of_response:1; }","duration":"187.398399ms","start":"2026-05-11T18:14:52.949951Z","end":"2026-05-11T18:14:53.137349Z","steps":["trace[644446651] 'process raft request' (duration: 187.218424ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:14:53.938608Z","caller":"traceutil/trace.go:171","msg":"trace[1821475615] transaction","detail":"{read_only:false; response_revision:8344; number_of_response:1; }","duration":"148.648359ms","start":"2026-05-11T18:14:53.789903Z","end":"2026-05-11T18:14:53.938551Z","steps":["trace[1821475615] 'process raft request' (duration: 148.406012ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:14:54.415177Z","caller":"traceutil/trace.go:171","msg":"trace[1422425811] linearizableReadLoop","detail":"{readStateIndex:8641; appliedIndex:8640; }","duration":"154.431696ms","start":"2026-05-11T18:14:54.260718Z","end":"2026-05-11T18:14:54.41515Z","steps":["trace[1422425811] 'read index received' (duration: 150.069457ms)","trace[1422425811] 'applied index is now lower than readState.Index' (duration: 4.359009ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:14:54.415309Z","caller":"traceutil/trace.go:171","msg":"trace[1877341212] transaction","detail":"{read_only:false; response_revision:8346; number_of_response:1; }","duration":"162.283132ms","start":"2026-05-11T18:14:54.252993Z","end":"2026-05-11T18:14:54.415276Z","steps":["trace[1877341212] 'process raft request' (duration: 157.792389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:14:54.415452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.275141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qcmqx\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-11T18:14:54.415497Z","caller":"traceutil/trace.go:171","msg":"trace[1005161228] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qcmqx; range_end:; response_count:1; response_revision:8346; }","duration":"143.357464ms","start":"2026-05-11T18:14:54.272126Z","end":"2026-05-11T18:14:54.415483Z","steps":["trace[1005161228] 'agreement among raft nodes before linearized reading' (duration: 143.195309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:14:54.415487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.758646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T18:14:54.415567Z","caller":"traceutil/trace.go:171","msg":"trace[434094353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8346; }","duration":"154.890328ms","start":"2026-05-11T18:14:54.260656Z","end":"2026-05-11T18:14:54.415547Z","steps":["trace[434094353] 'agreement among raft nodes before linearized reading' (duration: 154.631821ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:25.815774Z","caller":"traceutil/trace.go:171","msg":"trace[1819527418] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"267.952524ms","start":"2026-05-11T18:15:25.547793Z","end":"2026-05-11T18:15:25.815746Z","steps":["trace[1819527418] 'process raft request' (duration: 267.781669ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:25.839433Z","caller":"traceutil/trace.go:171","msg":"trace[1097754630] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"147.217448ms","start":"2026-05-11T18:15:25.692202Z","end":"2026-05-11T18:15:25.839419Z","steps":["trace[1097754630] 'process raft request' (duration: 147.112606ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:25.839692Z","caller":"traceutil/trace.go:171","msg":"trace[1545405542] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"147.362502ms","start":"2026-05-11T18:15:25.692306Z","end":"2026-05-11T18:15:25.839669Z","steps":["trace[1545405542] 'process raft request' (duration: 147.078154ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:36.002347Z","caller":"traceutil/trace.go:171","msg":"trace[2060497606] transaction","detail":"{read_only:false; response_revision:8664; number_of_response:1; }","duration":"139.354704ms","start":"2026-05-11T18:15:35.862961Z","end":"2026-05-11T18:15:36.002315Z","steps":["trace[2060497606] 'process raft request' (duration: 139.122098ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:36.100391Z","caller":"traceutil/trace.go:171","msg":"trace[966092406] transaction","detail":"{read_only:false; response_revision:8665; number_of_response:1; }","duration":"232.535964ms","start":"2026-05-11T18:15:35.867824Z","end":"2026-05-11T18:15:36.10036Z","steps":["trace[966092406] 'process raft request' (duration: 227.164007ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:36.100714Z","caller":"traceutil/trace.go:171","msg":"trace[1037294145] transaction","detail":"{read_only:false; response_revision:8667; number_of_response:1; }","duration":"225.000178ms","start":"2026-05-11T18:15:35.875703Z","end":"2026-05-11T18:15:36.100704Z","steps":["trace[1037294145] 'process raft request' (duration: 224.807013ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:36.100675Z","caller":"traceutil/trace.go:171","msg":"trace[746155209] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"230.714404ms","start":"2026-05-11T18:15:35.869921Z","end":"2026-05-11T18:15:36.100635Z","steps":["trace[746155209] 'process raft request' (duration: 230.340724ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:36.100673Z","caller":"traceutil/trace.go:171","msg":"trace[362362474] linearizableReadLoop","detail":"{readStateIndex:8975; appliedIndex:8971; }","duration":"174.933717ms","start":"2026-05-11T18:15:35.925709Z","end":"2026-05-11T18:15:36.100643Z","steps":["trace[362362474] 'read index received' (duration: 76.36389ms)","trace[362362474] 'applied index is now lower than readState.Index' (duration: 98.568577ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:15:36.100894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.155073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-11T18:15:36.100956Z","caller":"traceutil/trace.go:171","msg":"trace[2016678380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8667; }","duration":"175.307328ms","start":"2026-05-11T18:15:35.925637Z","end":"2026-05-11T18:15:36.100945Z","steps":["trace[2016678380] 'agreement among raft nodes before linearized reading' (duration: 175.099532ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:15:53.544536Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ba.wal"} {"level":"info","ts":"2026-05-11T18:16:54.530355Z","caller":"traceutil/trace.go:171","msg":"trace[1500082865] transaction","detail":"{read_only:false; response_revision:9423; number_of_response:1; }","duration":"124.683636ms","start":"2026-05-11T18:16:54.405628Z","end":"2026-05-11T18:16:54.530312Z","steps":["trace[1500082865] 'process raft request' (duration: 124.47784ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:17:28.877922Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8cf6035374726417","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T18:17:28.884588Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-11T18:17:28.884748Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-11T18:19:28.928875Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8074} {"level":"info","ts":"2026-05-11T18:19:28.997599Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8074,"took":"65.527753ms","hash":3196776466,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":28254208,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-11T18:19:28.997667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3196776466,"revision":8074,"compact-revision":5982} {"level":"info","ts":"2026-05-11T18:22:33.572425Z","caller":"traceutil/trace.go:171","msg":"trace[680585380] transaction","detail":"{read_only:false; response_revision:11857; number_of_response:1; }","duration":"217.411061ms","start":"2026-05-11T18:22:33.354979Z","end":"2026-05-11T18:22:33.57239Z","steps":["trace[680585380] 'process raft request' (duration: 217.188454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:22:33.786096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.92297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-11T18:22:33.786185Z","caller":"traceutil/trace.go:171","msg":"trace[169805111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11857; }","duration":"106.068233ms","start":"2026-05-11T18:22:33.680095Z","end":"2026-05-11T18:22:33.786163Z","steps":["trace[169805111] 'range keys from in-memory index tree' (duration: 105.725845ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:24:28.935894Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10434} {"level":"info","ts":"2026-05-11T18:24:29.018985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10434,"took":"79.650692ms","hash":1260008153,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":25989120,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-11T18:24:29.019062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1260008153,"revision":10434,"compact-revision":8074} {"level":"info","ts":"2026-05-11T18:29:10.560239Z","caller":"traceutil/trace.go:171","msg":"trace[184891358] linearizableReadLoop","detail":"{readStateIndex:14993; appliedIndex:14992; }","duration":"328.910103ms","start":"2026-05-11T18:29:10.231296Z","end":"2026-05-11T18:29:10.560206Z","steps":["trace[184891358] 'read index received' (duration: 328.691247ms)","trace[184891358] 'applied index is now lower than readState.Index' (duration: 217.645µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:29:10.560395Z","caller":"traceutil/trace.go:171","msg":"trace[1577097407] transaction","detail":"{read_only:false; response_revision:14493; number_of_response:1; }","duration":"354.145711ms","start":"2026-05-11T18:29:10.206231Z","end":"2026-05-11T18:29:10.560377Z","steps":["trace[1577097407] 'process raft request' (duration: 353.783561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:29:10.560523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:29:10.206208Z","time spent":"354.214632ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:29:10.560718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.384195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:29:10.560851Z","caller":"traceutil/trace.go:171","msg":"trace[790254804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14493; }","duration":"329.595841ms","start":"2026-05-11T18:29:10.231227Z","end":"2026-05-11T18:29:10.560823Z","steps":["trace[790254804] 'agreement among raft nodes before linearized reading' (duration: 329.381135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:29:10.560916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:29:10.231197Z","time spent":"329.706573ms","remote":"127.0.0.1:35338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T18:29:10.561675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.989945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:85 size:927166"} {"level":"info","ts":"2026-05-11T18:29:10.561747Z","caller":"traceutil/trace.go:171","msg":"trace[2025861778] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:85; response_revision:14493; }","duration":"145.114098ms","start":"2026-05-11T18:29:10.416619Z","end":"2026-05-11T18:29:10.561733Z","steps":["trace[2025861778] 'agreement among raft nodes before linearized reading' (duration: 143.973207ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:29:28.945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12629} {"level":"info","ts":"2026-05-11T18:29:29.036927Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12629,"took":"88.702939ms","hash":2881715044,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":27971584,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-11T18:29:29.037017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2881715044,"revision":12629,"compact-revision":10434} {"level":"info","ts":"2026-05-11T18:29:34.932539Z","caller":"traceutil/trace.go:171","msg":"trace[1296466871] linearizableReadLoop","detail":"{readStateIndex:15346; appliedIndex:15345; }","duration":"146.528908ms","start":"2026-05-11T18:29:34.785823Z","end":"2026-05-11T18:29:34.932352Z","steps":["trace[1296466871] 'read index received' (duration: 52.821331ms)","trace[1296466871] 'applied index is now lower than readState.Index' (duration: 93.706467ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:29:34.93261Z","caller":"traceutil/trace.go:171","msg":"trace[1128978185] transaction","detail":"{read_only:false; response_revision:14829; number_of_response:1; }","duration":"286.088094ms","start":"2026-05-11T18:29:34.64644Z","end":"2026-05-11T18:29:34.932528Z","steps":["trace[1128978185] 'process raft request' (duration: 192.272744ms)","trace[1128978185] 'compare' (duration: 93.254105ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:29:34.932895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.041841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-11T18:29:34.932956Z","caller":"traceutil/trace.go:171","msg":"trace[1300791628] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:14829; }","duration":"147.127274ms","start":"2026-05-11T18:29:34.785806Z","end":"2026-05-11T18:29:34.932933Z","steps":["trace[1300791628] 'agreement among raft nodes before linearized reading' (duration: 146.865757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:01.441384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.092726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:31:01.441519Z","caller":"traceutil/trace.go:171","msg":"trace[997947285] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15638; }","duration":"102.26996ms","start":"2026-05-11T18:31:01.339219Z","end":"2026-05-11T18:31:01.441489Z","steps":["trace[997947285] 'range keys from in-memory index tree' (duration: 102.009843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:01.441635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.427364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-05-11T18:31:01.441738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.325318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-11T18:31:01.441782Z","caller":"traceutil/trace.go:171","msg":"trace[1066354367] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15638; }","duration":"102.6369ms","start":"2026-05-11T18:31:01.339119Z","end":"2026-05-11T18:31:01.441755Z","steps":["trace[1066354367] 'range keys from in-memory index tree' (duration: 102.222409ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:31:01.441793Z","caller":"traceutil/trace.go:171","msg":"trace[1872571723] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15638; }","duration":"103.450771ms","start":"2026-05-11T18:31:01.338328Z","end":"2026-05-11T18:31:01.441779Z","steps":["trace[1872571723] 'range keys from in-memory index tree' (duration: 103.101213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:01.441834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.697351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-11T18:31:01.441914Z","caller":"traceutil/trace.go:171","msg":"trace[584473477] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15638; }","duration":"102.809314ms","start":"2026-05-11T18:31:01.339086Z","end":"2026-05-11T18:31:01.441895Z","steps":["trace[584473477] 'range keys from in-memory index tree' (duration: 102.515757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:01.442551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.570188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-05-11T18:31:01.442673Z","caller":"traceutil/trace.go:171","msg":"trace[742180637] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15638; }","duration":"102.723513ms","start":"2026-05-11T18:31:01.339926Z","end":"2026-05-11T18:31:01.44265Z","steps":["trace[742180637] 'range keys from in-memory index tree' (duration: 101.783707ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:31:01.714614Z","caller":"traceutil/trace.go:171","msg":"trace[594556945] transaction","detail":"{read_only:false; response_revision:15639; number_of_response:1; }","duration":"221.548784ms","start":"2026-05-11T18:31:01.49304Z","end":"2026-05-11T18:31:01.714589Z","steps":["trace[594556945] 'process raft request' (duration: 221.394509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:01.894628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.12122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T18:31:01.894968Z","caller":"traceutil/trace.go:171","msg":"trace[193645003] linearizableReadLoop","detail":"{readStateIndex:16179; appliedIndex:16177; }","duration":"395.844488ms","start":"2026-05-11T18:31:01.499059Z","end":"2026-05-11T18:31:01.894903Z","steps":["trace[193645003] 'read index received' (duration: 215.378076ms)","trace[193645003] 'applied index is now lower than readState.Index' (duration: 180.419841ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:31:01.895066Z","caller":"traceutil/trace.go:171","msg":"trace[1639456416] transaction","detail":"{read_only:false; response_revision:15640; number_of_response:1; }","duration":"399.164449ms","start":"2026-05-11T18:31:01.495863Z","end":"2026-05-11T18:31:01.895028Z","steps":["trace[1639456416] 'process raft request' (duration: 287.525404ms)","trace[1639456416] 'compare' (duration: 110.961356ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:31:01.895381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-11T18:31:01.895433Z","caller":"traceutil/trace.go:171","msg":"trace[177455910] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15641; }","duration":"396.359453ms","start":"2026-05-11T18:31:01.499059Z","end":"2026-05-11T18:31:01.895419Z","steps":["trace[177455910] 'agreement among raft nodes before linearized reading' (duration: 396.185248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:01.89542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.641959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-05-11T18:31:01.895463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:31:01.499052Z","time spent":"396.404354ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-05-11T18:31:01.895414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:31:01.495851Z","time spent":"399.337434ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:31:01.895498Z","caller":"traceutil/trace.go:171","msg":"trace[146219386] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15641; }","duration":"385.743043ms","start":"2026-05-11T18:31:01.509737Z","end":"2026-05-11T18:31:01.89548Z","steps":["trace[146219386] 'agreement among raft nodes before linearized reading' (duration: 385.548787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:01.895559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:31:01.509723Z","time spent":"385.823464ms","remote":"127.0.0.1:35588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-05-11T18:31:01.895543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.429565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T18:31:01.895705Z","caller":"traceutil/trace.go:171","msg":"trace[1824545923] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15641; }","duration":"396.673051ms","start":"2026-05-11T18:31:01.499017Z","end":"2026-05-11T18:31:01.89569Z","steps":["trace[1824545923] 'agreement among raft nodes before linearized reading' (duration: 396.366083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:31:01.895782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:31:01.498999Z","time spent":"396.770953ms","remote":"127.0.0.1:35588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-05-11T18:34:28.950956Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14702} {"level":"info","ts":"2026-05-11T18:34:29.034847Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14702,"took":"80.297179ms","hash":2521231882,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":33935360,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T18:34:29.034909Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2521231882,"revision":14702,"compact-revision":12629} {"level":"info","ts":"2026-05-11T18:36:56.570677Z","caller":"traceutil/trace.go:171","msg":"trace[183273664] transaction","detail":"{read_only:false; response_revision:18260; number_of_response:1; }","duration":"207.999945ms","start":"2026-05-11T18:36:56.362645Z","end":"2026-05-11T18:36:56.570645Z","steps":["trace[183273664] 'process raft request' (duration: 207.716137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:36:57.445551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.028379ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-11T18:36:57.445895Z","caller":"traceutil/trace.go:171","msg":"trace[1030558155] transaction","detail":"{read_only:false; response_revision:18263; number_of_response:1; }","duration":"147.973566ms","start":"2026-05-11T18:36:57.297897Z","end":"2026-05-11T18:36:57.445871Z","steps":["trace[1030558155] 'process raft request' (duration: 147.799081ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:36:57.445858Z","caller":"traceutil/trace.go:171","msg":"trace[1806204779] linearizableReadLoop","detail":"{readStateIndex:18888; appliedIndex:18887; }","duration":"279.890635ms","start":"2026-05-11T18:36:57.16592Z","end":"2026-05-11T18:36:57.44581Z","steps":["trace[1806204779] 'read index received' (duration: 91.518637ms)","trace[1806204779] 'applied index is now lower than readState.Index' (duration: 188.368047ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:36:57.446224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.289035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-sw7v6\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-05-11T18:36:57.446343Z","caller":"traceutil/trace.go:171","msg":"trace[89906398] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-sw7v6; range_end:; response_count:1; response_revision:18263; }","duration":"280.463879ms","start":"2026-05-11T18:36:57.165854Z","end":"2026-05-11T18:36:57.446318Z","steps":["trace[89906398] 'agreement among raft nodes before linearized reading' (duration: 280.048968ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:36:57.502323Z","caller":"traceutil/trace.go:171","msg":"trace[52228557] transaction","detail":"{read_only:false; response_revision:18264; number_of_response:1; }","duration":"202.287227ms","start":"2026-05-11T18:36:57.299997Z","end":"2026-05-11T18:36:57.502284Z","steps":["trace[52228557] 'process raft request' (duration: 201.970368ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:36:57.502351Z","caller":"traceutil/trace.go:171","msg":"trace[684009789] transaction","detail":"{read_only:false; response_revision:18265; number_of_response:1; }","duration":"201.022972ms","start":"2026-05-11T18:36:57.301296Z","end":"2026-05-11T18:36:57.502319Z","steps":["trace[684009789] 'process raft request' (duration: 200.893878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:36:57.502568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.178992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:36:57.5027Z","caller":"traceutil/trace.go:171","msg":"trace[996230791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18265; }","duration":"269.360367ms","start":"2026-05-11T18:36:57.233307Z","end":"2026-05-11T18:36:57.502667Z","steps":["trace[996230791] 'agreement among raft nodes before linearized reading' (duration: 269.10389ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:38:50.418835Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8cf6035374726417","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T18:38:50.424551Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-11T18:38:50.424655Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-11T18:39:28.958752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17274} {"level":"info","ts":"2026-05-11T18:39:29.064607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17274,"took":"101.394996ms","hash":2938150451,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":34635776,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-11T18:39:29.064683Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2938150451,"revision":17274,"compact-revision":14702} {"level":"warn","ts":"2026-05-11T18:43:02.495394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.542418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:43:02.495689Z","caller":"traceutil/trace.go:171","msg":"trace[1970849409] transaction","detail":"{read_only:false; response_revision:21046; number_of_response:1; }","duration":"264.834095ms","start":"2026-05-11T18:43:02.230797Z","end":"2026-05-11T18:43:02.495631Z","steps":["trace[1970849409] 'process raft request' (duration: 77.941937ms)","trace[1970849409] 'compare' (duration: 186.294431ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:43:02.495805Z","caller":"traceutil/trace.go:171","msg":"trace[586955504] linearizableReadLoop","detail":"{readStateIndex:21768; appliedIndex:21767; }","duration":"263.864228ms","start":"2026-05-11T18:43:02.231902Z","end":"2026-05-11T18:43:02.495766Z","steps":["trace[586955504] 'read index received' (duration: 76.805456ms)","trace[586955504] 'applied index is now lower than readState.Index' (duration: 187.055312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:43:02.496085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.138386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:43:02.496224Z","caller":"traceutil/trace.go:171","msg":"trace[1218552613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21047; }","duration":"264.308191ms","start":"2026-05-11T18:43:02.231876Z","end":"2026-05-11T18:43:02.496184Z","steps":["trace[1218552613] 'agreement among raft nodes before linearized reading' (duration: 264.025163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:43:02.874184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.142944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"warn","ts":"2026-05-11T18:43:02.8742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.061862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:43:02.874296Z","caller":"traceutil/trace.go:171","msg":"trace[1428373353] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:21048; }","duration":"147.34386ms","start":"2026-05-11T18:43:02.726926Z","end":"2026-05-11T18:43:02.87427Z","steps":["trace[1428373353] 'range keys from in-memory index tree' (duration: 146.883067ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:43:02.874305Z","caller":"traceutil/trace.go:171","msg":"trace[1457212299] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:21048; }","duration":"125.235946ms","start":"2026-05-11T18:43:02.749045Z","end":"2026-05-11T18:43:02.874281Z","steps":["trace[1457212299] 'count revisions from in-memory index tree' (duration: 124.954969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:43:02.874319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.132768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-11T18:43:02.874398Z","caller":"traceutil/trace.go:171","msg":"trace[632120995] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:21048; }","duration":"274.2353ms","start":"2026-05-11T18:43:02.600131Z","end":"2026-05-11T18:43:02.874366Z","steps":["trace[632120995] 'range keys from in-memory index tree' (duration: 273.974513ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:43:03.272465Z","caller":"traceutil/trace.go:171","msg":"trace[1377551914] transaction","detail":"{read_only:false; response_revision:21051; number_of_response:1; }","duration":"247.361257ms","start":"2026-05-11T18:43:03.025072Z","end":"2026-05-11T18:43:03.272433Z","steps":["trace[1377551914] 'process raft request' (duration: 247.293175ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:43:03.272545Z","caller":"traceutil/trace.go:171","msg":"trace[1929746185] transaction","detail":"{read_only:false; response_revision:21050; number_of_response:1; }","duration":"247.417329ms","start":"2026-05-11T18:43:03.025071Z","end":"2026-05-11T18:43:03.272488Z","steps":["trace[1929746185] 'process raft request' (duration: 247.0868ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:44:28.966255Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19596} {"level":"info","ts":"2026-05-11T18:44:29.081522Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19596,"took":"110.531605ms","hash":156790373,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":29548544,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T18:44:29.081595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":156790373,"revision":19596,"compact-revision":17274} {"level":"info","ts":"2026-05-11T18:46:59.391055Z","caller":"traceutil/trace.go:171","msg":"trace[1427804081] linearizableReadLoop","detail":"{readStateIndex:24304; appliedIndex:24303; }","duration":"152.650799ms","start":"2026-05-11T18:46:59.238363Z","end":"2026-05-11T18:46:59.391014Z","steps":["trace[1427804081] 'read index received' (duration: 152.463214ms)","trace[1427804081] 'applied index is now lower than readState.Index' (duration: 185.485µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:46:59.391189Z","caller":"traceutil/trace.go:171","msg":"trace[1579789280] transaction","detail":"{read_only:false; response_revision:23510; number_of_response:1; }","duration":"153.165262ms","start":"2026-05-11T18:46:59.237964Z","end":"2026-05-11T18:46:59.391129Z","steps":["trace[1579789280] 'process raft request' (duration: 152.805353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:46:59.391471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.06049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-11T18:46:59.391549Z","caller":"traceutil/trace.go:171","msg":"trace[1539405459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23510; }","duration":"153.195023ms","start":"2026-05-11T18:46:59.238338Z","end":"2026-05-11T18:46:59.391533Z","steps":["trace[1539405459] 'agreement among raft nodes before linearized reading' (duration: 152.894265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:46:59.391597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.69902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T18:46:59.391648Z","caller":"traceutil/trace.go:171","msg":"trace[109934032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23510; }","duration":"152.831864ms","start":"2026-05-11T18:46:59.238805Z","end":"2026-05-11T18:46:59.391637Z","steps":["trace[109934032] 'agreement among raft nodes before linearized reading' (duration: 152.611068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:46:59.391957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.040725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-11T18:46:59.392029Z","caller":"traceutil/trace.go:171","msg":"trace[1641054306] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23510; }","duration":"151.193849ms","start":"2026-05-11T18:46:59.240819Z","end":"2026-05-11T18:46:59.392013Z","steps":["trace[1641054306] 'agreement among raft nodes before linearized reading' (duration: 150.88899ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:49:28.977218Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21820} {"level":"info","ts":"2026-05-11T18:49:29.096596Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21820,"took":"113.524073ms","hash":3944209069,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":43069440,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-11T18:49:29.096725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3944209069,"revision":21820,"compact-revision":19596} {"level":"info","ts":"2026-05-11T18:50:32.115783Z","caller":"traceutil/trace.go:171","msg":"trace[1579198523] linearizableReadLoop","detail":"{readStateIndex:26499; appliedIndex:26498; }","duration":"490.000321ms","start":"2026-05-11T18:50:31.625752Z","end":"2026-05-11T18:50:32.115752Z","steps":["trace[1579198523] 'read index received' (duration: 489.97507ms)","trace[1579198523] 'applied index is now lower than readState.Index' (duration: 23.771µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:32.116017Z","caller":"traceutil/trace.go:171","msg":"trace[691306983] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"551.006813ms","start":"2026-05-11T18:50:31.564939Z","end":"2026-05-11T18:50:32.115946Z","steps":["trace[691306983] 'process raft request' (duration: 550.622863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.116098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.285188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T18:50:32.116165Z","caller":"traceutil/trace.go:171","msg":"trace[831694500] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:25648; }","duration":"490.464003ms","start":"2026-05-11T18:50:31.62569Z","end":"2026-05-11T18:50:32.116154Z","steps":["trace[831694500] 'agreement among raft nodes before linearized reading' (duration: 490.244387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.116198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:31.625663Z","time spent":"490.526795ms","remote":"127.0.0.1:41674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":10,"response size":32,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-05-11T18:50:32.116273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:31.564913Z","time spent":"551.262559ms","remote":"127.0.0.1:35524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8443,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:32.143428Z","caller":"traceutil/trace.go:171","msg":"trace[1706986579] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"505.089262ms","start":"2026-05-11T18:50:31.638298Z","end":"2026-05-11T18:50:32.143387Z","steps":["trace[1706986579] 'process raft request' (duration: 504.576477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.143485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.272803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11787"} {"level":"info","ts":"2026-05-11T18:50:32.14357Z","caller":"traceutil/trace.go:171","msg":"trace[1434884567] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25649; }","duration":"154.396156ms","start":"2026-05-11T18:50:31.989158Z","end":"2026-05-11T18:50:32.143554Z","steps":["trace[1434884567] 'agreement among raft nodes before linearized reading' (duration: 154.15796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.143649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:31.638252Z","time spent":"505.290418ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:32.143712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.122366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-05-11T18:50:32.143819Z","caller":"traceutil/trace.go:171","msg":"trace[497541986] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25649; }","duration":"344.27961ms","start":"2026-05-11T18:50:31.799508Z","end":"2026-05-11T18:50:32.143787Z","steps":["trace[497541986] 'agreement among raft nodes before linearized reading' (duration: 343.92308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.143868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:31.799486Z","time spent":"344.373293ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1658,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-05-11T18:50:32.427305Z","caller":"traceutil/trace.go:171","msg":"trace[1742808860] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"303.278843ms","start":"2026-05-11T18:50:32.124003Z","end":"2026-05-11T18:50:32.427282Z","steps":["trace[1742808860] 'process raft request' (duration: 302.902303ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:32.427358Z","caller":"traceutil/trace.go:171","msg":"trace[167357297] linearizableReadLoop","detail":"{readStateIndex:26503; appliedIndex:26500; }","duration":"284.07201ms","start":"2026-05-11T18:50:32.143252Z","end":"2026-05-11T18:50:32.427324Z","steps":["trace[167357297] 'read index received' (duration: 283.72039ms)","trace[167357297] 'applied index is now lower than readState.Index' (duration: 350.11µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:32.427499Z","caller":"traceutil/trace.go:171","msg":"trace[568360678] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"303.446227ms","start":"2026-05-11T18:50:32.124045Z","end":"2026-05-11T18:50:32.427491Z","steps":["trace[568360678] 'process raft request' (duration: 303.100978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.42755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.12402Z","time spent":"303.509719ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":652,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:32.427445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.12398Z","time spent":"303.399106ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1167,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:32.427623Z","caller":"traceutil/trace.go:171","msg":"trace[1465082802] transaction","detail":"{read_only:false; response_revision:25652; number_of_response:1; }","duration":"302.42819ms","start":"2026-05-11T18:50:32.12515Z","end":"2026-05-11T18:50:32.427578Z","steps":["trace[1465082802] 'process raft request' (duration: 302.089931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.427832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.454012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:208"} {"level":"warn","ts":"2026-05-11T18:50:32.427881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.011661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-11T18:50:32.428003Z","caller":"traceutil/trace.go:171","msg":"trace[1554454960] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:25652; }","duration":"301.178945ms","start":"2026-05-11T18:50:32.126796Z","end":"2026-05-11T18:50:32.427975Z","steps":["trace[1554454960] 'agreement among raft nodes before linearized reading' (duration: 300.810955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.42805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.126786Z","time spent":"301.253687ms","remote":"127.0.0.1:35374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":164,"request content":"key:\"/registry/masterleases/199.19.213.238\" "} {"level":"info","ts":"2026-05-11T18:50:32.427929Z","caller":"traceutil/trace.go:171","msg":"trace[455471782] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:25652; }","duration":"305.599137ms","start":"2026-05-11T18:50:32.122308Z","end":"2026-05-11T18:50:32.427907Z","steps":["trace[455471782] 'agreement among raft nodes before linearized reading' (duration: 305.207546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.427853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.125142Z","time spent":"302.587375ms","remote":"127.0.0.1:35868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7529,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:32.428158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.122287Z","time spent":"305.827652ms","remote":"127.0.0.1:35552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"info","ts":"2026-05-11T18:50:32.711491Z","caller":"traceutil/trace.go:171","msg":"trace[1640568967] linearizableReadLoop","detail":"{readStateIndex:26505; appliedIndex:26503; }","duration":"283.953416ms","start":"2026-05-11T18:50:32.42751Z","end":"2026-05-11T18:50:32.711463Z","steps":["trace[1640568967] 'read index received' (duration: 204.295116ms)","trace[1640568967] 'applied index is now lower than readState.Index' (duration: 79.65667ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:32.711862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.285863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:32.711941Z","caller":"traceutil/trace.go:171","msg":"trace[1170364624] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"464.558098ms","start":"2026-05-11T18:50:32.247344Z","end":"2026-05-11T18:50:32.711902Z","steps":["trace[1170364624] 'process raft request' (duration: 384.444015ms)","trace[1170364624] 'compare' (duration: 79.209018ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:32.712004Z","caller":"traceutil/trace.go:171","msg":"trace[277910061] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:25654; }","duration":"325.453198ms","start":"2026-05-11T18:50:32.38652Z","end":"2026-05-11T18:50:32.711973Z","steps":["trace[277910061] 'agreement among raft nodes before linearized reading' (duration: 325.15039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.712084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.386377Z","time spent":"325.687844ms","remote":"127.0.0.1:42138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true "} {"level":"warn","ts":"2026-05-11T18:50:32.712099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.495385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11787"} {"level":"info","ts":"2026-05-11T18:50:32.71222Z","caller":"traceutil/trace.go:171","msg":"trace[15950255] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25654; }","duration":"559.650979ms","start":"2026-05-11T18:50:32.152544Z","end":"2026-05-11T18:50:32.712195Z","steps":["trace[15950255] 'agreement among raft nodes before linearized reading' (duration: 559.253098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.712284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.152534Z","time spent":"559.740551ms","remote":"127.0.0.1:35832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11811,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-05-11T18:50:32.712221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.942787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:32.71245Z","caller":"traceutil/trace.go:171","msg":"trace[50639551] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"320.254256ms","start":"2026-05-11T18:50:32.392176Z","end":"2026-05-11T18:50:32.71243Z","steps":["trace[50639551] 'process raft request' (duration: 319.213177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.712131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.247305Z","time spent":"464.715202ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:32.712465Z","caller":"traceutil/trace.go:171","msg":"trace[727836844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25654; }","duration":"480.264545ms","start":"2026-05-11T18:50:32.232174Z","end":"2026-05-11T18:50:32.712439Z","steps":["trace[727836844] 'agreement among raft nodes before linearized reading' (duration: 479.772512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.712766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.392162Z","time spent":"320.334979ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:32.712779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.232105Z","time spent":"480.660466ms","remote":"127.0.0.1:35338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T18:50:32.712351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.09023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-bbbdaa17-1c00-4bdd-b64d-02f532db3344\" ","response":"range_response_count:1 size:1294"} {"level":"info","ts":"2026-05-11T18:50:32.712961Z","caller":"traceutil/trace.go:171","msg":"trace[1656007417] range","detail":"{range_begin:/registry/persistentvolumes/pvc-bbbdaa17-1c00-4bdd-b64d-02f532db3344; range_end:; response_count:1; response_revision:25654; }","duration":"564.687626ms","start":"2026-05-11T18:50:32.148231Z","end":"2026-05-11T18:50:32.712918Z","steps":["trace[1656007417] 'agreement among raft nodes before linearized reading' (duration: 563.955587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:32.713028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.148213Z","time spent":"564.794759ms","remote":"127.0.0.1:35496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1318,"request content":"key:\"/registry/persistentvolumes/pvc-bbbdaa17-1c00-4bdd-b64d-02f532db3344\" "} {"level":"warn","ts":"2026-05-11T18:50:33.003021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.859538ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-05-11T18:50:33.003359Z","caller":"traceutil/trace.go:171","msg":"trace[1833702526] transaction","detail":"{read_only:false; response_revision:25656; number_of_response:1; }","duration":"169.147609ms","start":"2026-05-11T18:50:32.834191Z","end":"2026-05-11T18:50:33.003339Z","steps":["trace[1833702526] 'process raft request' (duration: 169.087538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:33.003598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.429297Z","time spent":"574.296847ms","remote":"127.0.0.1:35374","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-11T18:50:33.00392Z","caller":"traceutil/trace.go:171","msg":"trace[66860048] transaction","detail":"{read_only:false; response_revision:25655; number_of_response:1; }","duration":"562.055214ms","start":"2026-05-11T18:50:32.441853Z","end":"2026-05-11T18:50:33.003908Z","steps":["trace[66860048] 'process raft request' (duration: 561.282353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:33.004023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.441826Z","time spent":"562.130926ms","remote":"127.0.0.1:35832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7832,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:33.00434Z","caller":"traceutil/trace.go:171","msg":"trace[1658764289] linearizableReadLoop","detail":"{readStateIndex:26507; appliedIndex:26505; }","duration":"292.750067ms","start":"2026-05-11T18:50:32.711563Z","end":"2026-05-11T18:50:33.004313Z","steps":["trace[1658764289] 'read index received' (duration: 121.651335ms)","trace[1658764289] 'applied index is now lower than readState.Index' (duration: 171.095151ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:33.004481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.145664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-11T18:50:33.004579Z","caller":"traceutil/trace.go:171","msg":"trace[1721494319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25656; }","duration":"425.261007ms","start":"2026-05-11T18:50:32.579296Z","end":"2026-05-11T18:50:33.004557Z","steps":["trace[1721494319] 'agreement among raft nodes before linearized reading' (duration: 425.024261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:33.004588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.047145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T18:50:33.004628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.57929Z","time spent":"425.327429ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T18:50:33.004666Z","caller":"traceutil/trace.go:171","msg":"trace[747902762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25656; }","duration":"289.164089ms","start":"2026-05-11T18:50:32.715484Z","end":"2026-05-11T18:50:33.004648Z","steps":["trace[747902762] 'agreement among raft nodes before linearized reading' (duration: 289.012274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:33.004637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.296751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-05-11T18:50:33.004663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.138831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-11T18:50:33.004831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.528648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:33.00489Z","caller":"traceutil/trace.go:171","msg":"trace[906263577] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25656; }","duration":"180.58351ms","start":"2026-05-11T18:50:32.824286Z","end":"2026-05-11T18:50:33.00487Z","steps":["trace[906263577] 'agreement among raft nodes before linearized reading' (duration: 180.501477ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:33.00473Z","caller":"traceutil/trace.go:171","msg":"trace[340531174] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25656; }","duration":"569.437784ms","start":"2026-05-11T18:50:32.435271Z","end":"2026-05-11T18:50:33.004709Z","steps":["trace[340531174] 'agreement among raft nodes before linearized reading' (duration: 569.111445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:33.005006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.435257Z","time spent":"569.733803ms","remote":"127.0.0.1:35524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-11T18:50:33.00483Z","caller":"traceutil/trace.go:171","msg":"trace[1072047221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25656; }","duration":"382.396438ms","start":"2026-05-11T18:50:32.622412Z","end":"2026-05-11T18:50:33.004809Z","steps":["trace[1072047221] 'agreement among raft nodes before linearized reading' (duration: 381.934646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:33.004845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.854258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T18:50:33.005219Z","caller":"traceutil/trace.go:171","msg":"trace[1974401289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25656; }","duration":"428.339721ms","start":"2026-05-11T18:50:32.576854Z","end":"2026-05-11T18:50:33.005194Z","steps":["trace[1974401289] 'agreement among raft nodes before linearized reading' (duration: 427.480158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:33.005335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.576827Z","time spent":"428.490575ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T18:50:33.005106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.622384Z","time spent":"382.708787ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T18:50:33.004752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.536542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-11T18:50:33.005484Z","caller":"traceutil/trace.go:171","msg":"trace[256129302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25656; }","duration":"383.280532ms","start":"2026-05-11T18:50:32.622191Z","end":"2026-05-11T18:50:33.005471Z","steps":["trace[256129302] 'agreement among raft nodes before linearized reading' (duration: 382.47434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:33.004612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.896285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:208"} {"level":"warn","ts":"2026-05-11T18:50:33.005528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.622184Z","time spent":"383.333863ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T18:50:33.005536Z","caller":"traceutil/trace.go:171","msg":"trace[271714387] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:25656; }","duration":"571.874691ms","start":"2026-05-11T18:50:32.433646Z","end":"2026-05-11T18:50:33.005521Z","steps":["trace[271714387] 'agreement among raft nodes before linearized reading' (duration: 570.814263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:33.005569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:32.433625Z","time spent":"571.935753ms","remote":"127.0.0.1:35552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-05-11T18:50:33.299097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.167313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:50:33.299219Z","caller":"traceutil/trace.go:171","msg":"trace[1341600817] linearizableReadLoop","detail":"{readStateIndex:26509; appliedIndex:26508; }","duration":"290.281009ms","start":"2026-05-11T18:50:33.008919Z","end":"2026-05-11T18:50:33.2992Z","steps":["trace[1341600817] 'read index received' (duration: 96.938851ms)","trace[1341600817] 'applied index is now lower than readState.Index' (duration: 193.340638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:33.299517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.591197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-11T18:50:33.299559Z","caller":"traceutil/trace.go:171","msg":"trace[189777991] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25657; }","duration":"290.648538ms","start":"2026-05-11T18:50:33.008899Z","end":"2026-05-11T18:50:33.299548Z","steps":["trace[189777991] 'agreement among raft nodes before linearized reading' (duration: 290.35132ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:33.299773Z","caller":"traceutil/trace.go:171","msg":"trace[2013919908] transaction","detail":"{read_only:false; response_revision:25657; number_of_response:1; }","duration":"293.844725ms","start":"2026-05-11T18:50:33.005913Z","end":"2026-05-11T18:50:33.299758Z","steps":["trace[2013919908] 'process raft request' (duration: 99.909691ms)","trace[2013919908] 'compare' (duration: 192.849215ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:33.300877Z","caller":"traceutil/trace.go:171","msg":"trace[807918117] transaction","detail":"{read_only:false; response_revision:25658; number_of_response:1; }","duration":"291.552084ms","start":"2026-05-11T18:50:33.009287Z","end":"2026-05-11T18:50:33.300839Z","steps":["trace[807918117] 'process raft request' (duration: 291.090691ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:33.301316Z","caller":"traceutil/trace.go:171","msg":"trace[2053109963] transaction","detail":"{read_only:false; response_revision:25659; number_of_response:1; }","duration":"291.450751ms","start":"2026-05-11T18:50:33.009846Z","end":"2026-05-11T18:50:33.301297Z","steps":["trace[2053109963] 'process raft request' (duration: 291.126752ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:33.301493Z","caller":"traceutil/trace.go:171","msg":"trace[452353745] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"289.000284ms","start":"2026-05-11T18:50:33.012485Z","end":"2026-05-11T18:50:33.301485Z","steps":["trace[452353745] 'process raft request' (duration: 288.930562ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:33.301551Z","caller":"traceutil/trace.go:171","msg":"trace[696475345] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"290.624609ms","start":"2026-05-11T18:50:33.01091Z","end":"2026-05-11T18:50:33.301535Z","steps":["trace[696475345] 'process raft request' (duration: 290.475525ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:33.30148Z","caller":"traceutil/trace.go:171","msg":"trace[57285918] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"290.739842ms","start":"2026-05-11T18:50:33.010687Z","end":"2026-05-11T18:50:33.301427Z","steps":["trace[57285918] 'process raft request' (duration: 290.357871ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:33.301612Z","caller":"traceutil/trace.go:171","msg":"trace[392154910] transaction","detail":"{read_only:false; response_revision:25661; number_of_response:1; }","duration":"290.793534ms","start":"2026-05-11T18:50:33.010779Z","end":"2026-05-11T18:50:33.301572Z","steps":["trace[392154910] 'process raft request' (duration: 290.494695ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:33.301882Z","caller":"traceutil/trace.go:171","msg":"trace[57811013] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"211.617645ms","start":"2026-05-11T18:50:33.090247Z","end":"2026-05-11T18:50:33.301865Z","steps":["trace[57811013] 'process raft request' (duration: 211.210464ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:33.301937Z","caller":"traceutil/trace.go:171","msg":"trace[191131591] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"205.462498ms","start":"2026-05-11T18:50:33.096455Z","end":"2026-05-11T18:50:33.301917Z","steps":["trace[191131591] 'process raft request' (duration: 205.274263ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:33.301953Z","caller":"traceutil/trace.go:171","msg":"trace[431529206] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"205.381766ms","start":"2026-05-11T18:50:33.096534Z","end":"2026-05-11T18:50:33.301915Z","steps":["trace[431529206] 'process raft request' (duration: 205.313984ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:34.908516Z","caller":"traceutil/trace.go:171","msg":"trace[932989396] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"189.706709ms","start":"2026-05-11T18:50:34.718776Z","end":"2026-05-11T18:50:34.908483Z","steps":["trace[932989396] 'process raft request' (duration: 189.503924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:35.215044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.624122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:50:35.215322Z","caller":"traceutil/trace.go:171","msg":"trace[739292810] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"205.209441ms","start":"2026-05-11T18:50:35.010093Z","end":"2026-05-11T18:50:35.215302Z","steps":["trace[739292810] 'process raft request' (duration: 205.117279ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:35.215347Z","caller":"traceutil/trace.go:171","msg":"trace[1000452374] transaction","detail":"{read_only:false; response_revision:25671; number_of_response:1; }","duration":"495.46955ms","start":"2026-05-11T18:50:34.719829Z","end":"2026-05-11T18:50:35.215298Z","steps":["trace[1000452374] 'process raft request' (duration: 249.496218ms)","trace[1000452374] 'compare' (duration: 245.471699ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:35.215543Z","caller":"traceutil/trace.go:171","msg":"trace[1793219811] linearizableReadLoop","detail":"{readStateIndex:26523; appliedIndex:26521; }","duration":"435.582587ms","start":"2026-05-11T18:50:34.779909Z","end":"2026-05-11T18:50:35.215491Z","steps":["trace[1793219811] 'read index received' (duration: 128.406939ms)","trace[1793219811] 'applied index is now lower than readState.Index' (duration: 307.169948ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:35.215572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:34.719815Z","time spent":"495.623244ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:35.215871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.941607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1083"} {"level":"info","ts":"2026-05-11T18:50:35.215911Z","caller":"traceutil/trace.go:171","msg":"trace[811449218] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:25672; }","duration":"436.04787ms","start":"2026-05-11T18:50:34.779848Z","end":"2026-05-11T18:50:35.215896Z","steps":["trace[811449218] 'agreement among raft nodes before linearized reading' (duration: 435.832984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:35.215939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:34.779825Z","time spent":"436.107451ms","remote":"127.0.0.1:35552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":1107,"request content":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cluster-operator\" "} {"level":"warn","ts":"2026-05-11T18:50:35.215998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.638279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T18:50:35.216102Z","caller":"traceutil/trace.go:171","msg":"trace[896904271] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25672; }","duration":"337.784423ms","start":"2026-05-11T18:50:34.878295Z","end":"2026-05-11T18:50:35.216079Z","steps":["trace[896904271] 'agreement among raft nodes before linearized reading' (duration: 337.544516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:35.216161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:34.87823Z","time spent":"337.919508ms","remote":"127.0.0.1:35828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":32,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-11T18:50:35.600166Z","caller":"traceutil/trace.go:171","msg":"trace[358604198] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"289.54381ms","start":"2026-05-11T18:50:35.31059Z","end":"2026-05-11T18:50:35.600133Z","steps":["trace[358604198] 'process raft request' (duration: 289.236751ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:35.60052Z","caller":"traceutil/trace.go:171","msg":"trace[725446813] transaction","detail":"{read_only:false; response_revision:25675; number_of_response:1; }","duration":"288.906861ms","start":"2026-05-11T18:50:35.311583Z","end":"2026-05-11T18:50:35.600489Z","steps":["trace[725446813] 'process raft request' (duration: 288.48456ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:35.600982Z","caller":"traceutil/trace.go:171","msg":"trace[1246010600] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"288.366697ms","start":"2026-05-11T18:50:35.312598Z","end":"2026-05-11T18:50:35.600964Z","steps":["trace[1246010600] 'process raft request' (duration: 287.796812ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:35.601063Z","caller":"traceutil/trace.go:171","msg":"trace[76926902] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"287.942175ms","start":"2026-05-11T18:50:35.313101Z","end":"2026-05-11T18:50:35.601043Z","steps":["trace[76926902] 'process raft request' (duration: 287.843633ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:35.601104Z","caller":"traceutil/trace.go:171","msg":"trace[309463713] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"288.389287ms","start":"2026-05-11T18:50:35.312652Z","end":"2026-05-11T18:50:35.601042Z","steps":["trace[309463713] 'process raft request' (duration: 288.199242ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:35.601261Z","caller":"traceutil/trace.go:171","msg":"trace[786981577] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"287.440112ms","start":"2026-05-11T18:50:35.313791Z","end":"2026-05-11T18:50:35.601231Z","steps":["trace[786981577] 'process raft request' (duration: 287.209556ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:35.601432Z","caller":"traceutil/trace.go:171","msg":"trace[1642747027] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"279.924647ms","start":"2026-05-11T18:50:35.321491Z","end":"2026-05-11T18:50:35.601416Z","steps":["trace[1642747027] 'process raft request' (duration: 279.66378ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:35.658479Z","caller":"traceutil/trace.go:171","msg":"trace[149574686] linearizableReadLoop","detail":"{readStateIndex:26533; appliedIndex:26525; }","duration":"185.145344ms","start":"2026-05-11T18:50:35.473307Z","end":"2026-05-11T18:50:35.658452Z","steps":["trace[149574686] 'read index received' (duration: 126.552787ms)","trace[149574686] 'applied index is now lower than readState.Index' (duration: 58.591527ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:35.658796Z","caller":"traceutil/trace.go:171","msg":"trace[576854785] transaction","detail":"{read_only:false; response_revision:25681; number_of_response:1; }","duration":"265.579717ms","start":"2026-05-11T18:50:35.3932Z","end":"2026-05-11T18:50:35.65878Z","steps":["trace[576854785] 'process raft request' (duration: 265.122064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:35.658911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.901079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:35.659Z","caller":"traceutil/trace.go:171","msg":"trace[1765703779] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:25681; }","duration":"170.036213ms","start":"2026-05-11T18:50:35.48894Z","end":"2026-05-11T18:50:35.658976Z","steps":["trace[1765703779] 'agreement among raft nodes before linearized reading' (duration: 169.848278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:35.65929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.129053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T18:50:35.659299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.973018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-11T18:50:35.659324Z","caller":"traceutil/trace.go:171","msg":"trace[1485492153] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25681; }","duration":"120.183245ms","start":"2026-05-11T18:50:35.539129Z","end":"2026-05-11T18:50:35.659313Z","steps":["trace[1485492153] 'agreement among raft nodes before linearized reading' (duration: 120.116873ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:35.659345Z","caller":"traceutil/trace.go:171","msg":"trace[879297741] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25681; }","duration":"186.074261ms","start":"2026-05-11T18:50:35.473257Z","end":"2026-05-11T18:50:35.659331Z","steps":["trace[879297741] 'agreement among raft nodes before linearized reading' (duration: 185.795443ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:50.46158Z","caller":"traceutil/trace.go:171","msg":"trace[218015281] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"154.582002ms","start":"2026-05-11T18:50:50.30697Z","end":"2026-05-11T18:50:50.461552Z","steps":["trace[218015281] 'process raft request' (duration: 154.350346ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:51.438625Z","caller":"traceutil/trace.go:171","msg":"trace[1985152379] linearizableReadLoop","detail":"{readStateIndex:26646; appliedIndex:26645; }","duration":"306.519801ms","start":"2026-05-11T18:50:51.132067Z","end":"2026-05-11T18:50:51.438586Z","steps":["trace[1985152379] 'read index received' (duration: 306.161411ms)","trace[1985152379] 'applied index is now lower than readState.Index' (duration: 356.56µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:51.438943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.830419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:51.438995Z","caller":"traceutil/trace.go:171","msg":"trace[4664602] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25791; }","duration":"306.958554ms","start":"2026-05-11T18:50:51.132024Z","end":"2026-05-11T18:50:51.438983Z","steps":["trace[4664602] 'agreement among raft nodes before linearized reading' (duration: 306.738597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:51.439037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.132004Z","time spent":"307.023376ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-11T18:50:51.43869Z","caller":"traceutil/trace.go:171","msg":"trace[163634986] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"402.746632ms","start":"2026-05-11T18:50:51.035897Z","end":"2026-05-11T18:50:51.438644Z","steps":["trace[163634986] 'process raft request' (duration: 402.419344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:51.439247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.035861Z","time spent":"403.284148ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:51.660571Z","caller":"traceutil/trace.go:171","msg":"trace[352243720] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"381.560207ms","start":"2026-05-11T18:50:51.278973Z","end":"2026-05-11T18:50:51.660533Z","steps":["trace[352243720] 'process raft request' (duration: 379.49914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:51.660766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.278938Z","time spent":"381.728462ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:51.660775Z","caller":"traceutil/trace.go:171","msg":"trace[1795986395] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"214.973298ms","start":"2026-05-11T18:50:51.445785Z","end":"2026-05-11T18:50:51.660759Z","steps":["trace[1795986395] 'process raft request' (duration: 214.869675ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:51.660919Z","caller":"traceutil/trace.go:171","msg":"trace[94846227] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"374.865044ms","start":"2026-05-11T18:50:51.286043Z","end":"2026-05-11T18:50:51.660908Z","steps":["trace[94846227] 'process raft request' (duration: 374.412132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:51.660998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.285974Z","time spent":"374.976577ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:51.661031Z","caller":"traceutil/trace.go:171","msg":"trace[213670110] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"121.393958ms","start":"2026-05-11T18:50:51.5396Z","end":"2026-05-11T18:50:51.660994Z","steps":["trace[213670110] 'process raft request' (duration: 121.115421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:51.661202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.352652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:51.66125Z","caller":"traceutil/trace.go:171","msg":"trace[383623567] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25795; }","duration":"493.437354ms","start":"2026-05-11T18:50:51.167802Z","end":"2026-05-11T18:50:51.661239Z","steps":["trace[383623567] 'agreement among raft nodes before linearized reading' (duration: 493.322441ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:51.661098Z","caller":"traceutil/trace.go:171","msg":"trace[2032960644] linearizableReadLoop","detail":"{readStateIndex:26648; appliedIndex:26646; }","duration":"222.356359ms","start":"2026-05-11T18:50:51.438716Z","end":"2026-05-11T18:50:51.661073Z","steps":["trace[2032960644] 'read index received' (duration: 219.781599ms)","trace[2032960644] 'applied index is now lower than readState.Index' (duration: 2.57387ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:51.661283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.167787Z","time spent":"493.487496ms","remote":"127.0.0.1:34334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":124,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true "} {"level":"warn","ts":"2026-05-11T18:50:51.66137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.681373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:51.661464Z","caller":"traceutil/trace.go:171","msg":"trace[732177585] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25795; }","duration":"282.839317ms","start":"2026-05-11T18:50:51.378606Z","end":"2026-05-11T18:50:51.661445Z","steps":["trace[732177585] 'agreement among raft nodes before linearized reading' (duration: 282.667332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:51.661516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.684265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T18:50:51.661563Z","caller":"traceutil/trace.go:171","msg":"trace[309742256] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25795; }","duration":"201.779257ms","start":"2026-05-11T18:50:51.459772Z","end":"2026-05-11T18:50:51.661551Z","steps":["trace[309742256] 'agreement among raft nodes before linearized reading' (duration: 201.682305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:51.661405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.228093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:51.661656Z","caller":"traceutil/trace.go:171","msg":"trace[292965622] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25795; }","duration":"212.550162ms","start":"2026-05-11T18:50:51.449084Z","end":"2026-05-11T18:50:51.661634Z","steps":["trace[292965622] 'agreement among raft nodes before linearized reading' (duration: 212.275834ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:52.10011Z","caller":"traceutil/trace.go:171","msg":"trace[1736737704] transaction","detail":"{read_only:false; response_revision:25796; number_of_response:1; }","duration":"435.373592ms","start":"2026-05-11T18:50:51.664702Z","end":"2026-05-11T18:50:52.100075Z","steps":["trace[1736737704] 'process raft request' (duration: 435.151455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.100483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.664674Z","time spent":"435.65838ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1633,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T18:50:52.41468Z","caller":"traceutil/trace.go:171","msg":"trace[1277556760] linearizableReadLoop","detail":"{readStateIndex:26654; appliedIndex:26650; }","duration":"726.675319ms","start":"2026-05-11T18:50:51.687978Z","end":"2026-05-11T18:50:52.414653Z","steps":["trace[1277556760] 'read index received' (duration: 411.81665ms)","trace[1277556760] 'applied index is now lower than readState.Index' (duration: 314.857339ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:52.414804Z","caller":"traceutil/trace.go:171","msg":"trace[1189737940] transaction","detail":"{read_only:false; response_revision:25797; number_of_response:1; }","duration":"745.913562ms","start":"2026-05-11T18:50:51.668796Z","end":"2026-05-11T18:50:52.41471Z","steps":["trace[1189737940] 'process raft request' (duration: 678.459434ms)","trace[1189737940] 'compare' (duration: 66.395449ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:52.414887Z","caller":"traceutil/trace.go:171","msg":"trace[84905320] transaction","detail":"{read_only:false; response_revision:25798; number_of_response:1; }","duration":"743.398313ms","start":"2026-05-11T18:50:51.67147Z","end":"2026-05-11T18:50:52.414868Z","steps":["trace[84905320] 'process raft request' (duration: 743.072295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.415031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.668771Z","time spent":"746.138949ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:52.415051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.671448Z","time spent":"743.477116ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:52.415362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.232445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-11T18:50:52.415327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.323277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T18:50:52.415405Z","caller":"traceutil/trace.go:171","msg":"trace[1862795076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25799; }","duration":"719.292197ms","start":"2026-05-11T18:50:51.696098Z","end":"2026-05-11T18:50:52.41539Z","steps":["trace[1862795076] 'agreement among raft nodes before linearized reading' (duration: 719.127703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.415436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.696088Z","time spent":"719.341489ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T18:50:52.415434Z","caller":"traceutil/trace.go:171","msg":"trace[248871618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25799; }","duration":"727.504221ms","start":"2026-05-11T18:50:51.687906Z","end":"2026-05-11T18:50:52.41541Z","steps":["trace[248871618] 'agreement among raft nodes before linearized reading' (duration: 727.249064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.41548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.687885Z","time spent":"727.587384ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T18:50:52.41553Z","caller":"traceutil/trace.go:171","msg":"trace[2090392966] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"742.671194ms","start":"2026-05-11T18:50:51.672846Z","end":"2026-05-11T18:50:52.415517Z","steps":["trace[2090392966] 'process raft request' (duration: 741.755889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.415603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.672838Z","time spent":"742.725715ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:52.415678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.631976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-05-11T18:50:52.415712Z","caller":"traceutil/trace.go:171","msg":"trace[1968086884] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25799; }","duration":"309.722078ms","start":"2026-05-11T18:50:52.105976Z","end":"2026-05-11T18:50:52.415698Z","steps":["trace[1968086884] 'agreement among raft nodes before linearized reading' (duration: 309.627515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.415716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.8754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T18:50:52.415746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:52.105957Z","time spent":"309.77914ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1675,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-11T18:50:52.415754Z","caller":"traceutil/trace.go:171","msg":"trace[1371112396] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25799; }","duration":"535.964842ms","start":"2026-05-11T18:50:51.879776Z","end":"2026-05-11T18:50:52.415741Z","steps":["trace[1371112396] 'agreement among raft nodes before linearized reading' (duration: 535.89655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.415789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.87975Z","time spent":"536.030974ms","remote":"127.0.0.1:42282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"warn","ts":"2026-05-11T18:50:52.415812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.135841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:52.415855Z","caller":"traceutil/trace.go:171","msg":"trace[1834896370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25799; }","duration":"186.218563ms","start":"2026-05-11T18:50:52.229625Z","end":"2026-05-11T18:50:52.415844Z","steps":["trace[1834896370] 'agreement among raft nodes before linearized reading' (duration: 186.13616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.415982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.860892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-11T18:50:52.416015Z","caller":"traceutil/trace.go:171","msg":"trace[686830745] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:25799; }","duration":"287.912834ms","start":"2026-05-11T18:50:52.128092Z","end":"2026-05-11T18:50:52.416005Z","steps":["trace[686830745] 'agreement among raft nodes before linearized reading' (duration: 287.816001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.416019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.376696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-11T18:50:52.41605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.442134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:52.416057Z","caller":"traceutil/trace.go:171","msg":"trace[1512595677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25799; }","duration":"673.433987ms","start":"2026-05-11T18:50:51.74261Z","end":"2026-05-11T18:50:52.416044Z","steps":["trace[1512595677] 'agreement among raft nodes before linearized reading' (duration: 673.333344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.416091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.742594Z","time spent":"673.491509ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T18:50:52.416121Z","caller":"traceutil/trace.go:171","msg":"trace[1236447684] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25799; }","duration":"159.532807ms","start":"2026-05-11T18:50:52.256541Z","end":"2026-05-11T18:50:52.416074Z","steps":["trace[1236447684] 'agreement among raft nodes before linearized reading' (duration: 159.471215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.416233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.802896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"warn","ts":"2026-05-11T18:50:52.416262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.093601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T18:50:52.41627Z","caller":"traceutil/trace.go:171","msg":"trace[972244545] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25799; }","duration":"307.846268ms","start":"2026-05-11T18:50:52.108409Z","end":"2026-05-11T18:50:52.416256Z","steps":["trace[972244545] 'agreement among raft nodes before linearized reading' (duration: 307.757695ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:52.416298Z","caller":"traceutil/trace.go:171","msg":"trace[1779121130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25799; }","duration":"719.149113ms","start":"2026-05-11T18:50:51.697136Z","end":"2026-05-11T18:50:52.416285Z","steps":["trace[1779121130] 'agreement among raft nodes before linearized reading' (duration: 719.05035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.416329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.697125Z","time spent":"719.198644ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T18:50:52.416488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.325715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T18:50:52.416514Z","caller":"traceutil/trace.go:171","msg":"trace[283196960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25799; }","duration":"720.382136ms","start":"2026-05-11T18:50:51.696123Z","end":"2026-05-11T18:50:52.416505Z","steps":["trace[283196960] 'agreement among raft nodes before linearized reading' (duration: 720.294504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.416538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:51.69611Z","time spent":"720.423028ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T18:50:52.416301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:52.108401Z","time spent":"307.893539ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1675,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-11T18:50:52.775954Z","caller":"traceutil/trace.go:171","msg":"trace[1670270248] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"354.210612ms","start":"2026-05-11T18:50:52.4217Z","end":"2026-05-11T18:50:52.77591Z","steps":["trace[1670270248] 'process raft request' (duration: 353.923194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.776164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:52.421679Z","time spent":"354.372395ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:52.776217Z","caller":"traceutil/trace.go:171","msg":"trace[1436410326] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"353.376488ms","start":"2026-05-11T18:50:52.422832Z","end":"2026-05-11T18:50:52.776209Z","steps":["trace[1436410326] 'process raft request' (duration: 353.136781ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:52.776141Z","caller":"traceutil/trace.go:171","msg":"trace[1097204771] transaction","detail":"{read_only:false; response_revision:25806; number_of_response:1; }","duration":"308.735802ms","start":"2026-05-11T18:50:52.467374Z","end":"2026-05-11T18:50:52.77611Z","steps":["trace[1097204771] 'process raft request' (duration: 308.690931ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:52.776283Z","caller":"traceutil/trace.go:171","msg":"trace[1907500935] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"353.536773ms","start":"2026-05-11T18:50:52.422675Z","end":"2026-05-11T18:50:52.776212Z","steps":["trace[1907500935] 'process raft request' (duration: 353.252375ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:52.776315Z","caller":"traceutil/trace.go:171","msg":"trace[1630228007] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"354.265183ms","start":"2026-05-11T18:50:52.421976Z","end":"2026-05-11T18:50:52.776241Z","steps":["trace[1630228007] 'process raft request' (duration: 353.867612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.776367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:52.422823Z","time spent":"353.43574ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:52.776453Z","caller":"traceutil/trace.go:171","msg":"trace[1641404529] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"354.336585ms","start":"2026-05-11T18:50:52.42209Z","end":"2026-05-11T18:50:52.776426Z","steps":["trace[1641404529] 'process raft request' (duration: 353.79775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.776525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:52.422664Z","time spent":"353.756538ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:52.776561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:52.422082Z","time spent":"354.426488ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:52.776561Z","caller":"traceutil/trace.go:171","msg":"trace[2130893337] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"351.641161ms","start":"2026-05-11T18:50:52.424908Z","end":"2026-05-11T18:50:52.77655Z","steps":["trace[2130893337] 'process raft request' (duration: 351.097506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.776538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:52.467354Z","time spent":"309.019169ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:52.776644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:52.424867Z","time spent":"351.739683ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1946,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:52.776547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:52.421967Z","time spent":"354.449888ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:50:52.798419Z","caller":"traceutil/trace.go:171","msg":"trace[1431265430] linearizableReadLoop","detail":"{readStateIndex:26663; appliedIndex:26655; }","duration":"293.553218ms","start":"2026-05-11T18:50:52.504841Z","end":"2026-05-11T18:50:52.798395Z","steps":["trace[1431265430] 'read index received' (duration: 270.824769ms)","trace[1431265430] 'applied index is now lower than readState.Index' (duration: 22.727039ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:52.798578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.716792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:52.798626Z","caller":"traceutil/trace.go:171","msg":"trace[1960282667] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25807; }","duration":"293.811685ms","start":"2026-05-11T18:50:52.504801Z","end":"2026-05-11T18:50:52.798613Z","steps":["trace[1960282667] 'agreement among raft nodes before linearized reading' (duration: 293.673621ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:52.798715Z","caller":"traceutil/trace.go:171","msg":"trace[1780822713] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"307.732125ms","start":"2026-05-11T18:50:52.490719Z","end":"2026-05-11T18:50:52.798451Z","steps":["trace[1780822713] 'process raft request' (duration: 307.57216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:52.799134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:52.490705Z","time spent":"308.129215ms","remote":"127.0.0.1:35374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:53.163416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.821499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:50:53.163554Z","caller":"traceutil/trace.go:171","msg":"trace[1383686372] linearizableReadLoop","detail":"{readStateIndex:26664; appliedIndex:26663; }","duration":"361.603643ms","start":"2026-05-11T18:50:52.801929Z","end":"2026-05-11T18:50:53.163532Z","steps":["trace[1383686372] 'read index received' (duration: 170.485216ms)","trace[1383686372] 'applied index is now lower than readState.Index' (duration: 191.116907ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:53.163693Z","caller":"traceutil/trace.go:171","msg":"trace[479644611] transaction","detail":"{read_only:false; response_revision:25808; number_of_response:1; }","duration":"377.786444ms","start":"2026-05-11T18:50:52.785863Z","end":"2026-05-11T18:50:53.163649Z","steps":["trace[479644611] 'process raft request' (duration: 186.593925ms)","trace[479644611] 'compare' (duration: 190.609893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:53.163848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.922571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-11T18:50:53.163884Z","caller":"traceutil/trace.go:171","msg":"trace[369452346] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25808; }","duration":"361.962142ms","start":"2026-05-11T18:50:52.801911Z","end":"2026-05-11T18:50:53.163873Z","steps":["trace[369452346] 'agreement among raft nodes before linearized reading' (duration: 361.677034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:53.163924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:52.801863Z","time spent":"362.052255ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":447,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-11T18:50:53.163946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:52.785838Z","time spent":"377.963378ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2793,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:50:53.416794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.899444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:131041"} {"level":"info","ts":"2026-05-11T18:50:53.416865Z","caller":"traceutil/trace.go:171","msg":"trace[1117008353] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25809; }","duration":"150.013108ms","start":"2026-05-11T18:50:53.266831Z","end":"2026-05-11T18:50:53.416844Z","steps":["trace[1117008353] 'range keys from in-memory index tree' (duration: 149.695798ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:53.417222Z","caller":"traceutil/trace.go:171","msg":"trace[1167946897] transaction","detail":"{read_only:false; response_revision:25810; number_of_response:1; }","duration":"150.11758ms","start":"2026-05-11T18:50:53.267071Z","end":"2026-05-11T18:50:53.417189Z","steps":["trace[1167946897] 'process raft request' (duration: 58.215746ms)","trace[1167946897] 'compare' (duration: 91.73253ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:53.668693Z","caller":"traceutil/trace.go:171","msg":"trace[420395567] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"399.675539ms","start":"2026-05-11T18:50:53.268991Z","end":"2026-05-11T18:50:53.668666Z","steps":["trace[420395567] 'process raft request' (duration: 399.558746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:53.668863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:53.268975Z","time spent":"399.807333ms","remote":"127.0.0.1:35638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1237,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T18:50:53.669811Z","caller":"traceutil/trace.go:171","msg":"trace[927452778] linearizableReadLoop","detail":"{readStateIndex:26669; appliedIndex:26666; }","duration":"244.051499ms","start":"2026-05-11T18:50:53.425736Z","end":"2026-05-11T18:50:53.669788Z","steps":["trace[927452778] 'read index received' (duration: 184.429886ms)","trace[927452778] 'applied index is now lower than readState.Index' (duration: 59.620543ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:50:53.669967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.209973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:50:53.669241Z","caller":"traceutil/trace.go:171","msg":"trace[1941836970] transaction","detail":"{read_only:false; response_revision:25811; number_of_response:1; }","duration":"401.085807ms","start":"2026-05-11T18:50:53.267692Z","end":"2026-05-11T18:50:53.668778Z","steps":["trace[1941836970] 'process raft request' (duration: 342.384459ms)","trace[1941836970] 'compare' (duration: 57.904977ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:53.670209Z","caller":"traceutil/trace.go:171","msg":"trace[2146689559] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"224.580899ms","start":"2026-05-11T18:50:53.44562Z","end":"2026-05-11T18:50:53.670201Z","steps":["trace[2146689559] 'process raft request' (duration: 224.108966ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:53.67Z","caller":"traceutil/trace.go:171","msg":"trace[1964968207] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25814; }","duration":"244.317866ms","start":"2026-05-11T18:50:53.425673Z","end":"2026-05-11T18:50:53.669991Z","steps":["trace[1964968207] 'agreement among raft nodes before linearized reading' (duration: 244.209643ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:53.670069Z","caller":"traceutil/trace.go:171","msg":"trace[2002244900] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"249.467967ms","start":"2026-05-11T18:50:53.420594Z","end":"2026-05-11T18:50:53.670062Z","steps":["trace[2002244900] 'process raft request' (duration: 248.8416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:53.67207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:50:53.267659Z","time spent":"402.759743ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T18:50:56.989482Z","caller":"traceutil/trace.go:171","msg":"trace[2131119454] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"194.346026ms","start":"2026-05-11T18:50:56.795096Z","end":"2026-05-11T18:50:56.989442Z","steps":["trace[2131119454] 'process raft request' (duration: 163.800673ms)","trace[2131119454] 'compare' (duration: 30.177453ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:56.989579Z","caller":"traceutil/trace.go:171","msg":"trace[597990109] linearizableReadLoop","detail":"{readStateIndex:26749; appliedIndex:26743; }","duration":"167.290419ms","start":"2026-05-11T18:50:56.822265Z","end":"2026-05-11T18:50:56.989555Z","steps":["trace[597990109] 'read index received' (duration: 2.54303ms)","trace[597990109] 'applied index is now lower than readState.Index' (duration: 164.746429ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:50:56.989673Z","caller":"traceutil/trace.go:171","msg":"trace[1200577143] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"193.151553ms","start":"2026-05-11T18:50:56.796514Z","end":"2026-05-11T18:50:56.989665Z","steps":["trace[1200577143] 'process raft request' (duration: 192.882075ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:56.989747Z","caller":"traceutil/trace.go:171","msg":"trace[486585162] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"191.962431ms","start":"2026-05-11T18:50:56.797752Z","end":"2026-05-11T18:50:56.989714Z","steps":["trace[486585162] 'process raft request' (duration: 191.761556ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:56.989606Z","caller":"traceutil/trace.go:171","msg":"trace[594760466] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"192.957727ms","start":"2026-05-11T18:50:56.796622Z","end":"2026-05-11T18:50:56.98958Z","steps":["trace[594760466] 'process raft request' (duration: 192.832444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:56.990192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.909595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-11T18:50:56.990251Z","caller":"traceutil/trace.go:171","msg":"trace[1495641778] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25893; }","duration":"168.000087ms","start":"2026-05-11T18:50:56.822238Z","end":"2026-05-11T18:50:56.990238Z","steps":["trace[1495641778] 'agreement among raft nodes before linearized reading' (duration: 167.757631ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:50:56.989878Z","caller":"traceutil/trace.go:171","msg":"trace[1196120176] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"193.722488ms","start":"2026-05-11T18:50:56.795756Z","end":"2026-05-11T18:50:56.989478Z","steps":["trace[1196120176] 'process raft request' (duration: 193.550033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:50:56.993565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.336298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-11T18:50:56.993647Z","caller":"traceutil/trace.go:171","msg":"trace[1087290315] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25893; }","duration":"131.456661ms","start":"2026-05-11T18:50:56.862174Z","end":"2026-05-11T18:50:56.993631Z","steps":["trace[1087290315] 'agreement among raft nodes before linearized reading' (duration: 129.369945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:02.019512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.302371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:51:02.019798Z","caller":"traceutil/trace.go:171","msg":"trace[267688582] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"294.265418ms","start":"2026-05-11T18:51:01.725517Z","end":"2026-05-11T18:51:02.019782Z","steps":["trace[267688582] 'process raft request' (duration: 294.095722ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:02.019903Z","caller":"traceutil/trace.go:171","msg":"trace[46989798] transaction","detail":"{read_only:false; response_revision:25948; number_of_response:1; }","duration":"297.062344ms","start":"2026-05-11T18:51:01.722792Z","end":"2026-05-11T18:51:02.019854Z","steps":["trace[46989798] 'process raft request' (duration: 65.34193ms)","trace[46989798] 'compare' (duration: 230.850009ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:02.020104Z","caller":"traceutil/trace.go:171","msg":"trace[1227983370] transaction","detail":"{read_only:false; response_revision:25950; number_of_response:1; }","duration":"294.134774ms","start":"2026-05-11T18:51:01.725941Z","end":"2026-05-11T18:51:02.020076Z","steps":["trace[1227983370] 'process raft request' (duration: 293.758833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:18.602933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.136738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:51:18.603201Z","caller":"traceutil/trace.go:171","msg":"trace[750793825] transaction","detail":"{read_only:false; response_revision:26074; number_of_response:1; }","duration":"524.920852ms","start":"2026-05-11T18:51:18.078219Z","end":"2026-05-11T18:51:18.603139Z","steps":["trace[750793825] 'process raft request' (duration: 383.502709ms)","trace[750793825] 'compare' (duration: 139.961554ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:18.603247Z","caller":"traceutil/trace.go:171","msg":"trace[384925414] transaction","detail":"{read_only:false; response_revision:26075; number_of_response:1; }","duration":"522.600178ms","start":"2026-05-11T18:51:18.080628Z","end":"2026-05-11T18:51:18.603228Z","steps":["trace[384925414] 'process raft request' (duration: 522.440604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:18.603312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:18.080597Z","time spent":"522.685471ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:51:18.603376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:18.07818Z","time spent":"525.097676ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:51:18.622513Z","caller":"traceutil/trace.go:171","msg":"trace[1572580857] transaction","detail":"{read_only:false; response_revision:26076; number_of_response:1; }","duration":"108.64344ms","start":"2026-05-11T18:51:18.513841Z","end":"2026-05-11T18:51:18.622485Z","steps":["trace[1572580857] 'process raft request' (duration: 108.471345ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:18.785448Z","caller":"traceutil/trace.go:171","msg":"trace[1246160683] transaction","detail":"{read_only:false; response_revision:26078; number_of_response:1; }","duration":"265.485903ms","start":"2026-05-11T18:51:18.519931Z","end":"2026-05-11T18:51:18.785416Z","steps":["trace[1246160683] 'process raft request' (duration: 265.295948ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:18.785594Z","caller":"traceutil/trace.go:171","msg":"trace[1623288608] transaction","detail":"{read_only:false; response_revision:26079; number_of_response:1; }","duration":"237.555242ms","start":"2026-05-11T18:51:18.548013Z","end":"2026-05-11T18:51:18.785569Z","steps":["trace[1623288608] 'process raft request' (duration: 237.348596ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:18.785634Z","caller":"traceutil/trace.go:171","msg":"trace[778630007] linearizableReadLoop","detail":"{readStateIndex:26940; appliedIndex:26937; }","duration":"244.594004ms","start":"2026-05-11T18:51:18.541012Z","end":"2026-05-11T18:51:18.785606Z","steps":["trace[778630007] 'read index received' (duration: 81.294295ms)","trace[778630007] 'applied index is now lower than readState.Index' (duration: 163.298379ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:18.785706Z","caller":"traceutil/trace.go:171","msg":"trace[1397364646] transaction","detail":"{read_only:false; response_revision:26080; number_of_response:1; }","duration":"171.588585ms","start":"2026-05-11T18:51:18.61406Z","end":"2026-05-11T18:51:18.785649Z","steps":["trace[1397364646] 'process raft request' (duration: 171.434661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:18.785832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.798129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:18.785888Z","caller":"traceutil/trace.go:171","msg":"trace[1375276715] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26080; }","duration":"244.929183ms","start":"2026-05-11T18:51:18.540944Z","end":"2026-05-11T18:51:18.785873Z","steps":["trace[1375276715] 'agreement among raft nodes before linearized reading' (duration: 244.81314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:18.785913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.309861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:18.785574Z","caller":"traceutil/trace.go:171","msg":"trace[1487297767] transaction","detail":"{read_only:false; response_revision:26077; number_of_response:1; }","duration":"265.969207ms","start":"2026-05-11T18:51:18.519432Z","end":"2026-05-11T18:51:18.785401Z","steps":["trace[1487297767] 'process raft request' (duration: 252.547121ms)","trace[1487297767] 'compare' (duration: 12.894131ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:18.786139Z","caller":"traceutil/trace.go:171","msg":"trace[1714911710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26080; }","duration":"144.58008ms","start":"2026-05-11T18:51:18.641541Z","end":"2026-05-11T18:51:18.786121Z","steps":["trace[1714911710] 'agreement among raft nodes before linearized reading' (duration: 144.332122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:19.25962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.077248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-11T18:51:19.259723Z","caller":"traceutil/trace.go:171","msg":"trace[449878160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26081; }","duration":"158.238822ms","start":"2026-05-11T18:51:19.101461Z","end":"2026-05-11T18:51:19.2597Z","steps":["trace[449878160] 'range keys from in-memory index tree' (duration: 157.743208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:19.260061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.199989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T18:51:19.260105Z","caller":"traceutil/trace.go:171","msg":"trace[2038520322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26081; }","duration":"155.291822ms","start":"2026-05-11T18:51:19.104801Z","end":"2026-05-11T18:51:19.260093Z","steps":["trace[2038520322] 'range keys from in-memory index tree' (duration: 155.040305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:19.260654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.308818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T18:51:19.260655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.049277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-11T18:51:19.260704Z","caller":"traceutil/trace.go:171","msg":"trace[20084614] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:26081; }","duration":"102.38501ms","start":"2026-05-11T18:51:19.158306Z","end":"2026-05-11T18:51:19.260691Z","steps":["trace[20084614] 'count revisions from in-memory index tree' (duration: 102.245866ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:19.260756Z","caller":"traceutil/trace.go:171","msg":"trace[559444222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26081; }","duration":"147.236312ms","start":"2026-05-11T18:51:19.113488Z","end":"2026-05-11T18:51:19.260724Z","steps":["trace[559444222] 'range keys from in-memory index tree' (duration: 146.79714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:19.260927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.664696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-11T18:51:19.260961Z","caller":"traceutil/trace.go:171","msg":"trace[516825551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26081; }","duration":"146.716387ms","start":"2026-05-11T18:51:19.114234Z","end":"2026-05-11T18:51:19.26095Z","steps":["trace[516825551] 'range keys from in-memory index tree' (duration: 146.553563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:19.261088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.217043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T18:51:19.261122Z","caller":"traceutil/trace.go:171","msg":"trace[682120787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26081; }","duration":"146.270485ms","start":"2026-05-11T18:51:19.114843Z","end":"2026-05-11T18:51:19.261114Z","steps":["trace[682120787] 'range keys from in-memory index tree' (duration: 146.069629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:24.992594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.051266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:51:24.992994Z","caller":"traceutil/trace.go:171","msg":"trace[700383071] transaction","detail":"{read_only:false; response_revision:26134; number_of_response:1; }","duration":"183.415127ms","start":"2026-05-11T18:51:24.809552Z","end":"2026-05-11T18:51:24.992967Z","steps":["trace[700383071] 'process raft request' (duration: 183.366045ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:24.993299Z","caller":"traceutil/trace.go:171","msg":"trace[2126571612] transaction","detail":"{read_only:false; response_revision:26131; number_of_response:1; }","duration":"369.255291ms","start":"2026-05-11T18:51:24.624031Z","end":"2026-05-11T18:51:24.993287Z","steps":["trace[2126571612] 'process raft request' (duration: 119.368713ms)","trace[2126571612] 'compare' (duration: 248.856411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:51:24.993372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:24.624022Z","time spent":"369.311752ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:51:24.993671Z","caller":"traceutil/trace.go:171","msg":"trace[2000440476] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"356.03662ms","start":"2026-05-11T18:51:24.637624Z","end":"2026-05-11T18:51:24.993661Z","steps":["trace[2000440476] 'process raft request' (duration: 355.122166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:24.993739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:24.637606Z","time spent":"356.100122ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:51:24.993888Z","caller":"traceutil/trace.go:171","msg":"trace[214654813] linearizableReadLoop","detail":"{readStateIndex:26994; appliedIndex:26992; }","duration":"367.725019ms","start":"2026-05-11T18:51:24.626147Z","end":"2026-05-11T18:51:24.993872Z","steps":["trace[214654813] 'read index received' (duration: 63.4779ms)","trace[214654813] 'applied index is now lower than readState.Index' (duration: 304.246019ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:24.99396Z","caller":"traceutil/trace.go:171","msg":"trace[1323495426] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"185.71097ms","start":"2026-05-11T18:51:24.808239Z","end":"2026-05-11T18:51:24.99395Z","steps":["trace[1323495426] 'process raft request' (duration: 184.597159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:24.994269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.11504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:24.994312Z","caller":"traceutil/trace.go:171","msg":"trace[1665084608] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:26134; }","duration":"368.185122ms","start":"2026-05-11T18:51:24.626117Z","end":"2026-05-11T18:51:24.994302Z","steps":["trace[1665084608] 'agreement among raft nodes before linearized reading' (duration: 368.10889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:24.994352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:24.626103Z","time spent":"368.240154ms","remote":"127.0.0.1:56508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-11T18:51:24.995752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.054748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T18:51:24.995855Z","caller":"traceutil/trace.go:171","msg":"trace[425486314] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:26135; }","duration":"299.247264ms","start":"2026-05-11T18:51:24.696582Z","end":"2026-05-11T18:51:24.995829Z","steps":["trace[425486314] 'agreement among raft nodes before linearized reading' (duration: 299.009577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:24.996214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.191979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T18:51:24.996249Z","caller":"traceutil/trace.go:171","msg":"trace[1082606243] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26135; }","duration":"224.285081ms","start":"2026-05-11T18:51:24.771953Z","end":"2026-05-11T18:51:24.996238Z","steps":["trace[1082606243] 'agreement among raft nodes before linearized reading' (duration: 224.217049ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:25.526204Z","caller":"traceutil/trace.go:171","msg":"trace[737656762] transaction","detail":"{read_only:false; response_revision:26140; number_of_response:1; }","duration":"133.725673ms","start":"2026-05-11T18:51:25.392457Z","end":"2026-05-11T18:51:25.526183Z","steps":["trace[737656762] 'process raft request' (duration: 133.686751ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:25.526424Z","caller":"traceutil/trace.go:171","msg":"trace[1656357691] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"190.927433ms","start":"2026-05-11T18:51:25.335488Z","end":"2026-05-11T18:51:25.526415Z","steps":["trace[1656357691] 'process raft request' (duration: 119.050574ms)","trace[1656357691] 'compare' (duration: 71.390815ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:25.989714Z","caller":"traceutil/trace.go:171","msg":"trace[952558598] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"163.080043ms","start":"2026-05-11T18:51:25.826595Z","end":"2026-05-11T18:51:25.989676Z","steps":["trace[952558598] 'process raft request' (duration: 162.722654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:26.166197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.949313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-05-11T18:51:26.166271Z","caller":"traceutil/trace.go:171","msg":"trace[632282885] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26142; }","duration":"122.078406ms","start":"2026-05-11T18:51:26.044175Z","end":"2026-05-11T18:51:26.166254Z","steps":["trace[632282885] 'range keys from in-memory index tree' (duration: 121.744018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:26.902858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.290627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-05-11T18:51:26.902953Z","caller":"traceutil/trace.go:171","msg":"trace[36513540] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:26144; }","duration":"102.408741ms","start":"2026-05-11T18:51:26.800518Z","end":"2026-05-11T18:51:26.902927Z","steps":["trace[36513540] 'range keys from in-memory index tree' (duration: 102.101392ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:27.139084Z","caller":"traceutil/trace.go:171","msg":"trace[1471698859] transaction","detail":"{read_only:false; response_revision:26147; number_of_response:1; }","duration":"138.733169ms","start":"2026-05-11T18:51:27.000328Z","end":"2026-05-11T18:51:27.139061Z","steps":["trace[1471698859] 'process raft request' (duration: 138.621406ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:27.13909Z","caller":"traceutil/trace.go:171","msg":"trace[1632695408] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"139.100449ms","start":"2026-05-11T18:51:26.999946Z","end":"2026-05-11T18:51:27.139047Z","steps":["trace[1632695408] 'process raft request' (duration: 138.847152ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:27.139632Z","caller":"traceutil/trace.go:171","msg":"trace[1797100993] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"138.02741ms","start":"2026-05-11T18:51:27.001587Z","end":"2026-05-11T18:51:27.139614Z","steps":["trace[1797100993] 'process raft request' (duration: 137.407213ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:27.139701Z","caller":"traceutil/trace.go:171","msg":"trace[1367404362] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"137.250819ms","start":"2026-05-11T18:51:27.002436Z","end":"2026-05-11T18:51:27.139687Z","steps":["trace[1367404362] 'process raft request' (duration: 136.839508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:27.434281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.432058ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-11T18:51:27.434613Z","caller":"traceutil/trace.go:171","msg":"trace[747894216] linearizableReadLoop","detail":"{readStateIndex:27014; appliedIndex:27013; }","duration":"200.02105ms","start":"2026-05-11T18:51:27.234555Z","end":"2026-05-11T18:51:27.434576Z","steps":["trace[747894216] 'read index received' (duration: 70.312606ms)","trace[747894216] 'applied index is now lower than readState.Index' (duration: 129.703594ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:51:27.434739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.177954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:27.434812Z","caller":"traceutil/trace.go:171","msg":"trace[127671497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26150; }","duration":"200.316348ms","start":"2026-05-11T18:51:27.234479Z","end":"2026-05-11T18:51:27.434795Z","steps":["trace[127671497] 'agreement among raft nodes before linearized reading' (duration: 200.190025ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:27.546914Z","caller":"traceutil/trace.go:171","msg":"trace[546773616] transaction","detail":"{read_only:false; response_revision:26151; number_of_response:1; }","duration":"108.193589ms","start":"2026-05-11T18:51:27.438705Z","end":"2026-05-11T18:51:27.546898Z","steps":["trace[546773616] 'process raft request' (duration: 108.050855ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:27.578249Z","caller":"traceutil/trace.go:171","msg":"trace[955534308] transaction","detail":"{read_only:false; response_revision:26153; number_of_response:1; }","duration":"138.861844ms","start":"2026-05-11T18:51:27.439378Z","end":"2026-05-11T18:51:27.57824Z","steps":["trace[955534308] 'process raft request' (duration: 138.806253ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:27.57832Z","caller":"traceutil/trace.go:171","msg":"trace[1955044733] transaction","detail":"{read_only:false; response_revision:26152; number_of_response:1; }","duration":"139.013218ms","start":"2026-05-11T18:51:27.439244Z","end":"2026-05-11T18:51:27.578258Z","steps":["trace[1955044733] 'process raft request' (duration: 133.134598ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:32.927224Z","caller":"traceutil/trace.go:171","msg":"trace[123334856] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"161.914071ms","start":"2026-05-11T18:51:32.765282Z","end":"2026-05-11T18:51:32.927196Z","steps":["trace[123334856] 'process raft request' (duration: 161.719356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:33.105949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.375275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:33.10607Z","caller":"traceutil/trace.go:171","msg":"trace[1505584415] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:26189; }","duration":"107.570181ms","start":"2026-05-11T18:51:32.998477Z","end":"2026-05-11T18:51:33.106047Z","steps":["trace[1505584415] 'count revisions from in-memory index tree' (duration: 107.270853ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:33.339217Z","caller":"traceutil/trace.go:171","msg":"trace[1758033883] transaction","detail":"{read_only:false; response_revision:26190; number_of_response:1; }","duration":"258.753109ms","start":"2026-05-11T18:51:33.080444Z","end":"2026-05-11T18:51:33.339197Z","steps":["trace[1758033883] 'process raft request' (duration: 258.500233ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:33.3891Z","caller":"traceutil/trace.go:171","msg":"trace[1131173174] linearizableReadLoop","detail":"{readStateIndex:27059; appliedIndex:27054; }","duration":"155.912698ms","start":"2026-05-11T18:51:33.233171Z","end":"2026-05-11T18:51:33.389084Z","steps":["trace[1131173174] 'read index received' (duration: 105.936226ms)","trace[1131173174] 'applied index is now lower than readState.Index' (duration: 49.975182ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:51:33.389244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.061541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:33.389284Z","caller":"traceutil/trace.go:171","msg":"trace[938263185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26194; }","duration":"156.175855ms","start":"2026-05-11T18:51:33.233097Z","end":"2026-05-11T18:51:33.389273Z","steps":["trace[938263185] 'agreement among raft nodes before linearized reading' (duration: 156.057282ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:33.389327Z","caller":"traceutil/trace.go:171","msg":"trace[1028072208] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"217.561297ms","start":"2026-05-11T18:51:33.171681Z","end":"2026-05-11T18:51:33.389242Z","steps":["trace[1028072208] 'process raft request' (duration: 217.31212ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:33.389297Z","caller":"traceutil/trace.go:171","msg":"trace[752982180] transaction","detail":"{read_only:false; response_revision:26192; number_of_response:1; }","duration":"217.736701ms","start":"2026-05-11T18:51:33.171482Z","end":"2026-05-11T18:51:33.389219Z","steps":["trace[752982180] 'process raft request' (duration: 217.469354ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:33.389478Z","caller":"traceutil/trace.go:171","msg":"trace[299910473] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"215.717768ms","start":"2026-05-11T18:51:33.173748Z","end":"2026-05-11T18:51:33.389466Z","steps":["trace[299910473] 'process raft request' (duration: 215.296397ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:33.38955Z","caller":"traceutil/trace.go:171","msg":"trace[1936434868] transaction","detail":"{read_only:false; response_revision:26191; number_of_response:1; }","duration":"221.194607ms","start":"2026-05-11T18:51:33.168268Z","end":"2026-05-11T18:51:33.389463Z","steps":["trace[1936434868] 'process raft request' (duration: 220.600111ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:33.919146Z","caller":"traceutil/trace.go:171","msg":"trace[356396359] transaction","detail":"{read_only:false; response_revision:26197; number_of_response:1; }","duration":"177.144776ms","start":"2026-05-11T18:51:33.741953Z","end":"2026-05-11T18:51:33.919097Z","steps":["trace[356396359] 'process raft request' (duration: 105.494505ms)","trace[356396359] 'compare' (duration: 71.266401ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:33.919163Z","caller":"traceutil/trace.go:171","msg":"trace[1369001661] transaction","detail":"{read_only:false; response_revision:26198; number_of_response:1; }","duration":"173.802366ms","start":"2026-05-11T18:51:33.745326Z","end":"2026-05-11T18:51:33.919128Z","steps":["trace[1369001661] 'process raft request' (duration: 173.661901ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:33.919236Z","caller":"traceutil/trace.go:171","msg":"trace[1455012101] transaction","detail":"{read_only:false; response_revision:26199; number_of_response:1; }","duration":"173.409245ms","start":"2026-05-11T18:51:33.745786Z","end":"2026-05-11T18:51:33.919195Z","steps":["trace[1455012101] 'process raft request' (duration: 173.305011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:51.725799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.728181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:51:51.726077Z","caller":"traceutil/trace.go:171","msg":"trace[1481702931] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"264.581448ms","start":"2026-05-11T18:51:51.461414Z","end":"2026-05-11T18:51:51.725996Z","steps":["trace[1481702931] 'process raft request' (duration: 125.55047ms)","trace[1481702931] 'compare' (duration: 137.501816ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:51.726218Z","caller":"traceutil/trace.go:171","msg":"trace[371722006] transaction","detail":"{read_only:false; response_revision:26336; number_of_response:1; }","duration":"257.179097ms","start":"2026-05-11T18:51:51.469016Z","end":"2026-05-11T18:51:51.726196Z","steps":["trace[371722006] 'process raft request' (duration: 257.083045ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:51.726257Z","caller":"traceutil/trace.go:171","msg":"trace[255142953] transaction","detail":"{read_only:false; response_revision:26335; number_of_response:1; }","duration":"260.705913ms","start":"2026-05-11T18:51:51.465489Z","end":"2026-05-11T18:51:51.726195Z","steps":["trace[255142953] 'process raft request' (duration: 260.440026ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:51.72644Z","caller":"traceutil/trace.go:171","msg":"trace[1498601305] transaction","detail":"{read_only:false; response_revision:26337; number_of_response:1; }","duration":"255.796969ms","start":"2026-05-11T18:51:51.470594Z","end":"2026-05-11T18:51:51.726391Z","steps":["trace[1498601305] 'process raft request' (duration: 255.561012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:51.997827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.00686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T18:51:51.997951Z","caller":"traceutil/trace.go:171","msg":"trace[811916263] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26338; }","duration":"131.210625ms","start":"2026-05-11T18:51:51.866714Z","end":"2026-05-11T18:51:51.997925Z","steps":["trace[811916263] 'count revisions from in-memory index tree' (duration: 130.895596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:51.998282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.413039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T18:51:51.998367Z","caller":"traceutil/trace.go:171","msg":"trace[791697360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26338; }","duration":"150.559423ms","start":"2026-05-11T18:51:51.847788Z","end":"2026-05-11T18:51:51.998347Z","steps":["trace[791697360] 'range keys from in-memory index tree' (duration: 150.221733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:51.998886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.377174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T18:51:51.999005Z","caller":"traceutil/trace.go:171","msg":"trace[623967581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26338; }","duration":"163.150936ms","start":"2026-05-11T18:51:51.835833Z","end":"2026-05-11T18:51:51.998984Z","steps":["trace[623967581] 'range keys from in-memory index tree' (duration: 162.056936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:52.318097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.060385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:51:52.318241Z","caller":"traceutil/trace.go:171","msg":"trace[1228893052] linearizableReadLoop","detail":"{readStateIndex:27208; appliedIndex:27206; }","duration":"290.656568ms","start":"2026-05-11T18:51:52.027559Z","end":"2026-05-11T18:51:52.318216Z","steps":["trace[1228893052] 'read index received' (duration: 3.393022ms)","trace[1228893052] 'applied index is now lower than readState.Index' (duration: 287.261996ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:52.31834Z","caller":"traceutil/trace.go:171","msg":"trace[1883030173] transaction","detail":"{read_only:false; response_revision:26340; number_of_response:1; }","duration":"315.189127ms","start":"2026-05-11T18:51:52.003134Z","end":"2026-05-11T18:51:52.318323Z","steps":["trace[1883030173] 'process raft request' (duration: 192.727391ms)","trace[1883030173] 'compare' (duration: 121.712446ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:51:52.318412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:52.003107Z","time spent":"315.26753ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:51:52.318715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.117502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-11T18:51:52.319081Z","caller":"traceutil/trace.go:171","msg":"trace[38881637] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26340; }","duration":"291.552804ms","start":"2026-05-11T18:51:52.02749Z","end":"2026-05-11T18:51:52.319043Z","steps":["trace[38881637] 'agreement among raft nodes before linearized reading' (duration: 290.905245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:52.324562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.638053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T18:51:52.32461Z","caller":"traceutil/trace.go:171","msg":"trace[344888330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26341; }","duration":"296.705934ms","start":"2026-05-11T18:51:52.027891Z","end":"2026-05-11T18:51:52.324597Z","steps":["trace[344888330] 'agreement among raft nodes before linearized reading' (duration: 296.56534ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:52.324656Z","caller":"traceutil/trace.go:171","msg":"trace[1580282028] transaction","detail":"{read_only:false; response_revision:26341; number_of_response:1; }","duration":"123.201517ms","start":"2026-05-11T18:51:52.201426Z","end":"2026-05-11T18:51:52.324628Z","steps":["trace[1580282028] 'process raft request' (duration: 122.842047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:52.324767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.052398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-11T18:51:52.324838Z","caller":"traceutil/trace.go:171","msg":"trace[37185269] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:26341; }","duration":"194.162301ms","start":"2026-05-11T18:51:52.13066Z","end":"2026-05-11T18:51:52.324822Z","steps":["trace[37185269] 'agreement among raft nodes before linearized reading' (duration: 193.919824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:52.324892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.866298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-11T18:51:52.324777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.367818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:52.324995Z","caller":"traceutil/trace.go:171","msg":"trace[1412311780] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:26341; }","duration":"146.646476ms","start":"2026-05-11T18:51:52.178333Z","end":"2026-05-11T18:51:52.324979Z","steps":["trace[1412311780] 'agreement among raft nodes before linearized reading' (duration: 146.377159ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:51:52.324991Z","caller":"traceutil/trace.go:171","msg":"trace[505619235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26341; }","duration":"297.007062ms","start":"2026-05-11T18:51:52.027961Z","end":"2026-05-11T18:51:52.324968Z","steps":["trace[505619235] 'agreement among raft nodes before linearized reading' (duration: 296.723334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:52.325115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.171227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:52.325207Z","caller":"traceutil/trace.go:171","msg":"trace[1064298129] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:26341; }","duration":"119.481016ms","start":"2026-05-11T18:51:52.20571Z","end":"2026-05-11T18:51:52.325191Z","steps":["trace[1064298129] 'agreement among raft nodes before linearized reading' (duration: 118.845269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:52.6316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.17934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T18:51:52.631824Z","caller":"traceutil/trace.go:171","msg":"trace[640703516] transaction","detail":"{read_only:false; response_revision:26342; number_of_response:1; }","duration":"307.755075ms","start":"2026-05-11T18:51:52.324031Z","end":"2026-05-11T18:51:52.631786Z","steps":["trace[640703516] 'process raft request' (duration: 131.297597ms)","trace[640703516] 'compare' (duration: 175.692877ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:52.631922Z","caller":"traceutil/trace.go:171","msg":"trace[1803436089] transaction","detail":"{read_only:false; response_revision:26345; number_of_response:1; }","duration":"197.794488ms","start":"2026-05-11T18:51:52.434111Z","end":"2026-05-11T18:51:52.631905Z","steps":["trace[1803436089] 'process raft request' (duration: 197.761347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:52.63202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:52.324005Z","time spent":"307.909119ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:51:52.63212Z","caller":"traceutil/trace.go:171","msg":"trace[1881982008] transaction","detail":"{read_only:false; response_revision:26343; number_of_response:1; }","duration":"302.446901ms","start":"2026-05-11T18:51:52.329667Z","end":"2026-05-11T18:51:52.632114Z","steps":["trace[1881982008] 'process raft request' (duration: 302.149113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:52.632162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:52.329642Z","time spent":"302.497921ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T18:51:52.632018Z","caller":"traceutil/trace.go:171","msg":"trace[403107433] linearizableReadLoop","detail":"{readStateIndex:27210; appliedIndex:27209; }","duration":"306.016287ms","start":"2026-05-11T18:51:52.325963Z","end":"2026-05-11T18:51:52.631979Z","steps":["trace[403107433] 'read index received' (duration: 129.385695ms)","trace[403107433] 'applied index is now lower than readState.Index' (duration: 176.626922ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:52.63227Z","caller":"traceutil/trace.go:171","msg":"trace[587531780] transaction","detail":"{read_only:false; response_revision:26344; number_of_response:1; }","duration":"301.995307ms","start":"2026-05-11T18:51:52.330269Z","end":"2026-05-11T18:51:52.632264Z","steps":["trace[587531780] 'process raft request' (duration: 301.582706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:52.632252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:52.327054Z","time spent":"305.192634ms","remote":"127.0.0.1:35374","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-11T18:51:52.632308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:52.330258Z","time spent":"302.028808ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:51:52.632382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.379517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T18:51:52.632488Z","caller":"traceutil/trace.go:171","msg":"trace[602171456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26345; }","duration":"306.503752ms","start":"2026-05-11T18:51:52.325919Z","end":"2026-05-11T18:51:52.632423Z","steps":["trace[602171456] 'agreement among raft nodes before linearized reading' (duration: 306.339927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:51:52.63256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:51:52.325898Z","time spent":"306.626005ms","remote":"127.0.0.1:35336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T18:51:52.774356Z","caller":"traceutil/trace.go:171","msg":"trace[768598172] transaction","detail":"{read_only:false; response_revision:26346; number_of_response:1; }","duration":"140.814257ms","start":"2026-05-11T18:51:52.633469Z","end":"2026-05-11T18:51:52.774283Z","steps":["trace[768598172] 'process raft request' (duration: 119.278849ms)","trace[768598172] 'compare' (duration: 21.342672ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:51:56.664945Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a6c.wal"} {"level":"info","ts":"2026-05-11T18:54:28.982634Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25010} {"level":"info","ts":"2026-05-11T18:54:29.127886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25010,"took":"140.308873ms","hash":2218544578,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":37117952,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-11T18:54:29.127965Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2218544578,"revision":25010,"compact-revision":21820} {"level":"info","ts":"2026-05-11T18:56:30.975584Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8cf6035374726417","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T18:56:30.982613Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-11T18:56:30.982732Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-11T18:59:28.989456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27864} {"level":"info","ts":"2026-05-11T18:59:29.13161Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27864,"took":"137.425104ms","hash":2400488248,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":32063488,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T18:59:29.131694Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2400488248,"revision":27864,"compact-revision":25010} {"level":"info","ts":"2026-05-11T19:04:28.995962Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30367} {"level":"info","ts":"2026-05-11T19:04:29.147011Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30367,"took":"146.059025ms","hash":2732986389,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":31285248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T19:04:29.147103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2732986389,"revision":30367,"compact-revision":27864} {"level":"info","ts":"2026-05-11T19:09:29.003524Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32693} {"level":"info","ts":"2026-05-11T19:09:29.167224Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32693,"took":"158.113994ms","hash":748774897,"current-db-size-bytes":59228160,"current-db-size":"59 MB","current-db-size-in-use-bytes":30056448,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T19:09:29.167309Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":748774897,"revision":32693,"compact-revision":30367} {"level":"info","ts":"2026-05-11T19:14:29.009914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34952} {"level":"info","ts":"2026-05-11T19:14:29.162796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34952,"took":"147.582667ms","hash":1843899173,"current-db-size-bytes":59228160,"current-db-size":"59 MB","current-db-size-in-use-bytes":31694848,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T19:14:29.162891Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1843899173,"revision":34952,"compact-revision":32693} {"level":"info","ts":"2026-05-11T19:17:48.068194Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8cf6035374726417","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T19:17:48.074781Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-11T19:17:48.074976Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-11T19:19:29.019023Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37250} {"level":"info","ts":"2026-05-11T19:19:29.147473Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37250,"took":"123.040618ms","hash":3746168385,"current-db-size-bytes":60502016,"current-db-size":"60 MB","current-db-size-in-use-bytes":32088064,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T19:19:29.147549Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3746168385,"revision":37250,"compact-revision":34952} {"level":"info","ts":"2026-05-11T19:24:29.027402Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39529} {"level":"info","ts":"2026-05-11T19:24:29.161752Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39529,"took":"127.912331ms","hash":3906518834,"current-db-size-bytes":60502016,"current-db-size":"60 MB","current-db-size-in-use-bytes":30429184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T19:24:29.16183Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3906518834,"revision":39529,"compact-revision":37250}