{"level":"warn","ts":"2026-09-01T07:16:47.03488Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T07:16:47.035109Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.111: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://162.253.55.111:2380","--initial-cluster=instance=https://162.253.55.111:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.111:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.111: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-09-01T07:16:47.03531Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T07:16:47.035366Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.111:2380"]} {"level":"info","ts":"2026-09-01T07:16:47.035423Z","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-09-01T07:16:47.037332Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"]} {"level":"info","ts":"2026-09-01T07:16:47.037562Z","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://162.253.55.111:2380"],"listen-peer-urls":["https://162.253.55.111:2380"],"advertise-client-urls":["https://162.253.55.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.111: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-09-01T07:16:47.041515Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.281001ms"} {"level":"info","ts":"2026-09-01T07:16:47.056315Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"299bf3744060cbd","cluster-id":"83d94f04ddaf7996"} {"level":"info","ts":"2026-09-01T07:16:47.056504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T07:16:47.056606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became follower at term 0"} {"level":"info","ts":"2026-09-01T07:16:47.056672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 299bf3744060cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T07:16:47.056703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became follower at term 1"} {"level":"info","ts":"2026-09-01T07:16:47.056867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=(187391103597939901)"} {"level":"warn","ts":"2026-09-01T07:16:47.063385Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T07:16:47.065431Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T07:16:47.067112Z","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-09-01T07:16:47.07061Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"299bf3744060cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T07:16:47.070799Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"299bf3744060cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T07:16:47.071007Z","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-09-01T07:16:47.071238Z","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-09-01T07:16:47.071267Z","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-09-01T07:16:47.073643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=(187391103597939901)"} {"level":"info","ts":"2026-09-01T07:16:47.07395Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"83d94f04ddaf7996","local-member-id":"299bf3744060cbd","added-peer-id":"299bf3744060cbd","added-peer-peer-urls":["https://162.253.55.111:2380"]} {"level":"info","ts":"2026-09-01T07:16:47.077695Z","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-09-01T07:16:47.077781Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.111:2380"} {"level":"info","ts":"2026-09-01T07:16:47.077827Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.111:2380"} {"level":"info","ts":"2026-09-01T07:16:47.07829Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"299bf3744060cbd","initial-advertise-peer-urls":["https://162.253.55.111:2380"],"listen-peer-urls":["https://162.253.55.111:2380"],"advertise-client-urls":["https://162.253.55.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T07:16:47.078393Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T07:16:47.458332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T07:16:47.458435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T07:16:47.458493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd received MsgPreVoteResp from 299bf3744060cbd at term 1"} {"level":"info","ts":"2026-09-01T07:16:47.458525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became candidate at term 2"} {"level":"info","ts":"2026-09-01T07:16:47.45854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd received MsgVoteResp from 299bf3744060cbd at term 2"} {"level":"info","ts":"2026-09-01T07:16:47.458567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became leader at term 2"} {"level":"info","ts":"2026-09-01T07:16:47.458587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 299bf3744060cbd elected leader 299bf3744060cbd at term 2"} {"level":"info","ts":"2026-09-01T07:16:47.459887Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"299bf3744060cbd","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.111:2379]}","request-path":"/0/members/299bf3744060cbd/attributes","cluster-id":"83d94f04ddaf7996","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T07:16:47.45994Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T07:16:47.460042Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T07:16:47.460045Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T07:16:47.460239Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T07:16:47.460269Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T07:16:47.461199Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"83d94f04ddaf7996","local-member-id":"299bf3744060cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T07:16:47.46132Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T07:16:47.461372Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T07:16:47.463405Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T07:16:47.463821Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.111:2379"} {"level":"info","ts":"2026-09-01T07:17:05.610128Z","caller":"traceutil/trace.go:171","msg":"trace[466496093] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"128.721685ms","start":"2026-09-01T07:17:05.481383Z","end":"2026-09-01T07:17:05.610105Z","steps":["trace[466496093] 'process raft request' (duration: 128.624183ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:17:31.574957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.874539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T07:17:31.57512Z","caller":"traceutil/trace.go:171","msg":"trace[699573220] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"372.461965ms","start":"2026-09-01T07:17:31.202638Z","end":"2026-09-01T07:17:31.5751Z","steps":["trace[699573220] 'read index received' (duration: 177.342651ms)","trace[699573220] 'applied index is now lower than readState.Index' (duration: 195.117554ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:17:31.575213Z","caller":"traceutil/trace.go:171","msg":"trace[2099697799] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"383.056435ms","start":"2026-09-01T07:17:31.192122Z","end":"2026-09-01T07:17:31.575178Z","steps":["trace[2099697799] 'process raft request' (duration: 187.978752ms)","trace[2099697799] 'compare' (duration: 193.689456ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T07:17:31.575391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.74989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T07:17:31.575453Z","caller":"traceutil/trace.go:171","msg":"trace[499056307] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:629; }","duration":"372.851862ms","start":"2026-09-01T07:17:31.202584Z","end":"2026-09-01T07:17:31.575436Z","steps":["trace[499056307] 'agreement among raft nodes before linearized reading' (duration: 372.692499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:17:31.575451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:17:31.192105Z","time spent":"383.222369ms","remote":"127.0.0.1:55684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T07:17:31.575524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:17:31.202561Z","time spent":"372.948984ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-09-01T07:17:31.575617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.690993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dc8vj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-01T07:17:31.575714Z","caller":"traceutil/trace.go:171","msg":"trace[1936411895] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dc8vj; range_end:; response_count:1; response_revision:629; }","duration":"277.873046ms","start":"2026-09-01T07:17:31.297818Z","end":"2026-09-01T07:17:31.575691Z","steps":["trace[1936411895] 'agreement among raft nodes before linearized reading' (duration: 277.504649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:17:32.247035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.383919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T07:17:32.247604Z","caller":"traceutil/trace.go:171","msg":"trace[1628164704] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"603.714776ms","start":"2026-09-01T07:17:31.643833Z","end":"2026-09-01T07:17:32.247547Z","steps":["trace[1628164704] 'process raft request' (duration: 271.670023ms)","trace[1628164704] 'compare' (duration: 330.963741ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:17:32.247727Z","caller":"traceutil/trace.go:171","msg":"trace[804304444] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"517.262599ms","start":"2026-09-01T07:17:31.730358Z","end":"2026-09-01T07:17:32.247621Z","steps":["trace[804304444] 'read index received' (duration: 185.142265ms)","trace[804304444] 'applied index is now lower than readState.Index' (duration: 332.114504ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T07:17:32.247821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:17:31.643802Z","time spent":"603.90662ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T07:17:32.247986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.606307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-09-01T07:17:32.248091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.025512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T07:17:32.2481Z","caller":"traceutil/trace.go:171","msg":"trace[2137372027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"517.762799ms","start":"2026-09-01T07:17:31.730311Z","end":"2026-09-01T07:17:32.248074Z","steps":["trace[2137372027] 'agreement among raft nodes before linearized reading' (duration: 517.399842ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:17:32.248102Z","caller":"traceutil/trace.go:171","msg":"trace[1985168292] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"253.665626ms","start":"2026-09-01T07:17:31.994393Z","end":"2026-09-01T07:17:32.248059Z","steps":["trace[1985168292] 'process raft request' (duration: 253.107915ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:17:32.248144Z","caller":"traceutil/trace.go:171","msg":"trace[2046997075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"464.128895ms","start":"2026-09-01T07:17:31.78399Z","end":"2026-09-01T07:17:32.248119Z","steps":["trace[2046997075] 'agreement among raft nodes before linearized reading' (duration: 464.021563ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:17:32.248155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:17:31.730297Z","time spent":"517.84832ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-01T07:17:32.248189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:17:31.783957Z","time spent":"464.224176ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-01T07:17:32.249181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.140191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-01T07:17:32.249289Z","caller":"traceutil/trace.go:171","msg":"trace[1194594137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"217.418637ms","start":"2026-09-01T07:17:32.031826Z","end":"2026-09-01T07:17:32.249245Z","steps":["trace[1194594137] 'agreement among raft nodes before linearized reading' (duration: 216.320015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:17:32.248387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.21196ms","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-09-01T07:17:32.249791Z","caller":"traceutil/trace.go:171","msg":"trace[1988729775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"441.65575ms","start":"2026-09-01T07:17:31.808118Z","end":"2026-09-01T07:17:32.249774Z","steps":["trace[1988729775] 'agreement among raft nodes before linearized reading' (duration: 440.055897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:17:32.249872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:17:31.808109Z","time spent":"441.750741ms","remote":"127.0.0.1:55942","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":"info","ts":"2026-09-01T07:19:06.94162Z","caller":"traceutil/trace.go:171","msg":"trace[53482409] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"102.408955ms","start":"2026-09-01T07:19:06.839181Z","end":"2026-09-01T07:19:06.94159Z","steps":["trace[53482409] 'process raft request' (duration: 102.172829ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:19:57.980622Z","caller":"traceutil/trace.go:171","msg":"trace[762550259] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"112.819606ms","start":"2026-09-01T07:19:57.86777Z","end":"2026-09-01T07:19:57.98059Z","steps":["trace[762550259] 'process raft request' (duration: 112.54927ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:19:58.583336Z","caller":"traceutil/trace.go:171","msg":"trace[1324501931] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"273.196925ms","start":"2026-09-01T07:19:58.310115Z","end":"2026-09-01T07:19:58.583312Z","steps":["trace[1324501931] 'process raft request' (duration: 273.049371ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:19:58.647301Z","caller":"traceutil/trace.go:171","msg":"trace[467581601] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"334.458977ms","start":"2026-09-01T07:19:58.312811Z","end":"2026-09-01T07:19:58.64727Z","steps":["trace[467581601] 'process raft request' (duration: 315.153492ms)","trace[467581601] 'compare' (duration: 19.009469ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:19:58.647364Z","caller":"traceutil/trace.go:171","msg":"trace[1297278342] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"235.047994ms","start":"2026-09-01T07:19:58.412294Z","end":"2026-09-01T07:19:58.647342Z","steps":["trace[1297278342] 'process raft request' (duration: 234.957632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:19:58.647441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:19:58.312787Z","time spent":"334.568489ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T07:19:58.647561Z","caller":"traceutil/trace.go:171","msg":"trace[1419576441] linearizableReadLoop","detail":"{readStateIndex:2305; appliedIndex:2302; }","duration":"213.676442ms","start":"2026-09-01T07:19:58.433866Z","end":"2026-09-01T07:19:58.647542Z","steps":["trace[1419576441] 'read index received' (duration: 149.33944ms)","trace[1419576441] 'applied index is now lower than readState.Index' (duration: 64.335972ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:19:58.64769Z","caller":"traceutil/trace.go:171","msg":"trace[1303927459] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"187.714585ms","start":"2026-09-01T07:19:58.459964Z","end":"2026-09-01T07:19:58.647678Z","steps":["trace[1303927459] 'process raft request' (duration: 187.344987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:19:58.64771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.845036ms","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-09-01T07:19:58.647743Z","caller":"traceutil/trace.go:171","msg":"trace[925030096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2223; }","duration":"213.926217ms","start":"2026-09-01T07:19:58.433806Z","end":"2026-09-01T07:19:58.647733Z","steps":["trace[925030096] 'agreement among raft nodes before linearized reading' (duration: 213.797895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:21:28.953489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.416923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T07:21:28.953622Z","caller":"traceutil/trace.go:171","msg":"trace[1050487342] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2904; }","duration":"110.582997ms","start":"2026-09-01T07:21:28.84301Z","end":"2026-09-01T07:21:28.953594Z","steps":["trace[1050487342] 'count revisions from in-memory index tree' (duration: 110.326731ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:21:29.023547Z","caller":"traceutil/trace.go:171","msg":"trace[1956936696] linearizableReadLoop","detail":"{readStateIndex:3013; appliedIndex:3012; }","duration":"156.341527ms","start":"2026-09-01T07:21:28.867178Z","end":"2026-09-01T07:21:29.02352Z","steps":["trace[1956936696] 'read index received' (duration: 156.058651ms)","trace[1956936696] 'applied index is now lower than readState.Index' (duration: 281.086µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:21:29.023665Z","caller":"traceutil/trace.go:171","msg":"trace[1932080304] transaction","detail":"{read_only:false; response_revision:2905; number_of_response:1; }","duration":"180.277081ms","start":"2026-09-01T07:21:28.843357Z","end":"2026-09-01T07:21:29.023634Z","steps":["trace[1932080304] 'process raft request' (duration: 179.898653ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:21:29.023926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.728525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-01T07:21:29.023995Z","caller":"traceutil/trace.go:171","msg":"trace[1456343468] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2905; }","duration":"156.860598ms","start":"2026-09-01T07:21:28.867122Z","end":"2026-09-01T07:21:29.023982Z","steps":["trace[1456343468] 'agreement among raft nodes before linearized reading' (duration: 156.49381ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:24:57.028964Z","caller":"traceutil/trace.go:171","msg":"trace[1132283498] linearizableReadLoop","detail":"{readStateIndex:4352; appliedIndex:4351; }","duration":"341.35629ms","start":"2026-09-01T07:24:56.687573Z","end":"2026-09-01T07:24:57.028929Z","steps":["trace[1132283498] 'read index received' (duration: 340.979581ms)","trace[1132283498] 'applied index is now lower than readState.Index' (duration: 375.469µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:24:57.029109Z","caller":"traceutil/trace.go:171","msg":"trace[1270389689] transaction","detail":"{read_only:false; response_revision:4196; number_of_response:1; }","duration":"345.346551ms","start":"2026-09-01T07:24:56.683714Z","end":"2026-09-01T07:24:57.029061Z","steps":["trace[1270389689] 'process raft request' (duration: 344.930541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:24:57.029202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.370879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T07:24:57.02925Z","caller":"traceutil/trace.go:171","msg":"trace[2085045761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4196; }","duration":"244.44804ms","start":"2026-09-01T07:24:56.784787Z","end":"2026-09-01T07:24:57.029235Z","steps":["trace[2085045761] 'agreement among raft nodes before linearized reading' (duration: 244.354938ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:24:57.029262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.689037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"warn","ts":"2026-09-01T07:24:57.029319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:24:56.683693Z","time spent":"345.519516ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T07:24:57.029359Z","caller":"traceutil/trace.go:171","msg":"trace[2099992903] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4196; }","duration":"341.824831ms","start":"2026-09-01T07:24:56.687514Z","end":"2026-09-01T07:24:57.029339Z","steps":["trace[2099992903] 'agreement among raft nodes before linearized reading' (duration: 341.574945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:24:57.0294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:24:56.687491Z","time spent":"341.898393ms","remote":"127.0.0.1:60962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5674,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-01T07:26:29.105683Z","caller":"traceutil/trace.go:171","msg":"trace[1563449728] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"152.090691ms","start":"2026-09-01T07:26:28.953565Z","end":"2026-09-01T07:26:29.105655Z","steps":["trace[1563449728] 'process raft request' (duration: 151.889977ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:26:29.232191Z","caller":"traceutil/trace.go:171","msg":"trace[2029251568] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"119.683909ms","start":"2026-09-01T07:26:29.11247Z","end":"2026-09-01T07:26:29.232154Z","steps":["trace[2029251568] 'process raft request' (duration: 117.466019ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:26:29.23626Z","caller":"traceutil/trace.go:171","msg":"trace[1760450270] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"123.257961ms","start":"2026-09-01T07:26:29.112964Z","end":"2026-09-01T07:26:29.236222Z","steps":["trace[1760450270] 'process raft request' (duration: 123.008176ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:26:29.23675Z","caller":"traceutil/trace.go:171","msg":"trace[1432602915] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"123.271122ms","start":"2026-09-01T07:26:29.113443Z","end":"2026-09-01T07:26:29.236714Z","steps":["trace[1432602915] 'process raft request' (duration: 122.695139ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:26:46.564938Z","caller":"traceutil/trace.go:171","msg":"trace[755410202] transaction","detail":"{read_only:false; response_revision:5656; number_of_response:1; }","duration":"140.123988ms","start":"2026-09-01T07:26:46.424785Z","end":"2026-09-01T07:26:46.564909Z","steps":["trace[755410202] 'process raft request' (duration: 139.907254ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:26:47.733824Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3008} {"level":"info","ts":"2026-09-01T07:26:47.832855Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3008,"took":"96.903439ms","hash":2656835187,"current-db-size-bytes":56360960,"current-db-size":"56 MB","current-db-size-in-use-bytes":29175808,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-01T07:26:47.83294Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2656835187,"revision":3008,"compact-revision":-1} {"level":"info","ts":"2026-09-01T07:26:48.742594Z","caller":"traceutil/trace.go:171","msg":"trace[575840930] transaction","detail":"{read_only:false; response_revision:5696; number_of_response:1; }","duration":"172.304384ms","start":"2026-09-01T07:26:48.570232Z","end":"2026-09-01T07:26:48.742537Z","steps":["trace[575840930] 'process raft request' (duration: 172.09232ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:26:49.107044Z","caller":"traceutil/trace.go:171","msg":"trace[23867837] linearizableReadLoop","detail":"{readStateIndex:5910; appliedIndex:5907; }","duration":"504.50249ms","start":"2026-09-01T07:26:48.602494Z","end":"2026-09-01T07:26:49.106996Z","steps":["trace[23867837] 'read index received' (duration: 139.789871ms)","trace[23867837] 'applied index is now lower than readState.Index' (duration: 364.710899ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:26:49.107066Z","caller":"traceutil/trace.go:171","msg":"trace[1873904528] transaction","detail":"{read_only:false; response_revision:5697; number_of_response:1; }","duration":"521.389496ms","start":"2026-09-01T07:26:48.585627Z","end":"2026-09-01T07:26:49.107016Z","steps":["trace[1873904528] 'process raft request' (duration: 521.067059ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:26:49.107151Z","caller":"traceutil/trace.go:171","msg":"trace[1404887659] transaction","detail":"{read_only:false; response_revision:5698; number_of_response:1; }","duration":"520.992487ms","start":"2026-09-01T07:26:48.586074Z","end":"2026-09-01T07:26:49.107067Z","steps":["trace[1404887659] 'process raft request' (duration: 520.850914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:26:49.107347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.821738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-lw8g4\" ","response":"range_response_count:1 size:7666"} {"level":"info","ts":"2026-09-01T07:26:49.107412Z","caller":"traceutil/trace.go:171","msg":"trace[71090243] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-lw8g4; range_end:; response_count:1; response_revision:5698; }","duration":"504.938711ms","start":"2026-09-01T07:26:48.602451Z","end":"2026-09-01T07:26:49.10739Z","steps":["trace[71090243] 'agreement among raft nodes before linearized reading' (duration: 504.703615ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:26:49.107413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:26:48.586054Z","time spent":"521.214283ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T07:26:49.10745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:26:48.602438Z","time spent":"505.003372ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":7689,"request content":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-lw8g4\" "} {"level":"warn","ts":"2026-09-01T07:26:49.10743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:26:48.585593Z","time spent":"521.658482ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T07:26:49.107514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.835671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vrsv9\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-09-01T07:26:49.107573Z","caller":"traceutil/trace.go:171","msg":"trace[1216478846] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vrsv9; range_end:; response_count:1; response_revision:5698; }","duration":"246.979385ms","start":"2026-09-01T07:26:48.860579Z","end":"2026-09-01T07:26:49.107559Z","steps":["trace[1216478846] 'agreement among raft nodes before linearized reading' (duration: 246.706319ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:31:47.74214Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5684} {"level":"info","ts":"2026-09-01T07:31:47.833437Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5684,"took":"88.424079ms","hash":309095109,"current-db-size-bytes":56360960,"current-db-size":"56 MB","current-db-size-in-use-bytes":28467200,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T07:31:47.83354Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":309095109,"revision":5684,"compact-revision":3008} {"level":"info","ts":"2026-09-01T07:33:20.339931Z","caller":"traceutil/trace.go:171","msg":"trace[1023009558] transaction","detail":"{read_only:false; response_revision:8770; number_of_response:1; }","duration":"209.876204ms","start":"2026-09-01T07:33:20.130023Z","end":"2026-09-01T07:33:20.339899Z","steps":["trace[1023009558] 'process raft request' (duration: 209.594258ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:33:20.372717Z","caller":"traceutil/trace.go:171","msg":"trace[198792612] linearizableReadLoop","detail":"{readStateIndex:9078; appliedIndex:9076; }","duration":"239.876843ms","start":"2026-09-01T07:33:20.13281Z","end":"2026-09-01T07:33:20.372687Z","steps":["trace[198792612] 'read index received' (duration: 206.827375ms)","trace[198792612] 'applied index is now lower than readState.Index' (duration: 33.048168ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:33:20.372775Z","caller":"traceutil/trace.go:171","msg":"trace[414090969] transaction","detail":"{read_only:false; response_revision:8771; number_of_response:1; }","duration":"240.845845ms","start":"2026-09-01T07:33:20.131909Z","end":"2026-09-01T07:33:20.372755Z","steps":["trace[414090969] 'process raft request' (duration: 240.600959ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:33:20.373001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.828214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T07:33:20.37301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.178149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-01T07:33:20.373053Z","caller":"traceutil/trace.go:171","msg":"trace[1780981076] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:8771; }","duration":"104.965588ms","start":"2026-09-01T07:33:20.268072Z","end":"2026-09-01T07:33:20.373038Z","steps":["trace[1780981076] 'agreement among raft nodes before linearized reading' (duration: 104.845565ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:33:20.373055Z","caller":"traceutil/trace.go:171","msg":"trace[1308877735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8771; }","duration":"240.290822ms","start":"2026-09-01T07:33:20.132754Z","end":"2026-09-01T07:33:20.373044Z","steps":["trace[1308877735] 'agreement among raft nodes before linearized reading' (duration: 240.098458ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:33:20.373033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.596488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-01T07:33:20.373053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.438835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T07:33:20.373126Z","caller":"traceutil/trace.go:171","msg":"trace[56020392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8771; }","duration":"233.70816ms","start":"2026-09-01T07:33:20.139398Z","end":"2026-09-01T07:33:20.373107Z","steps":["trace[56020392] 'agreement among raft nodes before linearized reading' (duration: 233.517956ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:33:20.373143Z","caller":"traceutil/trace.go:171","msg":"trace[1596466147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8771; }","duration":"172.59367ms","start":"2026-09-01T07:33:20.200531Z","end":"2026-09-01T07:33:20.373124Z","steps":["trace[1596466147] 'agreement among raft nodes before linearized reading' (duration: 172.421985ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:33:20.503965Z","caller":"traceutil/trace.go:171","msg":"trace[86067475] transaction","detail":"{read_only:false; response_revision:8772; number_of_response:1; }","duration":"126.211646ms","start":"2026-09-01T07:33:20.377732Z","end":"2026-09-01T07:33:20.503944Z","steps":["trace[86067475] 'process raft request' (duration: 74.158792ms)","trace[86067475] 'compare' (duration: 51.901741ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:33:20.504138Z","caller":"traceutil/trace.go:171","msg":"trace[443274861] transaction","detail":"{read_only:false; response_revision:8773; number_of_response:1; }","duration":"125.726805ms","start":"2026-09-01T07:33:20.378389Z","end":"2026-09-01T07:33:20.504116Z","steps":["trace[443274861] 'process raft request' (duration: 125.51707ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:33:25.052844Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002395.wal"} {"level":"info","ts":"2026-09-01T07:33:31.23126Z","caller":"traceutil/trace.go:171","msg":"trace[271096465] transaction","detail":"{read_only:false; response_revision:8830; number_of_response:1; }","duration":"324.7259ms","start":"2026-09-01T07:33:30.906499Z","end":"2026-09-01T07:33:31.231224Z","steps":["trace[271096465] 'process raft request' (duration: 324.463704ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:33:31.231659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:33:30.906461Z","time spent":"324.985005ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T07:33:31.235704Z","caller":"traceutil/trace.go:171","msg":"trace[1386699146] transaction","detail":"{read_only:false; response_revision:8831; number_of_response:1; }","duration":"326.633854ms","start":"2026-09-01T07:33:30.909052Z","end":"2026-09-01T07:33:31.235686Z","steps":["trace[1386699146] 'process raft request' (duration: 326.132092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:33:31.235795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:33:30.909031Z","time spent":"326.715526ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T07:33:31.982573Z","caller":"traceutil/trace.go:171","msg":"trace[1598712634] linearizableReadLoop","detail":"{readStateIndex:9144; appliedIndex:9143; }","duration":"241.449719ms","start":"2026-09-01T07:33:31.741099Z","end":"2026-09-01T07:33:31.982548Z","steps":["trace[1598712634] 'read index received' (duration: 241.273315ms)","trace[1598712634] 'applied index is now lower than readState.Index' (duration: 175.174µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:33:31.982613Z","caller":"traceutil/trace.go:171","msg":"trace[932975701] transaction","detail":"{read_only:false; response_revision:8834; number_of_response:1; }","duration":"300.984755ms","start":"2026-09-01T07:33:31.68159Z","end":"2026-09-01T07:33:31.982575Z","steps":["trace[932975701] 'process raft request' (duration: 300.798211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:33:31.982833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:33:31.681569Z","time spent":"301.15483ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T07:33:31.982884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.255701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T07:33:31.982928Z","caller":"traceutil/trace.go:171","msg":"trace[1804847719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8834; }","duration":"199.344553ms","start":"2026-09-01T07:33:31.783573Z","end":"2026-09-01T07:33:31.982918Z","steps":["trace[1804847719] 'agreement among raft nodes before linearized reading' (duration: 199.161459ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:33:31.983213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.072464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vrsv9\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-09-01T07:33:31.983332Z","caller":"traceutil/trace.go:171","msg":"trace[1849384795] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vrsv9; range_end:; response_count:1; response_revision:8834; }","duration":"242.264109ms","start":"2026-09-01T07:33:31.741046Z","end":"2026-09-01T07:33:31.98331Z","steps":["trace[1849384795] 'agreement among raft nodes before linearized reading' (duration: 241.653694ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:33:34.549455Z","caller":"traceutil/trace.go:171","msg":"trace[999920205] transaction","detail":"{read_only:false; response_revision:8847; number_of_response:1; }","duration":"120.281838ms","start":"2026-09-01T07:33:34.429143Z","end":"2026-09-01T07:33:34.549425Z","steps":["trace[999920205] 'process raft request' (duration: 37.668503ms)","trace[999920205] 'compare' (duration: 82.206616ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:34:03.374542Z","caller":"traceutil/trace.go:171","msg":"trace[1937351929] transaction","detail":"{read_only:false; response_revision:9056; number_of_response:1; }","duration":"431.990542ms","start":"2026-09-01T07:34:02.942522Z","end":"2026-09-01T07:34:03.374512Z","steps":["trace[1937351929] 'process raft request' (duration: 431.822638ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:34:03.374765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:34:02.94249Z","time spent":"432.155406ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T07:34:03.60987Z","caller":"traceutil/trace.go:171","msg":"trace[438521921] transaction","detail":"{read_only:false; response_revision:9057; number_of_response:1; }","duration":"666.853485ms","start":"2026-09-01T07:34:02.94298Z","end":"2026-09-01T07:34:03.609834Z","steps":["trace[438521921] 'process raft request' (duration: 650.52726ms)","trace[438521921] 'compare' (duration: 15.905856ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T07:34:03.610104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:34:02.94295Z","time spent":"667.029889ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T07:34:03.610133Z","caller":"traceutil/trace.go:171","msg":"trace[1834020655] transaction","detail":"{read_only:false; response_revision:9058; number_of_response:1; }","duration":"252.12436ms","start":"2026-09-01T07:34:03.357962Z","end":"2026-09-01T07:34:03.610087Z","steps":["trace[1834020655] 'process raft request' (duration: 251.776242ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:34:03.610209Z","caller":"traceutil/trace.go:171","msg":"trace[156346785] transaction","detail":"{read_only:false; response_revision:9059; number_of_response:1; }","duration":"250.943312ms","start":"2026-09-01T07:34:03.359246Z","end":"2026-09-01T07:34:03.61019Z","steps":["trace[156346785] 'process raft request' (duration: 250.761308ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:34:11.588491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.860532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-01T07:34:11.588629Z","caller":"traceutil/trace.go:171","msg":"trace[816421334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9099; }","duration":"125.030916ms","start":"2026-09-01T07:34:11.463547Z","end":"2026-09-01T07:34:11.588578Z","steps":["trace[816421334] 'range keys from in-memory index tree' (duration: 124.495983ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:34:16.219068Z","caller":"traceutil/trace.go:171","msg":"trace[163078118] transaction","detail":"{read_only:false; response_revision:9138; number_of_response:1; }","duration":"112.540039ms","start":"2026-09-01T07:34:16.106497Z","end":"2026-09-01T07:34:16.219037Z","steps":["trace[163078118] 'process raft request' (duration: 83.977925ms)","trace[163078118] 'compare' (duration: 28.400991ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T07:34:16.572083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.882829ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-09-01T07:34:16.572234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.076101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vrsv9\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-09-01T07:34:16.572265Z","caller":"traceutil/trace.go:171","msg":"trace[202313139] linearizableReadLoop","detail":"{readStateIndex:9461; appliedIndex:9460; }","duration":"131.180167ms","start":"2026-09-01T07:34:16.441052Z","end":"2026-09-01T07:34:16.572232Z","steps":["trace[202313139] 'read index received' (duration: 61.661µs)","trace[202313139] 'applied index is now lower than readState.Index' (duration: 131.115786ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:34:16.572334Z","caller":"traceutil/trace.go:171","msg":"trace[1251937559] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vrsv9; range_end:; response_count:1; response_revision:9139; }","duration":"253.236554ms","start":"2026-09-01T07:34:16.319073Z","end":"2026-09-01T07:34:16.57231Z","steps":["trace[1251937559] 'agreement among raft nodes before linearized reading' (duration: 47.343485ms)","trace[1251937559] 'range keys from in-memory index tree' (duration: 205.659245ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T07:34:16.572446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.365001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T07:34:16.572536Z","caller":"traceutil/trace.go:171","msg":"trace[1550353649] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:9139; }","duration":"131.517825ms","start":"2026-09-01T07:34:16.440992Z","end":"2026-09-01T07:34:16.57251Z","steps":["trace[1550353649] 'agreement among raft nodes before linearized reading' (duration: 131.338231ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:35:14.772693Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"299bf3744060cbd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T07:35:14.788228Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-01T07:35:14.788373Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-01T07:35:43.758739Z","caller":"traceutil/trace.go:171","msg":"trace[2048989262] transaction","detail":"{read_only:false; response_revision:9975; number_of_response:1; }","duration":"352.723655ms","start":"2026-09-01T07:35:43.405974Z","end":"2026-09-01T07:35:43.758698Z","steps":["trace[2048989262] 'process raft request' (duration: 352.473408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:35:43.758946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:35:43.405952Z","time spent":"352.888009ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T07:36:47.749075Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8035} {"level":"info","ts":"2026-09-01T07:36:47.861302Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8035,"took":"109.565804ms","hash":2770645344,"current-db-size-bytes":56360960,"current-db-size":"56 MB","current-db-size-in-use-bytes":25055232,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-01T07:36:47.861401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2770645344,"revision":8035,"compact-revision":5684} {"level":"info","ts":"2026-09-01T07:41:24.277592Z","caller":"traceutil/trace.go:171","msg":"trace[793588683] transaction","detail":"{read_only:false; response_revision:12419; number_of_response:1; }","duration":"124.678339ms","start":"2026-09-01T07:41:24.152872Z","end":"2026-09-01T07:41:24.277551Z","steps":["trace[793588683] 'process raft request' (duration: 108.496799ms)","trace[793588683] 'compare' (duration: 15.879093ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:41:25.542856Z","caller":"traceutil/trace.go:171","msg":"trace[2085163876] linearizableReadLoop","detail":"{readStateIndex:12852; appliedIndex:12849; }","duration":"122.235233ms","start":"2026-09-01T07:41:25.420599Z","end":"2026-09-01T07:41:25.542834Z","steps":["trace[2085163876] 'read index received' (duration: 21.479822ms)","trace[2085163876] 'applied index is now lower than readState.Index' (duration: 100.754191ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:41:25.542954Z","caller":"traceutil/trace.go:171","msg":"trace[325070858] transaction","detail":"{read_only:false; response_revision:12425; number_of_response:1; }","duration":"124.648458ms","start":"2026-09-01T07:41:25.418256Z","end":"2026-09-01T07:41:25.542904Z","steps":["trace[325070858] 'process raft request' (duration: 97.046585ms)","trace[325070858] 'compare' (duration: 27.292526ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:41:25.542976Z","caller":"traceutil/trace.go:171","msg":"trace[1619482856] transaction","detail":"{read_only:false; response_revision:12426; number_of_response:1; }","duration":"122.489458ms","start":"2026-09-01T07:41:25.42045Z","end":"2026-09-01T07:41:25.54294Z","steps":["trace[1619482856] 'process raft request' (duration: 122.290834ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:41:25.543106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.484038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19951"} {"level":"info","ts":"2026-09-01T07:41:25.543172Z","caller":"traceutil/trace.go:171","msg":"trace[476162143] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:12426; }","duration":"122.59692ms","start":"2026-09-01T07:41:25.420565Z","end":"2026-09-01T07:41:25.543162Z","steps":["trace[476162143] 'agreement among raft nodes before linearized reading' (duration: 122.419796ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:41:47.761242Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10426} {"level":"info","ts":"2026-09-01T07:41:47.853655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10426,"took":"89.643235ms","hash":3850061235,"current-db-size-bytes":56360960,"current-db-size":"56 MB","current-db-size-in-use-bytes":25853952,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T07:41:47.853766Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3850061235,"revision":10426,"compact-revision":8035} {"level":"info","ts":"2026-09-01T07:46:47.769356Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12593} {"level":"info","ts":"2026-09-01T07:46:47.861352Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12593,"took":"89.245636ms","hash":3412662532,"current-db-size-bytes":56360960,"current-db-size":"56 MB","current-db-size-in-use-bytes":28024832,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T07:46:47.861575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3412662532,"revision":12593,"compact-revision":10426} {"level":"info","ts":"2026-09-01T07:47:54.126925Z","caller":"traceutil/trace.go:171","msg":"trace[1236499526] linearizableReadLoop","detail":"{readStateIndex:15533; appliedIndex:15532; }","duration":"249.269473ms","start":"2026-09-01T07:47:53.877635Z","end":"2026-09-01T07:47:54.126905Z","steps":["trace[1236499526] 'read index received' (duration: 249.16034ms)","trace[1236499526] 'applied index is now lower than readState.Index' (duration: 108.493µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:47:54.127019Z","caller":"traceutil/trace.go:171","msg":"trace[638192538] transaction","detail":"{read_only:false; response_revision:15016; number_of_response:1; }","duration":"263.678793ms","start":"2026-09-01T07:47:53.863334Z","end":"2026-09-01T07:47:54.127012Z","steps":["trace[638192538] 'process raft request' (duration: 263.433617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:47:54.12721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.55777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T07:47:54.127237Z","caller":"traceutil/trace.go:171","msg":"trace[918675176] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:15016; }","duration":"249.655862ms","start":"2026-09-01T07:47:53.877572Z","end":"2026-09-01T07:47:54.127228Z","steps":["trace[918675176] 'agreement among raft nodes before linearized reading' (duration: 249.58998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:47:54.127411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.849095ms","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-09-01T07:47:54.127472Z","caller":"traceutil/trace.go:171","msg":"trace[1317262099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15016; }","duration":"194.972058ms","start":"2026-09-01T07:47:53.932485Z","end":"2026-09-01T07:47:54.127457Z","steps":["trace[1317262099] 'agreement among raft nodes before linearized reading' (duration: 194.772233ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:48:12.725977Z","caller":"traceutil/trace.go:171","msg":"trace[769397030] transaction","detail":"{read_only:false; response_revision:15125; number_of_response:1; }","duration":"375.73245ms","start":"2026-09-01T07:48:12.350215Z","end":"2026-09-01T07:48:12.725947Z","steps":["trace[769397030] 'process raft request' (duration: 375.542336ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:48:12.726176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:48:12.350145Z","time spent":"375.928544ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11815,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T07:48:12.726875Z","caller":"traceutil/trace.go:171","msg":"trace[1659369562] transaction","detail":"{read_only:false; response_revision:15126; number_of_response:1; }","duration":"282.572716ms","start":"2026-09-01T07:48:12.444266Z","end":"2026-09-01T07:48:12.726839Z","steps":["trace[1659369562] 'process raft request' (duration: 282.272849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:48:38.280929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.019039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T07:48:38.28117Z","caller":"traceutil/trace.go:171","msg":"trace[87759361] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:15452; }","duration":"243.300065ms","start":"2026-09-01T07:48:38.03783Z","end":"2026-09-01T07:48:38.28113Z","steps":["trace[87759361] 'count revisions from in-memory index tree' (duration: 242.909197ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:48:38.281324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.512519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T07:48:38.281393Z","caller":"traceutil/trace.go:171","msg":"trace[1514347343] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:15452; }","duration":"301.59192ms","start":"2026-09-01T07:48:37.979783Z","end":"2026-09-01T07:48:38.281375Z","steps":["trace[1514347343] 'count revisions from in-memory index tree' (duration: 301.383436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:48:38.281391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.394246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T07:48:38.281514Z","caller":"traceutil/trace.go:171","msg":"trace[926649107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15452; }","duration":"342.608561ms","start":"2026-09-01T07:48:37.938879Z","end":"2026-09-01T07:48:38.281488Z","steps":["trace[926649107] 'range keys from in-memory index tree' (duration: 341.819573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:48:38.281568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:48:37.938856Z","time spent":"342.695102ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-01T07:48:38.281439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:48:37.97971Z","time spent":"301.711773ms","remote":"127.0.0.1:39892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-09-01T07:50:06.095525Z","caller":"traceutil/trace.go:171","msg":"trace[1840304364] transaction","detail":"{read_only:false; response_revision:16273; number_of_response:1; }","duration":"118.559642ms","start":"2026-09-01T07:50:05.976931Z","end":"2026-09-01T07:50:06.095491Z","steps":["trace[1840304364] 'process raft request' (duration: 118.330017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:50:06.30112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.586621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T07:50:06.301233Z","caller":"traceutil/trace.go:171","msg":"trace[1034575605] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:16273; }","duration":"124.747395ms","start":"2026-09-01T07:50:06.176458Z","end":"2026-09-01T07:50:06.301205Z","steps":["trace[1034575605] 'count revisions from in-memory index tree' (duration: 124.417978ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:50:07.661472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.273451ms","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-09-01T07:50:07.661593Z","caller":"traceutil/trace.go:171","msg":"trace[22997128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16276; }","duration":"199.435945ms","start":"2026-09-01T07:50:07.46213Z","end":"2026-09-01T07:50:07.661566Z","steps":["trace[22997128] 'range keys from in-memory index tree' (duration: 199.027994ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:50:07.661597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.611688ms","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-09-01T07:50:07.66165Z","caller":"traceutil/trace.go:171","msg":"trace[1131054282] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16276; }","duration":"116.69967ms","start":"2026-09-01T07:50:07.544935Z","end":"2026-09-01T07:50:07.661634Z","steps":["trace[1131054282] 'range keys from in-memory index tree' (duration: 116.448825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:50:07.661891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.073679ms","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-09-01T07:50:07.661932Z","caller":"traceutil/trace.go:171","msg":"trace[26336461] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16276; }","duration":"110.11561ms","start":"2026-09-01T07:50:07.551808Z","end":"2026-09-01T07:50:07.661924Z","steps":["trace[26336461] 'range keys from in-memory index tree' (duration: 109.955256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:50:07.662056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.18635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-01T07:50:07.662087Z","caller":"traceutil/trace.go:171","msg":"trace[1204802853] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16276; }","duration":"110.276473ms","start":"2026-09-01T07:50:07.551803Z","end":"2026-09-01T07:50:07.662079Z","steps":["trace[1204802853] 'range keys from in-memory index tree' (duration: 110.034257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:50:07.662261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.985659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-01T07:50:07.662317Z","caller":"traceutil/trace.go:171","msg":"trace[575522372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16276; }","duration":"201.065301ms","start":"2026-09-01T07:50:07.461233Z","end":"2026-09-01T07:50:07.662298Z","steps":["trace[575522372] 'range keys from in-memory index tree' (duration: 200.863396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:50:07.663013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.37382ms","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-09-01T07:50:07.663063Z","caller":"traceutil/trace.go:171","msg":"trace[1141918351] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16276; }","duration":"264.446041ms","start":"2026-09-01T07:50:07.398606Z","end":"2026-09-01T07:50:07.663052Z","steps":["trace[1141918351] 'range keys from in-memory index tree' (duration: 264.280068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:50:07.662961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.51621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-mn9s7\" ","response":"range_response_count:1 size:22021"} {"level":"info","ts":"2026-09-01T07:50:07.66379Z","caller":"traceutil/trace.go:171","msg":"trace[931299773] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-mn9s7; range_end:; response_count:1; response_revision:16276; }","duration":"271.639946ms","start":"2026-09-01T07:50:07.392107Z","end":"2026-09-01T07:50:07.663747Z","steps":["trace[931299773] 'range keys from in-memory index tree' (duration: 269.856004ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:51:47.777248Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14595} {"level":"info","ts":"2026-09-01T07:51:47.865651Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14595,"took":"84.974315ms","hash":1498137022,"current-db-size-bytes":56360960,"current-db-size":"56 MB","current-db-size-in-use-bytes":31309824,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T07:51:47.865743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1498137022,"revision":14595,"compact-revision":12593} {"level":"info","ts":"2026-09-01T07:56:22.95379Z","caller":"traceutil/trace.go:171","msg":"trace[858046714] transaction","detail":"{read_only:false; response_revision:19001; number_of_response:1; }","duration":"130.938807ms","start":"2026-09-01T07:56:22.822815Z","end":"2026-09-01T07:56:22.953754Z","steps":["trace[858046714] 'process raft request' (duration: 130.050317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:56:23.179964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.012084ms","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-09-01T07:56:23.18009Z","caller":"traceutil/trace.go:171","msg":"trace[2029194531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19002; }","duration":"105.150827ms","start":"2026-09-01T07:56:23.074916Z","end":"2026-09-01T07:56:23.180067Z","steps":["trace[2029194531] 'range keys from in-memory index tree' (duration: 104.788269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:56:23.180145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.257247ms","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-09-01T07:56:23.180275Z","caller":"traceutil/trace.go:171","msg":"trace[794911002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19002; }","duration":"104.455011ms","start":"2026-09-01T07:56:23.075786Z","end":"2026-09-01T07:56:23.180241Z","steps":["trace[794911002] 'range keys from in-memory index tree' (duration: 103.893579ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:56:23.322633Z","caller":"traceutil/trace.go:171","msg":"trace[1187124502] transaction","detail":"{read_only:false; response_revision:19003; number_of_response:1; }","duration":"137.533188ms","start":"2026-09-01T07:56:23.18506Z","end":"2026-09-01T07:56:23.322593Z","steps":["trace[1187124502] 'process raft request' (duration: 137.276932ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:56:23.322621Z","caller":"traceutil/trace.go:171","msg":"trace[208719514] linearizableReadLoop","detail":"{readStateIndex:19656; appliedIndex:19655; }","duration":"136.201018ms","start":"2026-09-01T07:56:23.186367Z","end":"2026-09-01T07:56:23.322568Z","steps":["trace[208719514] 'read index received' (duration: 136.069265ms)","trace[208719514] 'applied index is now lower than readState.Index' (duration: 130.333µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T07:56:23.323825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.413485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:940140"} {"level":"info","ts":"2026-09-01T07:56:23.323902Z","caller":"traceutil/trace.go:171","msg":"trace[1485180332] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:19003; }","duration":"137.556289ms","start":"2026-09-01T07:56:23.18633Z","end":"2026-09-01T07:56:23.323887Z","steps":["trace[1485180332] 'agreement among raft nodes before linearized reading' (duration: 136.371432ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:56:23.34096Z","caller":"traceutil/trace.go:171","msg":"trace[933819687] transaction","detail":"{read_only:false; response_revision:19004; number_of_response:1; }","duration":"153.611486ms","start":"2026-09-01T07:56:23.187322Z","end":"2026-09-01T07:56:23.340934Z","steps":["trace[933819687] 'process raft request' (duration: 153.391411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:56:23.341322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.439228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-clrtz\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-09-01T07:56:23.341424Z","caller":"traceutil/trace.go:171","msg":"trace[977290504] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-clrtz; range_end:; response_count:1; response_revision:19004; }","duration":"103.604011ms","start":"2026-09-01T07:56:23.23779Z","end":"2026-09-01T07:56:23.341394Z","steps":["trace[977290504] 'agreement among raft nodes before linearized reading' (duration: 103.294514ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:56:23.971427Z","caller":"traceutil/trace.go:171","msg":"trace[1665253565] transaction","detail":"{read_only:false; response_revision:19005; number_of_response:1; }","duration":"584.606881ms","start":"2026-09-01T07:56:23.386791Z","end":"2026-09-01T07:56:23.971398Z","steps":["trace[1665253565] 'process raft request' (duration: 584.387396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:56:23.971606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:56:23.386767Z","time spent":"584.739925ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T07:56:24.084805Z","caller":"traceutil/trace.go:171","msg":"trace[1289084412] transaction","detail":"{read_only:false; response_revision:19007; number_of_response:1; }","duration":"656.24851ms","start":"2026-09-01T07:56:23.428528Z","end":"2026-09-01T07:56:24.084777Z","steps":["trace[1289084412] 'process raft request' (duration: 656.143198ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T07:56:24.084939Z","caller":"traceutil/trace.go:171","msg":"trace[927521690] transaction","detail":"{read_only:false; response_revision:19006; number_of_response:1; }","duration":"671.246793ms","start":"2026-09-01T07:56:23.413649Z","end":"2026-09-01T07:56:24.084895Z","steps":["trace[927521690] 'process raft request' (duration: 603.39071ms)","trace[927521690] 'compare' (duration: 67.477114ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T07:56:24.08501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:56:23.428496Z","time spent":"656.391534ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T07:56:24.085031Z","caller":"traceutil/trace.go:171","msg":"trace[433339777] linearizableReadLoop","detail":"{readStateIndex:19660; appliedIndex:19657; }","duration":"356.921069ms","start":"2026-09-01T07:56:23.72808Z","end":"2026-09-01T07:56:24.085001Z","steps":["trace[433339777] 'read index received' (duration: 243.174606ms)","trace[433339777] 'applied index is now lower than readState.Index' (duration: 113.745163ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T07:56:24.085082Z","caller":"traceutil/trace.go:171","msg":"trace[1506665392] transaction","detail":"{read_only:false; response_revision:19008; number_of_response:1; }","duration":"229.935593ms","start":"2026-09-01T07:56:23.855083Z","end":"2026-09-01T07:56:24.085019Z","steps":["trace[1506665392] 'process raft request' (duration: 229.652476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:56:24.085155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:56:23.413616Z","time spent":"671.428127ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T07:56:24.085239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.737783ms","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-09-01T07:56:24.085294Z","caller":"traceutil/trace.go:171","msg":"trace[86460523] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19008; }","duration":"352.802465ms","start":"2026-09-01T07:56:23.732473Z","end":"2026-09-01T07:56:24.085275Z","steps":["trace[86460523] 'agreement among raft nodes before linearized reading' (duration: 352.719213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:56:24.085331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:56:23.732466Z","time spent":"352.856726ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-01T07:56:24.085329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.227436ms","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-09-01T07:56:24.085377Z","caller":"traceutil/trace.go:171","msg":"trace[1960139618] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19008; }","duration":"357.337908ms","start":"2026-09-01T07:56:23.728027Z","end":"2026-09-01T07:56:24.085365Z","steps":["trace[1960139618] 'agreement among raft nodes before linearized reading' (duration: 357.120423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:56:24.085415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:56:23.728004Z","time spent":"357.40347ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-01T07:56:24.085542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.474118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T07:56:24.085582Z","caller":"traceutil/trace.go:171","msg":"trace[2099904180] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:19008; }","duration":"248.527639ms","start":"2026-09-01T07:56:23.837041Z","end":"2026-09-01T07:56:24.085569Z","steps":["trace[2099904180] 'agreement among raft nodes before linearized reading' (duration: 248.466147ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:56:24.085635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.43478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T07:56:24.085686Z","caller":"traceutil/trace.go:171","msg":"trace[774141827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19008; }","duration":"301.483081ms","start":"2026-09-01T07:56:23.784191Z","end":"2026-09-01T07:56:24.085674Z","steps":["trace[774141827] 'agreement among raft nodes before linearized reading' (duration: 301.41243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T07:56:24.085716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T07:56:23.784153Z","time spent":"301.556743ms","remote":"127.0.0.1:55666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-01T07:56:47.78529Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16924} {"level":"info","ts":"2026-09-01T07:56:47.889237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16924,"took":"99.012266ms","hash":2459939777,"current-db-size-bytes":56360960,"current-db-size":"56 MB","current-db-size-in-use-bytes":32514048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T07:56:47.889329Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2459939777,"revision":16924,"compact-revision":14595} {"level":"info","ts":"2026-09-01T07:56:53.096729Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"299bf3744060cbd","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T07:56:53.10287Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-01T07:56:53.102989Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-01T07:58:12.535628Z","caller":"traceutil/trace.go:171","msg":"trace[1516552309] transaction","detail":"{read_only:false; response_revision:20066; number_of_response:1; }","duration":"107.818689ms","start":"2026-09-01T07:58:12.42774Z","end":"2026-09-01T07:58:12.535559Z","steps":["trace[1516552309] 'process raft request' (duration: 107.598634ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:01:47.792469Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19295} {"level":"info","ts":"2026-09-01T08:01:47.897092Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19295,"took":"100.563573ms","hash":3795412419,"current-db-size-bytes":56360960,"current-db-size":"56 MB","current-db-size-in-use-bytes":34021376,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-01T08:01:47.897208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3795412419,"revision":19295,"compact-revision":16924} {"level":"warn","ts":"2026-09-01T08:02:05.891441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.024161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T08:02:05.89159Z","caller":"traceutil/trace.go:171","msg":"trace[1039413943] transaction","detail":"{read_only:false; response_revision:21734; number_of_response:1; }","duration":"396.868562ms","start":"2026-09-01T08:02:05.494699Z","end":"2026-09-01T08:02:05.891567Z","steps":["trace[1039413943] 'process raft request' (duration: 265.601134ms)","trace[1039413943] 'compare' (duration: 130.50278ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T08:02:05.89166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T08:02:05.494666Z","time spent":"396.961814ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T08:02:05.93583Z","caller":"traceutil/trace.go:171","msg":"trace[1379085261] transaction","detail":"{read_only:false; response_revision:21735; number_of_response:1; }","duration":"383.382012ms","start":"2026-09-01T08:02:05.552421Z","end":"2026-09-01T08:02:05.935803Z","steps":["trace[1379085261] 'process raft request' (duration: 383.113417ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:02:05.935818Z","caller":"traceutil/trace.go:171","msg":"trace[1627582062] linearizableReadLoop","detail":"{readStateIndex:22476; appliedIndex:22474; }","duration":"151.92085ms","start":"2026-09-01T08:02:05.78386Z","end":"2026-09-01T08:02:05.935781Z","steps":["trace[1627582062] 'read index received' (duration: 151.662124ms)","trace[1627582062] 'applied index is now lower than readState.Index' (duration: 257.076µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:02:05.935866Z","caller":"traceutil/trace.go:171","msg":"trace[504293031] transaction","detail":"{read_only:false; response_revision:21736; number_of_response:1; }","duration":"326.891038ms","start":"2026-09-01T08:02:05.60894Z","end":"2026-09-01T08:02:05.935831Z","steps":["trace[504293031] 'process raft request' (duration: 326.770555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:02:05.935932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T08:02:05.552377Z","time spent":"383.509955ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T08:02:05.936131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.226576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:02:05.936238Z","caller":"traceutil/trace.go:171","msg":"trace[2032710030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21736; }","duration":"152.420211ms","start":"2026-09-01T08:02:05.783791Z","end":"2026-09-01T08:02:05.936212Z","steps":["trace[2032710030] 'agreement among raft nodes before linearized reading' (duration: 152.188005ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:02:05.93638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T08:02:05.608907Z","time spent":"327.047682ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T08:02:12.109347Z","caller":"traceutil/trace.go:171","msg":"trace[1826122745] transaction","detail":"{read_only:false; response_revision:21767; number_of_response:1; }","duration":"124.032571ms","start":"2026-09-01T08:02:11.985271Z","end":"2026-09-01T08:02:12.109303Z","steps":["trace[1826122745] 'process raft request' (duration: 123.525819ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:02:12.109682Z","caller":"traceutil/trace.go:171","msg":"trace[204471701] transaction","detail":"{read_only:false; response_revision:21768; number_of_response:1; }","duration":"123.665113ms","start":"2026-09-01T08:02:11.985982Z","end":"2026-09-01T08:02:12.109647Z","steps":["trace[204471701] 'process raft request' (duration: 123.042118ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:02:27.809026Z","caller":"traceutil/trace.go:171","msg":"trace[725287851] transaction","detail":"{read_only:false; response_revision:21848; number_of_response:1; }","duration":"214.969874ms","start":"2026-09-01T08:02:27.594022Z","end":"2026-09-01T08:02:27.808992Z","steps":["trace[725287851] 'process raft request' (duration: 214.7728ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:02:28.157811Z","caller":"traceutil/trace.go:171","msg":"trace[1800178868] transaction","detail":"{read_only:false; response_revision:21849; number_of_response:1; }","duration":"147.920328ms","start":"2026-09-01T08:02:28.009848Z","end":"2026-09-01T08:02:28.157769Z","steps":["trace[1800178868] 'process raft request' (duration: 112.353523ms)","trace[1800178868] 'compare' (duration: 35.171896ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T08:02:28.464575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.529165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T08:02:28.464963Z","caller":"traceutil/trace.go:171","msg":"trace[1306362114] transaction","detail":"{read_only:false; response_revision:21850; number_of_response:1; }","duration":"239.329742ms","start":"2026-09-01T08:02:28.225573Z","end":"2026-09-01T08:02:28.464902Z","steps":["trace[1306362114] 'process raft request' (duration: 72.338496ms)","trace[1306362114] 'compare' (duration: 166.085365ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:02:28.465906Z","caller":"traceutil/trace.go:171","msg":"trace[10535014] linearizableReadLoop","detail":"{readStateIndex:22597; appliedIndex:22595; }","duration":"202.09167ms","start":"2026-09-01T08:02:28.263784Z","end":"2026-09-01T08:02:28.465876Z","steps":["trace[10535014] 'read index received' (duration: 34.084491ms)","trace[10535014] 'applied index is now lower than readState.Index' (duration: 168.005549ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:02:28.465968Z","caller":"traceutil/trace.go:171","msg":"trace[233780576] transaction","detail":"{read_only:false; response_revision:21851; number_of_response:1; }","duration":"238.064733ms","start":"2026-09-01T08:02:28.227875Z","end":"2026-09-01T08:02:28.46594Z","steps":["trace[233780576] 'process raft request' (duration: 237.895459ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:02:28.466082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.271884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:02:28.466149Z","caller":"traceutil/trace.go:171","msg":"trace[189042962] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:21851; }","duration":"202.402257ms","start":"2026-09-01T08:02:28.263731Z","end":"2026-09-01T08:02:28.466134Z","steps":["trace[189042962] 'agreement among raft nodes before linearized reading' (duration: 202.278365ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:02:28.467261Z","caller":"traceutil/trace.go:171","msg":"trace[1259948280] transaction","detail":"{read_only:false; response_revision:21852; number_of_response:1; }","duration":"106.703194ms","start":"2026-09-01T08:02:28.360539Z","end":"2026-09-01T08:02:28.467242Z","steps":["trace[1259948280] 'process raft request' (duration: 106.450088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:02:28.467713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.508376ms","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-09-01T08:02:28.467788Z","caller":"traceutil/trace.go:171","msg":"trace[1484515800] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21853; }","duration":"201.66514ms","start":"2026-09-01T08:02:28.266107Z","end":"2026-09-01T08:02:28.467772Z","steps":["trace[1484515800] 'agreement among raft nodes before linearized reading' (duration: 201.383353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:02:28.46776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.268755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:02:28.467971Z","caller":"traceutil/trace.go:171","msg":"trace[1856123604] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:21853; }","duration":"158.552531ms","start":"2026-09-01T08:02:28.309386Z","end":"2026-09-01T08:02:28.467939Z","steps":["trace[1856123604] 'agreement among raft nodes before linearized reading' (duration: 158.204974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:02:28.826988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.79674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T08:02:28.827199Z","caller":"traceutil/trace.go:171","msg":"trace[1245226945] linearizableReadLoop","detail":"{readStateIndex:22600; appliedIndex:22599; }","duration":"352.707039ms","start":"2026-09-01T08:02:28.474465Z","end":"2026-09-01T08:02:28.827172Z","steps":["trace[1245226945] 'read index received' (duration: 231.745189ms)","trace[1245226945] 'applied index is now lower than readState.Index' (duration: 120.9592ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:02:28.827199Z","caller":"traceutil/trace.go:171","msg":"trace[426830710] transaction","detail":"{read_only:false; response_revision:21854; number_of_response:1; }","duration":"353.956158ms","start":"2026-09-01T08:02:28.473203Z","end":"2026-09-01T08:02:28.827159Z","steps":["trace[426830710] 'process raft request' (duration: 231.854281ms)","trace[426830710] 'compare' (duration: 121.452992ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T08:02:28.827495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.030497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19251"} {"level":"info","ts":"2026-09-01T08:02:28.827546Z","caller":"traceutil/trace.go:171","msg":"trace[1003065199] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21854; }","duration":"353.101828ms","start":"2026-09-01T08:02:28.474428Z","end":"2026-09-01T08:02:28.827529Z","steps":["trace[1003065199] 'agreement among raft nodes before linearized reading' (duration: 352.866063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:02:28.827607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T08:02:28.474417Z","time spent":"353.17668ms","remote":"127.0.0.1:55842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19274,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-01T08:02:28.827605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T08:02:28.473178Z","time spent":"354.290106ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T08:02:28.891025Z","caller":"traceutil/trace.go:171","msg":"trace[249036549] transaction","detail":"{read_only:false; response_revision:21855; number_of_response:1; }","duration":"412.259573ms","start":"2026-09-01T08:02:28.478721Z","end":"2026-09-01T08:02:28.890981Z","steps":["trace[249036549] 'process raft request' (duration: 411.957217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:02:28.892744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T08:02:28.4787Z","time spent":"413.929222ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T08:02:28.891159Z","caller":"traceutil/trace.go:171","msg":"trace[1607138465] transaction","detail":"{read_only:false; response_revision:21856; number_of_response:1; }","duration":"332.475516ms","start":"2026-09-01T08:02:28.558588Z","end":"2026-09-01T08:02:28.891063Z","steps":["trace[1607138465] 'process raft request' (duration: 332.298552ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:02:28.891245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.592117ms","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-09-01T08:02:28.89296Z","caller":"traceutil/trace.go:171","msg":"trace[697369311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21856; }","duration":"110.369457ms","start":"2026-09-01T08:02:28.782553Z","end":"2026-09-01T08:02:28.892923Z","steps":["trace[697369311] 'agreement among raft nodes before linearized reading' (duration: 108.395373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:02:28.892982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T08:02:28.558566Z","time spent":"334.269476ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T08:02:28.891417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.755509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:02:28.893064Z","caller":"traceutil/trace.go:171","msg":"trace[1083217745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21856; }","duration":"109.452328ms","start":"2026-09-01T08:02:28.78359Z","end":"2026-09-01T08:02:28.893042Z","steps":["trace[1083217745] 'agreement among raft nodes before linearized reading' (duration: 107.482572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:02:29.194632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.700417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T08:02:29.194749Z","caller":"traceutil/trace.go:171","msg":"trace[10290590] linearizableReadLoop","detail":"{readStateIndex:22603; appliedIndex:22602; }","duration":"279.416681ms","start":"2026-09-01T08:02:28.915314Z","end":"2026-09-01T08:02:29.194731Z","steps":["trace[10290590] 'read index received' (duration: 123.347127ms)","trace[10290590] 'applied index is now lower than readState.Index' (duration: 156.067914ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T08:02:29.194808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.496093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:02:29.194825Z","caller":"traceutil/trace.go:171","msg":"trace[232260577] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21857; }","duration":"279.553164ms","start":"2026-09-01T08:02:28.915265Z","end":"2026-09-01T08:02:29.194818Z","steps":["trace[232260577] 'agreement among raft nodes before linearized reading' (duration: 279.504603ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:02:29.194814Z","caller":"traceutil/trace.go:171","msg":"trace[1031149839] transaction","detail":"{read_only:false; response_revision:21857; number_of_response:1; }","duration":"296.127433ms","start":"2026-09-01T08:02:28.898648Z","end":"2026-09-01T08:02:29.194775Z","steps":["trace[1031149839] 'process raft request' (duration: 140.190841ms)","trace[1031149839] 'compare' (duration: 155.131143ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:02:29.777753Z","caller":"traceutil/trace.go:171","msg":"trace[153046484] transaction","detail":"{read_only:false; response_revision:21858; number_of_response:1; }","duration":"178.323255ms","start":"2026-09-01T08:02:29.599399Z","end":"2026-09-01T08:02:29.777722Z","steps":["trace[153046484] 'process raft request' (duration: 178.11279ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:02:29.93619Z","caller":"traceutil/trace.go:171","msg":"trace[666078263] transaction","detail":"{read_only:false; response_revision:21859; number_of_response:1; }","duration":"119.13998ms","start":"2026-09-01T08:02:29.817011Z","end":"2026-09-01T08:02:29.936151Z","steps":["trace[666078263] 'process raft request' (duration: 118.897254ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:06:38.506403Z","caller":"traceutil/trace.go:171","msg":"trace[1348199957] transaction","detail":"{read_only:false; response_revision:24436; number_of_response:1; }","duration":"222.976318ms","start":"2026-09-01T08:06:38.283401Z","end":"2026-09-01T08:06:38.506377Z","steps":["trace[1348199957] 'process raft request' (duration: 222.723503ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:06:38.506361Z","caller":"traceutil/trace.go:171","msg":"trace[1976015438] linearizableReadLoop","detail":"{readStateIndex:25257; appliedIndex:25256; }","duration":"140.785796ms","start":"2026-09-01T08:06:38.365536Z","end":"2026-09-01T08:06:38.506322Z","steps":["trace[1976015438] 'read index received' (duration: 140.443228ms)","trace[1976015438] 'applied index is now lower than readState.Index' (duration: 341.048µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T08:06:38.506611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.053831ms","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-09-01T08:06:38.50673Z","caller":"traceutil/trace.go:171","msg":"trace[1412256150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24436; }","duration":"141.237246ms","start":"2026-09-01T08:06:38.365476Z","end":"2026-09-01T08:06:38.506714Z","steps":["trace[1412256150] 'agreement among raft nodes before linearized reading' (duration: 140.9737ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:06:47.803478Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21644} {"level":"info","ts":"2026-09-01T08:06:47.941798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21644,"took":"133.657822ms","hash":1911926480,"current-db-size-bytes":56672256,"current-db-size":"57 MB","current-db-size-in-use-bytes":42467328,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-01T08:06:47.941889Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1911926480,"revision":21644,"compact-revision":19295} {"level":"info","ts":"2026-09-01T08:10:00.143913Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069da.wal"} {"level":"info","ts":"2026-09-01T08:11:47.809827Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24529} {"level":"info","ts":"2026-09-01T08:11:47.964068Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24529,"took":"149.878544ms","hash":2359699388,"current-db-size-bytes":56672256,"current-db-size":"57 MB","current-db-size-in-use-bytes":33443840,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-01T08:11:47.964219Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2359699388,"revision":24529,"compact-revision":21644} {"level":"warn","ts":"2026-09-01T08:12:35.197588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.293026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T08:12:35.19771Z","caller":"traceutil/trace.go:171","msg":"trace[1425085458] linearizableReadLoop","detail":"{readStateIndex:28294; appliedIndex:28293; }","duration":"191.232221ms","start":"2026-09-01T08:12:35.006461Z","end":"2026-09-01T08:12:35.197694Z","steps":["trace[1425085458] 'read index received' (duration: 1.664008ms)","trace[1425085458] 'applied index is now lower than readState.Index' (duration: 189.566583ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T08:12:35.197884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.388264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:35.197857Z","caller":"traceutil/trace.go:171","msg":"trace[1723354927] transaction","detail":"{read_only:false; response_revision:27392; number_of_response:1; }","duration":"266.504516ms","start":"2026-09-01T08:12:34.931314Z","end":"2026-09-01T08:12:35.197818Z","steps":["trace[1723354927] 'process raft request' (duration: 76.875862ms)","trace[1723354927] 'compare' (duration: 189.042931ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:35.197926Z","caller":"traceutil/trace.go:171","msg":"trace[1886332600] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:27392; }","duration":"191.500727ms","start":"2026-09-01T08:12:35.006414Z","end":"2026-09-01T08:12:35.197915Z","steps":["trace[1886332600] 'agreement among raft nodes before linearized reading' (duration: 191.395014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:35.243081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.126817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T08:12:35.243165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.604921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:35.243222Z","caller":"traceutil/trace.go:171","msg":"trace[1118801864] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:27394; }","duration":"235.335741ms","start":"2026-09-01T08:12:35.007848Z","end":"2026-09-01T08:12:35.243184Z","steps":["trace[1118801864] 'agreement among raft nodes before linearized reading' (duration: 235.077646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:35.243429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.798023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:35.243473Z","caller":"traceutil/trace.go:171","msg":"trace[129390412] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:27394; }","duration":"232.862405ms","start":"2026-09-01T08:12:35.0106Z","end":"2026-09-01T08:12:35.243463Z","steps":["trace[129390412] 'agreement among raft nodes before linearized reading' (duration: 232.791573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:35.24364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.852307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-09-01T08:12:35.243676Z","caller":"traceutil/trace.go:171","msg":"trace[1535802436] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:27394; }","duration":"233.926639ms","start":"2026-09-01T08:12:35.00974Z","end":"2026-09-01T08:12:35.243666Z","steps":["trace[1535802436] 'agreement among raft nodes before linearized reading' (duration: 233.770516ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:35.243266Z","caller":"traceutil/trace.go:171","msg":"trace[645258156] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:27394; }","duration":"236.788975ms","start":"2026-09-01T08:12:35.006456Z","end":"2026-09-01T08:12:35.243245Z","steps":["trace[645258156] 'agreement among raft nodes before linearized reading' (duration: 236.625061ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:35.243874Z","caller":"traceutil/trace.go:171","msg":"trace[1712912237] transaction","detail":"{read_only:false; response_revision:27394; number_of_response:1; }","duration":"111.779901ms","start":"2026-09-01T08:12:35.132023Z","end":"2026-09-01T08:12:35.243803Z","steps":["trace[1712912237] 'process raft request' (duration: 110.705836ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:36.881075Z","caller":"traceutil/trace.go:171","msg":"trace[1742522344] transaction","detail":"{read_only:false; response_revision:27402; number_of_response:1; }","duration":"203.975922ms","start":"2026-09-01T08:12:36.676873Z","end":"2026-09-01T08:12:36.880849Z","steps":["trace[1742522344] 'process raft request' (duration: 170.426885ms)","trace[1742522344] 'compare' (duration: 32.77404ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:36.887274Z","caller":"traceutil/trace.go:171","msg":"trace[1245429947] transaction","detail":"{read_only:false; response_revision:27403; number_of_response:1; }","duration":"192.94324ms","start":"2026-09-01T08:12:36.694305Z","end":"2026-09-01T08:12:36.887248Z","steps":["trace[1245429947] 'process raft request' (duration: 192.730526ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:36.971305Z","caller":"traceutil/trace.go:171","msg":"trace[163687332] linearizableReadLoop","detail":"{readStateIndex:28306; appliedIndex:28303; }","duration":"185.790486ms","start":"2026-09-01T08:12:36.785488Z","end":"2026-09-01T08:12:36.971279Z","steps":["trace[163687332] 'read index received' (duration: 61.840777ms)","trace[163687332] 'applied index is now lower than readState.Index' (duration: 123.948479ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T08:12:36.971972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.472963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:36.972008Z","caller":"traceutil/trace.go:171","msg":"trace[977425017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27403; }","duration":"186.564054ms","start":"2026-09-01T08:12:36.785435Z","end":"2026-09-01T08:12:36.971999Z","steps":["trace[977425017] 'agreement among raft nodes before linearized reading' (duration: 186.477012ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:36.972356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.95524ms","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-09-01T08:12:36.972421Z","caller":"traceutil/trace.go:171","msg":"trace[943885630] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:27403; }","duration":"172.069482ms","start":"2026-09-01T08:12:36.800334Z","end":"2026-09-01T08:12:36.972403Z","steps":["trace[943885630] 'agreement among raft nodes before linearized reading' (duration: 171.783926ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:37.307467Z","caller":"traceutil/trace.go:171","msg":"trace[1790441048] transaction","detail":"{read_only:false; response_revision:27406; number_of_response:1; }","duration":"104.028713ms","start":"2026-09-01T08:12:37.203403Z","end":"2026-09-01T08:12:37.307432Z","steps":["trace[1790441048] 'process raft request' (duration: 57.325384ms)","trace[1790441048] 'compare' (duration: 46.156987ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:38.126527Z","caller":"traceutil/trace.go:171","msg":"trace[792381156] transaction","detail":"{read_only:false; response_revision:27412; number_of_response:1; }","duration":"178.338295ms","start":"2026-09-01T08:12:37.948159Z","end":"2026-09-01T08:12:38.126497Z","steps":["trace[792381156] 'process raft request' (duration: 142.680409ms)","trace[792381156] 'compare' (duration: 35.38376ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T08:12:39.702429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.145289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T08:12:39.702966Z","caller":"traceutil/trace.go:171","msg":"trace[1860069961] transaction","detail":"{read_only:false; response_revision:27423; number_of_response:1; }","duration":"204.610688ms","start":"2026-09-01T08:12:39.498309Z","end":"2026-09-01T08:12:39.70292Z","steps":["trace[1860069961] 'process raft request' (duration: 102.865247ms)","trace[1860069961] 'compare' (duration: 100.759408ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:39.945732Z","caller":"traceutil/trace.go:171","msg":"trace[590312726] transaction","detail":"{read_only:false; response_revision:27425; number_of_response:1; }","duration":"110.749437ms","start":"2026-09-01T08:12:39.834851Z","end":"2026-09-01T08:12:39.9456Z","steps":["trace[590312726] 'process raft request' (duration: 62.207636ms)","trace[590312726] 'compare' (duration: 48.302286ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T08:12:40.245925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.941158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T08:12:40.246042Z","caller":"traceutil/trace.go:171","msg":"trace[1746363617] transaction","detail":"{read_only:false; response_revision:27427; number_of_response:1; }","duration":"223.224115ms","start":"2026-09-01T08:12:40.022792Z","end":"2026-09-01T08:12:40.246017Z","steps":["trace[1746363617] 'process raft request' (duration: 34.078922ms)","trace[1746363617] 'compare' (duration: 188.792225ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:40.333002Z","caller":"traceutil/trace.go:171","msg":"trace[1814243761] transaction","detail":"{read_only:false; response_revision:27428; number_of_response:1; }","duration":"155.457731ms","start":"2026-09-01T08:12:40.177508Z","end":"2026-09-01T08:12:40.332966Z","steps":["trace[1814243761] 'process raft request' (duration: 155.297427ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:40.400979Z","caller":"traceutil/trace.go:171","msg":"trace[1475811501] transaction","detail":"{read_only:false; response_revision:27429; number_of_response:1; }","duration":"146.456396ms","start":"2026-09-01T08:12:40.254474Z","end":"2026-09-01T08:12:40.400931Z","steps":["trace[1475811501] 'process raft request' (duration: 91.023095ms)","trace[1475811501] 'compare' (duration: 55.063402ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:41.27617Z","caller":"traceutil/trace.go:171","msg":"trace[361528212] transaction","detail":"{read_only:false; response_revision:27434; number_of_response:1; }","duration":"161.099851ms","start":"2026-09-01T08:12:41.115018Z","end":"2026-09-01T08:12:41.276118Z","steps":["trace[361528212] 'process raft request' (duration: 160.849794ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:41.807538Z","caller":"traceutil/trace.go:171","msg":"trace[235505181] transaction","detail":"{read_only:false; response_revision:27440; number_of_response:1; }","duration":"248.763109ms","start":"2026-09-01T08:12:41.558745Z","end":"2026-09-01T08:12:41.807508Z","steps":["trace[235505181] 'process raft request' (duration: 248.538814ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:41.807723Z","caller":"traceutil/trace.go:171","msg":"trace[1112605069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27440; }","duration":"100.061452ms","start":"2026-09-01T08:12:41.707632Z","end":"2026-09-01T08:12:41.807694Z","steps":["trace[1112605069] 'agreement among raft nodes before linearized reading' (duration: 99.892068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:42.409922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.064866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T08:12:42.410067Z","caller":"traceutil/trace.go:171","msg":"trace[808461220] transaction","detail":"{read_only:false; response_revision:27441; number_of_response:1; }","duration":"700.721773ms","start":"2026-09-01T08:12:41.709311Z","end":"2026-09-01T08:12:42.410033Z","steps":["trace[808461220] 'process raft request' (duration: 403.435202ms)","trace[808461220] 'compare' (duration: 296.720248ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:42.410234Z","caller":"traceutil/trace.go:171","msg":"trace[974680010] linearizableReadLoop","detail":"{readStateIndex:28345; appliedIndex:28344; }","duration":"602.731029ms","start":"2026-09-01T08:12:41.807465Z","end":"2026-09-01T08:12:42.410196Z","steps":["trace[974680010] 'read index received' (duration: 305.374437ms)","trace[974680010] 'applied index is now lower than readState.Index' (duration: 297.339822ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T08:12:42.410267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T08:12:41.709286Z","time spent":"700.854517ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T08:12:42.41031Z","caller":"traceutil/trace.go:171","msg":"trace[540554192] transaction","detail":"{read_only:false; response_revision:27443; number_of_response:1; }","duration":"384.508269ms","start":"2026-09-01T08:12:42.025782Z","end":"2026-09-01T08:12:42.410291Z","steps":["trace[540554192] 'process raft request' (duration: 384.408127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:42.410669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T08:12:42.025748Z","time spent":"384.618451ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T08:12:42.410716Z","caller":"traceutil/trace.go:171","msg":"trace[1005672851] transaction","detail":"{read_only:false; response_revision:27442; number_of_response:1; }","duration":"598.514112ms","start":"2026-09-01T08:12:41.812177Z","end":"2026-09-01T08:12:42.410691Z","steps":["trace[1005672851] 'process raft request' (duration: 597.865588ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:42.410751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.24089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:42.410982Z","caller":"traceutil/trace.go:171","msg":"trace[381166299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27443; }","duration":"627.402513ms","start":"2026-09-01T08:12:41.783474Z","end":"2026-09-01T08:12:42.410877Z","steps":["trace[381166299] 'agreement among raft nodes before linearized reading' (duration: 627.050416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:42.410826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T08:12:41.812151Z","time spent":"598.617965ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T08:12:42.411098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T08:12:41.783418Z","time spent":"627.661549ms","remote":"127.0.0.1:55666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-01T08:12:42.696832Z","caller":"traceutil/trace.go:171","msg":"trace[1500830551] transaction","detail":"{read_only:false; response_revision:27445; number_of_response:1; }","duration":"131.534004ms","start":"2026-09-01T08:12:42.565262Z","end":"2026-09-01T08:12:42.696796Z","steps":["trace[1500830551] 'process raft request' (duration: 131.250217ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:44.560246Z","caller":"traceutil/trace.go:171","msg":"trace[2068782468] transaction","detail":"{read_only:false; response_revision:27458; number_of_response:1; }","duration":"128.339829ms","start":"2026-09-01T08:12:44.43185Z","end":"2026-09-01T08:12:44.560189Z","steps":["trace[2068782468] 'process raft request' (duration: 128.288838ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:44.5603Z","caller":"traceutil/trace.go:171","msg":"trace[926818097] transaction","detail":"{read_only:false; response_revision:27457; number_of_response:1; }","duration":"140.237883ms","start":"2026-09-01T08:12:44.420038Z","end":"2026-09-01T08:12:44.560276Z","steps":["trace[926818097] 'process raft request' (duration: 139.972437ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:44.560315Z","caller":"traceutil/trace.go:171","msg":"trace[325664631] transaction","detail":"{read_only:false; response_revision:27456; number_of_response:1; }","duration":"143.658281ms","start":"2026-09-01T08:12:44.416614Z","end":"2026-09-01T08:12:44.560272Z","steps":["trace[325664631] 'process raft request' (duration: 87.474664ms)","trace[325664631] 'compare' (duration: 55.662635ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:45.721098Z","caller":"traceutil/trace.go:171","msg":"trace[734676895] linearizableReadLoop","detail":"{readStateIndex:28370; appliedIndex:28369; }","duration":"255.468842ms","start":"2026-09-01T08:12:45.465593Z","end":"2026-09-01T08:12:45.721062Z","steps":["trace[734676895] 'read index received' (duration: 255.339689ms)","trace[734676895] 'applied index is now lower than readState.Index' (duration: 127.953µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:45.72123Z","caller":"traceutil/trace.go:171","msg":"trace[1777851691] transaction","detail":"{read_only:false; response_revision:27466; number_of_response:1; }","duration":"255.899612ms","start":"2026-09-01T08:12:45.465279Z","end":"2026-09-01T08:12:45.721178Z","steps":["trace[1777851691] 'process raft request' (duration: 255.604435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:45.721351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.719328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:45.721439Z","caller":"traceutil/trace.go:171","msg":"trace[1319022259] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:27466; }","duration":"255.846831ms","start":"2026-09-01T08:12:45.465572Z","end":"2026-09-01T08:12:45.721419Z","steps":["trace[1319022259] 'agreement among raft nodes before linearized reading' (duration: 255.667406ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:45.745009Z","caller":"traceutil/trace.go:171","msg":"trace[137306781] transaction","detail":"{read_only:false; response_revision:27467; number_of_response:1; }","duration":"278.571602ms","start":"2026-09-01T08:12:45.466408Z","end":"2026-09-01T08:12:45.74498Z","steps":["trace[137306781] 'process raft request' (duration: 277.663992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:45.7454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.793276ms","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-09-01T08:12:45.745443Z","caller":"traceutil/trace.go:171","msg":"trace[827049698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27467; }","duration":"126.908638ms","start":"2026-09-01T08:12:45.618525Z","end":"2026-09-01T08:12:45.745433Z","steps":["trace[827049698] 'agreement among raft nodes before linearized reading' (duration: 126.714374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:45.745615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.962841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-09-01T08:12:45.745741Z","caller":"traceutil/trace.go:171","msg":"trace[1980302008] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:27467; }","duration":"279.140005ms","start":"2026-09-01T08:12:45.466579Z","end":"2026-09-01T08:12:45.745719Z","steps":["trace[1980302008] 'agreement among raft nodes before linearized reading' (duration: 278.368206ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:46.707365Z","caller":"traceutil/trace.go:171","msg":"trace[1715733158] transaction","detail":"{read_only:false; response_revision:27472; number_of_response:1; }","duration":"138.83842ms","start":"2026-09-01T08:12:46.568509Z","end":"2026-09-01T08:12:46.707347Z","steps":["trace[1715733158] 'process raft request' (duration: 138.795369ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:46.707492Z","caller":"traceutil/trace.go:171","msg":"trace[447596484] transaction","detail":"{read_only:false; response_revision:27471; number_of_response:1; }","duration":"140.276904ms","start":"2026-09-01T08:12:46.56718Z","end":"2026-09-01T08:12:46.707457Z","steps":["trace[447596484] 'process raft request' (duration: 102.449217ms)","trace[447596484] 'compare' (duration: 37.443428ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:47.510342Z","caller":"traceutil/trace.go:171","msg":"trace[1080225426] transaction","detail":"{read_only:false; response_revision:27477; number_of_response:1; }","duration":"125.487294ms","start":"2026-09-01T08:12:47.384816Z","end":"2026-09-01T08:12:47.510303Z","steps":["trace[1080225426] 'process raft request' (duration: 125.224079ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:47.534743Z","caller":"traceutil/trace.go:171","msg":"trace[2068637672] linearizableReadLoop","detail":"{readStateIndex:28383; appliedIndex:28381; }","duration":"130.057529ms","start":"2026-09-01T08:12:47.404657Z","end":"2026-09-01T08:12:47.534714Z","steps":["trace[2068637672] 'read index received' (duration: 105.336373ms)","trace[2068637672] 'applied index is now lower than readState.Index' (duration: 24.719856ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:47.534804Z","caller":"traceutil/trace.go:171","msg":"trace[285423782] transaction","detail":"{read_only:false; response_revision:27478; number_of_response:1; }","duration":"140.490459ms","start":"2026-09-01T08:12:47.394274Z","end":"2026-09-01T08:12:47.534765Z","steps":["trace[285423782] 'process raft request' (duration: 140.255313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:47.534919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.240944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:47.53496Z","caller":"traceutil/trace.go:171","msg":"trace[778113109] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:27478; }","duration":"130.347556ms","start":"2026-09-01T08:12:47.404603Z","end":"2026-09-01T08:12:47.534951Z","steps":["trace[778113109] 'agreement among raft nodes before linearized reading' (duration: 130.250154ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:47.851527Z","caller":"traceutil/trace.go:171","msg":"trace[2051759422] linearizableReadLoop","detail":"{readStateIndex:28384; appliedIndex:28383; }","duration":"114.701948ms","start":"2026-09-01T08:12:47.736796Z","end":"2026-09-01T08:12:47.851498Z","steps":["trace[2051759422] 'read index received' (duration: 114.484773ms)","trace[2051759422] 'applied index is now lower than readState.Index' (duration: 216.165µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:47.851566Z","caller":"traceutil/trace.go:171","msg":"trace[524743592] transaction","detail":"{read_only:false; response_revision:27479; number_of_response:1; }","duration":"123.813438ms","start":"2026-09-01T08:12:47.727727Z","end":"2026-09-01T08:12:47.851541Z","steps":["trace[524743592] 'process raft request' (duration: 123.583512ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:47.851698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.878242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T08:12:47.851737Z","caller":"traceutil/trace.go:171","msg":"trace[1521705240] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:27479; }","duration":"114.941524ms","start":"2026-09-01T08:12:47.736781Z","end":"2026-09-01T08:12:47.851722Z","steps":["trace[1521705240] 'agreement among raft nodes before linearized reading' (duration: 114.844831ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:48.002157Z","caller":"traceutil/trace.go:171","msg":"trace[619284699] transaction","detail":"{read_only:false; response_revision:27480; number_of_response:1; }","duration":"249.940406ms","start":"2026-09-01T08:12:47.752191Z","end":"2026-09-01T08:12:48.002131Z","steps":["trace[619284699] 'process raft request' (duration: 197.430773ms)","trace[619284699] 'compare' (duration: 52.066343ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:48.00236Z","caller":"traceutil/trace.go:171","msg":"trace[190587323] linearizableReadLoop","detail":"{readStateIndex:28385; appliedIndex:28384; }","duration":"150.730863ms","start":"2026-09-01T08:12:47.851599Z","end":"2026-09-01T08:12:48.00233Z","steps":["trace[190587323] 'read index received' (duration: 98.022905ms)","trace[190587323] 'applied index is now lower than readState.Index' (duration: 52.705298ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T08:12:48.002487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.955499ms","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-09-01T08:12:48.00254Z","caller":"traceutil/trace.go:171","msg":"trace[459214459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27481; }","duration":"230.048281ms","start":"2026-09-01T08:12:47.772479Z","end":"2026-09-01T08:12:48.002527Z","steps":["trace[459214459] 'agreement among raft nodes before linearized reading' (duration: 229.860556ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:48.002584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.972352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"warn","ts":"2026-09-01T08:12:48.002603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.979443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T08:12:48.002585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.651122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:48.002627Z","caller":"traceutil/trace.go:171","msg":"trace[160374904] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:27481; }","duration":"252.054895ms","start":"2026-09-01T08:12:47.750558Z","end":"2026-09-01T08:12:48.002613Z","steps":["trace[160374904] 'agreement among raft nodes before linearized reading' (duration: 251.86836ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:48.002644Z","caller":"traceutil/trace.go:171","msg":"trace[584849666] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:27481; }","duration":"235.060445ms","start":"2026-09-01T08:12:47.767571Z","end":"2026-09-01T08:12:48.002631Z","steps":["trace[584849666] 'agreement among raft nodes before linearized reading' (duration: 234.984913ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:48.002671Z","caller":"traceutil/trace.go:171","msg":"trace[492474311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27481; }","duration":"212.784714ms","start":"2026-09-01T08:12:47.789868Z","end":"2026-09-01T08:12:48.002653Z","steps":["trace[492474311] 'agreement among raft nodes before linearized reading' (duration: 212.620081ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:49.72088Z","caller":"traceutil/trace.go:171","msg":"trace[1713978177] linearizableReadLoop","detail":"{readStateIndex:28398; appliedIndex:28397; }","duration":"156.124157ms","start":"2026-09-01T08:12:49.564728Z","end":"2026-09-01T08:12:49.720853Z","steps":["trace[1713978177] 'read index received' (duration: 108.25649ms)","trace[1713978177] 'applied index is now lower than readState.Index' (duration: 47.866467ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:49.720908Z","caller":"traceutil/trace.go:171","msg":"trace[641940127] transaction","detail":"{read_only:false; response_revision:27493; number_of_response:1; }","duration":"178.560591ms","start":"2026-09-01T08:12:49.542307Z","end":"2026-09-01T08:12:49.720867Z","steps":["trace[641940127] 'process raft request' (duration: 130.803387ms)","trace[641940127] 'compare' (duration: 47.604301ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T08:12:49.721011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.214231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T08:12:49.721018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.021107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:49.721055Z","caller":"traceutil/trace.go:171","msg":"trace[890095877] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:27493; }","duration":"181.311293ms","start":"2026-09-01T08:12:49.53973Z","end":"2026-09-01T08:12:49.721041Z","steps":["trace[890095877] 'agreement among raft nodes before linearized reading' (duration: 181.222231ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:49.721251Z","caller":"traceutil/trace.go:171","msg":"trace[1654860585] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:27493; }","duration":"181.269093ms","start":"2026-09-01T08:12:49.539962Z","end":"2026-09-01T08:12:49.721231Z","steps":["trace[1654860585] 'agreement among raft nodes before linearized reading' (duration: 181.004297ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:49.721343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.492168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:49.721382Z","caller":"traceutil/trace.go:171","msg":"trace[809445112] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:27493; }","duration":"181.547579ms","start":"2026-09-01T08:12:49.539825Z","end":"2026-09-01T08:12:49.721373Z","steps":["trace[809445112] 'agreement among raft nodes before linearized reading' (duration: 181.488567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:49.721533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.239172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T08:12:49.721582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.061099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-09-01T08:12:49.721615Z","caller":"traceutil/trace.go:171","msg":"trace[76096658] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:27493; }","duration":"181.09103ms","start":"2026-09-01T08:12:49.540514Z","end":"2026-09-01T08:12:49.721605Z","steps":["trace[76096658] 'agreement among raft nodes before linearized reading' (duration: 180.966136ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:49.721611Z","caller":"traceutil/trace.go:171","msg":"trace[150866707] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:27493; }","duration":"181.328444ms","start":"2026-09-01T08:12:49.540261Z","end":"2026-09-01T08:12:49.721589Z","steps":["trace[150866707] 'agreement among raft nodes before linearized reading' (duration: 181.209681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:49.72151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.694932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:49.721733Z","caller":"traceutil/trace.go:171","msg":"trace[1234715152] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:27493; }","duration":"181.998849ms","start":"2026-09-01T08:12:49.539725Z","end":"2026-09-01T08:12:49.721724Z","steps":["trace[1234715152] 'agreement among raft nodes before linearized reading' (duration: 181.670421ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:50.918304Z","caller":"traceutil/trace.go:171","msg":"trace[1147116021] transaction","detail":"{read_only:false; response_revision:27501; number_of_response:1; }","duration":"128.423512ms","start":"2026-09-01T08:12:50.789838Z","end":"2026-09-01T08:12:50.918262Z","steps":["trace[1147116021] 'process raft request' (duration: 126.423997ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:51.212667Z","caller":"traceutil/trace.go:171","msg":"trace[2015662189] transaction","detail":"{read_only:false; response_revision:27504; number_of_response:1; }","duration":"121.856442ms","start":"2026-09-01T08:12:51.09078Z","end":"2026-09-01T08:12:51.212636Z","steps":["trace[2015662189] 'process raft request' (duration: 121.619137ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:52.016357Z","caller":"traceutil/trace.go:171","msg":"trace[1541222090] transaction","detail":"{read_only:false; response_revision:27509; number_of_response:1; }","duration":"169.347998ms","start":"2026-09-01T08:12:51.846976Z","end":"2026-09-01T08:12:52.016324Z","steps":["trace[1541222090] 'process raft request' (duration: 169.157704ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:52.036661Z","caller":"traceutil/trace.go:171","msg":"trace[1690325987] transaction","detail":"{read_only:false; response_revision:27510; number_of_response:1; }","duration":"150.802345ms","start":"2026-09-01T08:12:51.885842Z","end":"2026-09-01T08:12:52.036644Z","steps":["trace[1690325987] 'process raft request' (duration: 150.716823ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:52.901795Z","caller":"traceutil/trace.go:171","msg":"trace[1787275577] transaction","detail":"{read_only:false; response_revision:27515; number_of_response:1; }","duration":"109.681182ms","start":"2026-09-01T08:12:52.792074Z","end":"2026-09-01T08:12:52.901755Z","steps":["trace[1787275577] 'process raft request' (duration: 97.37934ms)","trace[1787275577] 'compare' (duration: 11.984485ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:53.354869Z","caller":"traceutil/trace.go:171","msg":"trace[2053257869] transaction","detail":"{read_only:false; response_revision:27519; number_of_response:1; }","duration":"115.338161ms","start":"2026-09-01T08:12:53.239507Z","end":"2026-09-01T08:12:53.354845Z","steps":["trace[2053257869] 'process raft request' (duration: 115.185118ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:53.694365Z","caller":"traceutil/trace.go:171","msg":"trace[892487288] linearizableReadLoop","detail":"{readStateIndex:28426; appliedIndex:28424; }","duration":"359.301742ms","start":"2026-09-01T08:12:53.335036Z","end":"2026-09-01T08:12:53.694337Z","steps":["trace[892487288] 'read index received' (duration: 19.65958ms)","trace[892487288] 'applied index is now lower than readState.Index' (duration: 339.637932ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:53.694439Z","caller":"traceutil/trace.go:171","msg":"trace[283387933] transaction","detail":"{read_only:false; response_revision:27520; number_of_response:1; }","duration":"409.930171ms","start":"2026-09-01T08:12:53.284469Z","end":"2026-09-01T08:12:53.694399Z","steps":["trace[283387933] 'process raft request' (duration: 409.668526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:53.694545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.472705ms","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-09-01T08:12:53.694603Z","caller":"traceutil/trace.go:171","msg":"trace[1177282232] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27520; }","duration":"359.603199ms","start":"2026-09-01T08:12:53.334983Z","end":"2026-09-01T08:12:53.694586Z","steps":["trace[1177282232] 'agreement among raft nodes before linearized reading' (duration: 359.484796ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:53.6946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T08:12:53.284443Z","time spent":"410.063355ms","remote":"127.0.0.1:55942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T08:12:53.694655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T08:12:53.334956Z","time spent":"359.683961ms","remote":"127.0.0.1:60922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-01T08:12:53.694672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.298041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:53.694722Z","caller":"traceutil/trace.go:171","msg":"trace[1679460934] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:27520; }","duration":"122.372893ms","start":"2026-09-01T08:12:53.572335Z","end":"2026-09-01T08:12:53.694708Z","steps":["trace[1679460934] 'agreement among raft nodes before linearized reading' (duration: 122.293711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:53.694894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.047422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:53.695058Z","caller":"traceutil/trace.go:171","msg":"trace[846097153] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:27520; }","duration":"179.256866ms","start":"2026-09-01T08:12:53.515779Z","end":"2026-09-01T08:12:53.695036Z","steps":["trace[846097153] 'agreement among raft nodes before linearized reading' (duration: 178.791066ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:54.754373Z","caller":"traceutil/trace.go:171","msg":"trace[1976171698] linearizableReadLoop","detail":"{readStateIndex:28432; appliedIndex:28431; }","duration":"134.541782ms","start":"2026-09-01T08:12:54.619805Z","end":"2026-09-01T08:12:54.754347Z","steps":["trace[1976171698] 'read index received' (duration: 134.43754ms)","trace[1976171698] 'applied index is now lower than readState.Index' (duration: 103.192µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:54.754429Z","caller":"traceutil/trace.go:171","msg":"trace[568588698] transaction","detail":"{read_only:false; response_revision:27526; number_of_response:1; }","duration":"168.815958ms","start":"2026-09-01T08:12:54.585571Z","end":"2026-09-01T08:12:54.754387Z","steps":["trace[568588698] 'process raft request' (duration: 168.591073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T08:12:54.754539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.705836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:54.754615Z","caller":"traceutil/trace.go:171","msg":"trace[1316310143] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:27526; }","duration":"134.847589ms","start":"2026-09-01T08:12:54.619746Z","end":"2026-09-01T08:12:54.754593Z","steps":["trace[1316310143] 'agreement among raft nodes before linearized reading' (duration: 134.692965ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:54.852308Z","caller":"traceutil/trace.go:171","msg":"trace[293922987] transaction","detail":"{read_only:false; response_revision:27527; number_of_response:1; }","duration":"119.094978ms","start":"2026-09-01T08:12:54.733182Z","end":"2026-09-01T08:12:54.852277Z","steps":["trace[293922987] 'process raft request' (duration: 69.343238ms)","trace[293922987] 'compare' (duration: 49.410432ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:55.023564Z","caller":"traceutil/trace.go:171","msg":"trace[1129801338] transaction","detail":"{read_only:false; response_revision:27528; number_of_response:1; }","duration":"116.732734ms","start":"2026-09-01T08:12:54.906801Z","end":"2026-09-01T08:12:55.023534Z","steps":["trace[1129801338] 'process raft request' (duration: 55.86297ms)","trace[1129801338] 'compare' (duration: 60.736481ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:55.185595Z","caller":"traceutil/trace.go:171","msg":"trace[545665965] transaction","detail":"{read_only:false; response_revision:27531; number_of_response:1; }","duration":"143.538589ms","start":"2026-09-01T08:12:55.042021Z","end":"2026-09-01T08:12:55.185559Z","steps":["trace[545665965] 'process raft request' (duration: 104.489545ms)","trace[545665965] 'compare' (duration: 38.619715ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T08:12:55.210786Z","caller":"traceutil/trace.go:171","msg":"trace[1766083279] transaction","detail":"{read_only:false; response_revision:27532; number_of_response:1; }","duration":"128.334841ms","start":"2026-09-01T08:12:55.082421Z","end":"2026-09-01T08:12:55.210756Z","steps":["trace[1766083279] 'process raft request' (duration: 128.132716ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:12:56.925249Z","caller":"traceutil/trace.go:171","msg":"trace[27179156] linearizableReadLoop","detail":"{readStateIndex:28449; appliedIndex:28447; }","duration":"140.414007ms","start":"2026-09-01T08:12:56.784786Z","end":"2026-09-01T08:12:56.9252Z","steps":["trace[27179156] 'read index received' (duration: 50.784464ms)","trace[27179156] 'applied index is now lower than readState.Index' (duration: 89.627773ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T08:12:56.925385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.590991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T08:12:56.925423Z","caller":"traceutil/trace.go:171","msg":"trace[2014073566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27542; }","duration":"140.705855ms","start":"2026-09-01T08:12:56.784704Z","end":"2026-09-01T08:12:56.92541Z","steps":["trace[2014073566] 'agreement among raft nodes before linearized reading' (duration: 140.619263ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T08:16:18.656043Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"299bf3744060cbd","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T08:16:18.664496Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-01T08:16:18.664684Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-01T08:16:47.816676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27043} {"level":"info","ts":"2026-09-01T08:16:47.951753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27043,"took":"127.79132ms","hash":2215553189,"current-db-size-bytes":56672256,"current-db-size":"57 MB","current-db-size-in-use-bytes":29564928,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T08:16:47.951839Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2215553189,"revision":27043,"compact-revision":24529}